{"level":"warn","ts":"2026-04-12T20:49:10.384656Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-12T20:49:10.384747Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210: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.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210: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-04-12T20:49:10.384811Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-12T20:49:10.384827Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-04-12T20:49:10.384881Z","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-04-12T20:49:10.385544Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-04-12T20:49:10.385697Z","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.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210: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-04-12T20:49:10.389592Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.607759ms"} {"level":"info","ts":"2026-04-12T20:49:10.396684Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-04-12T20:49:10.396748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-04-12T20:49:10.396845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-04-12T20:49:10.396869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-12T20:49:10.396882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-04-12T20:49:10.396951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-04-12T20:49:10.404296Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-12T20:49:10.405783Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-12T20:49:10.408229Z","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-04-12T20:49:10.412025Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-12T20:49:10.412171Z","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-04-12T20:49:10.412275Z","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-04-12T20:49:10.412289Z","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-04-12T20:49:10.412608Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-12T20:49:10.415815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-04-12T20:49:10.415972Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-04-12T20:49:10.419633Z","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-04-12T20:49:10.419687Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-04-12T20:49:10.419713Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-04-12T20:49:10.419998Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-12T20:49:10.420045Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-12T20:49:10.503042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-04-12T20:49:10.50308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-12T20:49:10.503107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-04-12T20:49:10.503125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-04-12T20:49:10.503134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-04-12T20:49:10.503148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-04-12T20:49:10.503164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-04-12T20:49:10.505401Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-04-12T20:49:10.505488Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T20:49:10.505699Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T20:49:10.505799Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-12T20:49:10.505837Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-12T20:49:10.505716Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:49:10.50686Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:49:10.506947Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:49:10.506974Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:49:10.508668Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-04-12T20:49:10.509429Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-12T20:49:39.084833Z","caller":"traceutil/trace.go:171","msg":"trace[194463166] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"237.057705ms","start":"2026-04-12T20:49:38.847752Z","end":"2026-04-12T20:49:39.08481Z","steps":["trace[194463166] 'process raft request' (duration: 230.841335ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:39.085172Z","caller":"traceutil/trace.go:171","msg":"trace[2141641059] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"223.503398ms","start":"2026-04-12T20:49:38.861625Z","end":"2026-04-12T20:49:39.085129Z","steps":["trace[2141641059] 'process raft request' (duration: 223.338184ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:39.085233Z","caller":"traceutil/trace.go:171","msg":"trace[352482096] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"221.697004ms","start":"2026-04-12T20:49:38.863524Z","end":"2026-04-12T20:49:39.085221Z","steps":["trace[352482096] 'process raft request' (duration: 221.52295ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:39.085433Z","caller":"traceutil/trace.go:171","msg":"trace[1146846870] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"213.429205ms","start":"2026-04-12T20:49:38.871993Z","end":"2026-04-12T20:49:39.085423Z","steps":["trace[1146846870] 'process raft request' (duration: 213.120717ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:39.085528Z","caller":"traceutil/trace.go:171","msg":"trace[1274727066] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"205.803132ms","start":"2026-04-12T20:49:38.879635Z","end":"2026-04-12T20:49:39.085438Z","steps":["trace[1274727066] 'process raft request' (duration: 205.558926ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:39.096295Z","caller":"traceutil/trace.go:171","msg":"trace[807179561] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"135.586959ms","start":"2026-04-12T20:49:38.960662Z","end":"2026-04-12T20:49:39.096249Z","steps":["trace[807179561] 'process raft request' (duration: 132.281778ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:40.48051Z","caller":"traceutil/trace.go:171","msg":"trace[1973744672] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"124.499052ms","start":"2026-04-12T20:49:40.355983Z","end":"2026-04-12T20:49:40.480482Z","steps":["trace[1973744672] 'read index received' (duration: 124.095982ms)","trace[1973744672] 'applied index is now lower than readState.Index' (duration: 401.9µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:49:40.480646Z","caller":"traceutil/trace.go:171","msg":"trace[540659134] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"126.805896ms","start":"2026-04-12T20:49:40.353769Z","end":"2026-04-12T20:49:40.480574Z","steps":["trace[540659134] 'process raft request' (duration: 126.437508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:40.481364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.352762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570105"} {"level":"info","ts":"2026-04-12T20:49:40.481477Z","caller":"traceutil/trace.go:171","msg":"trace[1349495839] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:583; }","duration":"125.486995ms","start":"2026-04-12T20:49:40.355956Z","end":"2026-04-12T20:49:40.481443Z","steps":["trace[1349495839] 'agreement among raft nodes before linearized reading' (duration: 124.690216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:40.482954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.745171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:49:40.483028Z","caller":"traceutil/trace.go:171","msg":"trace[1054484264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"115.824353ms","start":"2026-04-12T20:49:40.367167Z","end":"2026-04-12T20:49:40.482992Z","steps":["trace[1054484264] 'agreement among raft nodes before linearized reading' (duration: 115.687489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:40.483301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.511863ms","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-04-12T20:49:40.483346Z","caller":"traceutil/trace.go:171","msg":"trace[1434904011] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"107.557673ms","start":"2026-04-12T20:49:40.375766Z","end":"2026-04-12T20:49:40.483324Z","steps":["trace[1434904011] 'agreement among raft nodes before linearized reading' (duration: 107.483472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:41.145479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.797404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:49:41.145557Z","caller":"traceutil/trace.go:171","msg":"trace[1272564331] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"581.694623ms","start":"2026-04-12T20:49:40.563849Z","end":"2026-04-12T20:49:41.145544Z","steps":["trace[1272564331] 'read index received' (duration: 282.134331ms)","trace[1272564331] 'applied index is now lower than readState.Index' (duration: 299.559382ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:49:41.145639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.787965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-12T20:49:41.145657Z","caller":"traceutil/trace.go:171","msg":"trace[305593080] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:584; }","duration":"581.833906ms","start":"2026-04-12T20:49:40.563817Z","end":"2026-04-12T20:49:41.145651Z","steps":["trace[305593080] 'agreement among raft nodes before linearized reading' (duration: 581.788915ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:41.145625Z","caller":"traceutil/trace.go:171","msg":"trace[1843041678] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"654.638811ms","start":"2026-04-12T20:49:40.490946Z","end":"2026-04-12T20:49:41.145585Z","steps":["trace[1843041678] 'process raft request' (duration: 355.090859ms)","trace[1843041678] 'compare' (duration: 298.671551ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:49:41.145682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:40.563796Z","time spent":"581.880957ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-12T20:49:41.145815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:40.490935Z","time spent":"654.780525ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:49:41.145832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.27751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-04-12T20:49:41.145898Z","caller":"traceutil/trace.go:171","msg":"trace[207345493] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:584; }","duration":"556.392882ms","start":"2026-04-12T20:49:40.589482Z","end":"2026-04-12T20:49:41.145875Z","steps":["trace[207345493] 'agreement among raft nodes before linearized reading' (duration: 556.165707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:41.145934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:40.589461Z","time spent":"556.466715ms","remote":"127.0.0.1:54306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":15711,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-04-12T20:49:45.238258Z","caller":"traceutil/trace.go:171","msg":"trace[774804066] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"157.580538ms","start":"2026-04-12T20:49:45.080647Z","end":"2026-04-12T20:49:45.238228Z","steps":["trace[774804066] 'process raft request' (duration: 156.716718ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:45.348665Z","caller":"traceutil/trace.go:171","msg":"trace[788918901] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"106.19658ms","start":"2026-04-12T20:49:45.242432Z","end":"2026-04-12T20:49:45.348628Z","steps":["trace[788918901] 'process raft request' (duration: 92.334506ms)","trace[788918901] 'compare' (duration: 13.620618ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:49:45.348681Z","caller":"traceutil/trace.go:171","msg":"trace[1899131238] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"104.396637ms","start":"2026-04-12T20:49:45.244256Z","end":"2026-04-12T20:49:45.348652Z","steps":["trace[1899131238] 'process raft request' (duration: 104.253434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:45.815529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.316858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-brrx4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-12T20:49:45.815602Z","caller":"traceutil/trace.go:171","msg":"trace[170392742] range","detail":"{range_begin:/registry/pods/kube-system/cilium-brrx4; range_end:; response_count:1; response_revision:613; }","duration":"297.442871ms","start":"2026-04-12T20:49:45.518141Z","end":"2026-04-12T20:49:45.815584Z","steps":["trace[170392742] 'range keys from in-memory index tree' (duration: 297.124914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:46.875244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.525081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:49:46.875372Z","caller":"traceutil/trace.go:171","msg":"trace[93474250] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"1.042940843s","start":"2026-04-12T20:49:45.832391Z","end":"2026-04-12T20:49:46.875332Z","steps":["trace[93474250] 'process raft request' (duration: 397.251787ms)","trace[93474250] 'compare' (duration: 645.421949ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:49:46.87543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:45.832327Z","time spent":"1.043077096s","remote":"127.0.0.1:54448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:49:46.924349Z","caller":"traceutil/trace.go:171","msg":"trace[1590187350] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:634; }","duration":"766.18784ms","start":"2026-04-12T20:49:46.158141Z","end":"2026-04-12T20:49:46.924329Z","steps":["trace[1590187350] 'read index received' (duration: 71.515493ms)","trace[1590187350] 'applied index is now lower than readState.Index' (duration: 694.671357ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:49:46.924588Z","caller":"traceutil/trace.go:171","msg":"trace[1183606439] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"981.217264ms","start":"2026-04-12T20:49:45.943253Z","end":"2026-04-12T20:49:46.92447Z","steps":["trace[1183606439] 'process raft request' (duration: 980.907317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:46.924813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:45.943224Z","time spent":"981.4924ms","remote":"127.0.0.1:54442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:49:46.924824Z","caller":"traceutil/trace.go:171","msg":"trace[611942490] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"1.032326186s","start":"2026-04-12T20:49:45.892456Z","end":"2026-04-12T20:49:46.924782Z","steps":["trace[611942490] 'process raft request' (duration: 1.031602089s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:46.925187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:45.892436Z","time spent":"1.032687295s","remote":"127.0.0.1:54818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:49:46.925513Z","caller":"traceutil/trace.go:171","msg":"trace[1359599490] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"836.323711ms","start":"2026-04-12T20:49:46.088977Z","end":"2026-04-12T20:49:46.9253Z","steps":["trace[1359599490] 'process raft request' (duration: 835.317997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:46.925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.852716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-12T20:49:46.925613Z","caller":"traceutil/trace.go:171","msg":"trace[1087537413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"767.494152ms","start":"2026-04-12T20:49:46.158092Z","end":"2026-04-12T20:49:46.925586Z","steps":["trace[1087537413] 'agreement among raft nodes before linearized reading' (duration: 766.360645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:46.925711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:46.158079Z","time spent":"767.614485ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-12T20:49:46.925855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:46.088946Z","time spent":"836.639569ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:49:46.926093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.645696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:49:46.927037Z","caller":"traceutil/trace.go:171","msg":"trace[1932861239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"555.938012ms","start":"2026-04-12T20:49:46.370264Z","end":"2026-04-12T20:49:46.926202Z","steps":["trace[1932861239] 'agreement among raft nodes before linearized reading' (duration: 555.256575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:46.927082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:46.370241Z","time spent":"556.829194ms","remote":"127.0.0.1:54244","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-04-12T20:49:48.806915Z","caller":"traceutil/trace.go:171","msg":"trace[1829982815] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"153.926191ms","start":"2026-04-12T20:49:48.652954Z","end":"2026-04-12T20:49:48.80688Z","steps":["trace[1829982815] 'process raft request' (duration: 153.664835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:59.607713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.984206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-12T20:49:59.60774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.069034ms","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-04-12T20:49:59.6078Z","caller":"traceutil/trace.go:171","msg":"trace[1794986977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:759; }","duration":"201.110459ms","start":"2026-04-12T20:49:59.406672Z","end":"2026-04-12T20:49:59.607782Z","steps":["trace[1794986977] 'range keys from in-memory index tree' (duration: 200.844072ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:59.607817Z","caller":"traceutil/trace.go:171","msg":"trace[540307171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:759; }","duration":"200.188637ms","start":"2026-04-12T20:49:59.407609Z","end":"2026-04-12T20:49:59.607797Z","steps":["trace[540307171] 'range keys from in-memory index tree' (duration: 199.894669ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:02.518924Z","caller":"traceutil/trace.go:171","msg":"trace[901040185] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"206.049858ms","start":"2026-04-12T20:50:02.31285Z","end":"2026-04-12T20:50:02.518899Z","steps":["trace[901040185] 'process raft request' (duration: 205.894664ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:35.925877Z","caller":"traceutil/trace.go:171","msg":"trace[222816602] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"117.554497ms","start":"2026-04-12T20:50:35.808271Z","end":"2026-04-12T20:50:35.925826Z","steps":["trace[222816602] 'process raft request' (duration: 117.398413ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:36.448822Z","caller":"traceutil/trace.go:171","msg":"trace[170410980] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"639.051869ms","start":"2026-04-12T20:50:35.809744Z","end":"2026-04-12T20:50:36.448796Z","steps":["trace[170410980] 'process raft request' (duration: 636.918919ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:36.448867Z","caller":"traceutil/trace.go:171","msg":"trace[1329941253] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"346.680292ms","start":"2026-04-12T20:50:36.102171Z","end":"2026-04-12T20:50:36.448851Z","steps":["trace[1329941253] 'process raft request' (duration: 346.402106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:36.448992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:36.102146Z","time spent":"346.761104ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:50:36.449152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:35.809719Z","time spent":"639.178103ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:50:36.775576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.98146ms","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-04-12T20:50:36.77571Z","caller":"traceutil/trace.go:171","msg":"trace[1135020085] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:995; }","duration":"204.43111ms","start":"2026-04-12T20:50:36.571259Z","end":"2026-04-12T20:50:36.77569Z","steps":["trace[1135020085] 'count revisions from in-memory index tree' (duration: 203.773265ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:57.433304Z","caller":"traceutil/trace.go:171","msg":"trace[784129932] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"130.492638ms","start":"2026-04-12T20:50:57.302779Z","end":"2026-04-12T20:50:57.433272Z","steps":["trace[784129932] 'process raft request' (duration: 130.278313ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:58.003173Z","caller":"traceutil/trace.go:171","msg":"trace[648185028] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1100; }","duration":"278.174387ms","start":"2026-04-12T20:50:57.724949Z","end":"2026-04-12T20:50:58.003123Z","steps":["trace[648185028] 'read index received' (duration: 277.674195ms)","trace[648185028] 'applied index is now lower than readState.Index' (duration: 498.261µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:50:58.003226Z","caller":"traceutil/trace.go:171","msg":"trace[1124204747] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"296.433062ms","start":"2026-04-12T20:50:57.706764Z","end":"2026-04-12T20:50:58.003197Z","steps":["trace[1124204747] 'process raft request' (duration: 296.001792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:58.003383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.392332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T20:50:58.003419Z","caller":"traceutil/trace.go:171","msg":"trace[2126720843] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1064; }","duration":"278.509614ms","start":"2026-04-12T20:50:57.724901Z","end":"2026-04-12T20:50:58.00341Z","steps":["trace[2126720843] 'agreement among raft nodes before linearized reading' (duration: 278.386561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:59.217882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.970527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:50:59.218077Z","caller":"traceutil/trace.go:171","msg":"trace[1965327965] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"612.381669ms","start":"2026-04-12T20:50:58.605662Z","end":"2026-04-12T20:50:59.218044Z","steps":["trace[1965327965] 'process raft request' (duration: 360.169386ms)","trace[1965327965] 'compare' (duration: 251.748591ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:50:59.218369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:58.605647Z","time spent":"612.615524ms","remote":"127.0.0.1:54536","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-04-12T20:50:59.2185Z","caller":"traceutil/trace.go:171","msg":"trace[669897444] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1104; }","duration":"330.032984ms","start":"2026-04-12T20:50:58.888456Z","end":"2026-04-12T20:50:59.218489Z","steps":["trace[669897444] 'read index received' (duration: 77.281508ms)","trace[669897444] 'applied index is now lower than readState.Index' (duration: 252.750386ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:50:59.614192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.705487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-12T20:50:59.614307Z","caller":"traceutil/trace.go:171","msg":"trace[997213876] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1068; }","duration":"725.864021ms","start":"2026-04-12T20:50:58.888418Z","end":"2026-04-12T20:50:59.614282Z","steps":["trace[997213876] 'agreement among raft nodes before linearized reading' (duration: 330.123826ms)","trace[997213876] 'range keys from in-memory index tree' (duration: 395.459278ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:50:59.614355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:58.888398Z","time spent":"725.946582ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-12T20:50:59.614325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.266966ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-12T20:50:59.614443Z","caller":"traceutil/trace.go:171","msg":"trace[621178350] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1105; }","duration":"335.721257ms","start":"2026-04-12T20:50:59.278701Z","end":"2026-04-12T20:50:59.614423Z","steps":["trace[621178350] 'read index received' (duration: 79.751µs)","trace[621178350] 'applied index is now lower than readState.Index' (duration: 335.639986ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:50:59.614609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.901382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:50:59.614652Z","caller":"traceutil/trace.go:171","msg":"trace[1053995162] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1068; }","duration":"336.000854ms","start":"2026-04-12T20:50:59.278638Z","end":"2026-04-12T20:50:59.614638Z","steps":["trace[1053995162] 'agreement among raft nodes before linearized reading' (duration: 335.902112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:59.614688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:59.278612Z","time spent":"336.065425ms","remote":"127.0.0.1:50440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-12T20:50:59.614888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.625253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:50:59.614989Z","caller":"traceutil/trace.go:171","msg":"trace[1898746951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1068; }","duration":"246.762036ms","start":"2026-04-12T20:50:59.368204Z","end":"2026-04-12T20:50:59.614966Z","steps":["trace[1898746951] 'agreement among raft nodes before linearized reading' (duration: 246.605652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:59.615128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.574635ms","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-04-12T20:50:59.615218Z","caller":"traceutil/trace.go:171","msg":"trace[552486356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1068; }","duration":"177.709238ms","start":"2026-04-12T20:50:59.437493Z","end":"2026-04-12T20:50:59.615202Z","steps":["trace[552486356] 'agreement among raft nodes before linearized reading' (duration: 177.422061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:00.550481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.557778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:00.550571Z","caller":"traceutil/trace.go:171","msg":"trace[521859303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1072; }","duration":"181.722741ms","start":"2026-04-12T20:51:00.368828Z","end":"2026-04-12T20:51:00.550551Z","steps":["trace[521859303] 'range keys from in-memory index tree' (duration: 181.406494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:01.049376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.49635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:01.049531Z","caller":"traceutil/trace.go:171","msg":"trace[825579663] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1073; }","duration":"149.659814ms","start":"2026-04-12T20:51:00.899824Z","end":"2026-04-12T20:51:01.049483Z","steps":["trace[825579663] 'count revisions from in-memory index tree' (duration: 149.403829ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:12.287416Z","caller":"traceutil/trace.go:171","msg":"trace[1236470708] linearizableReadLoop","detail":"{readStateIndex:1202; appliedIndex:1201; }","duration":"138.083615ms","start":"2026-04-12T20:51:12.149314Z","end":"2026-04-12T20:51:12.287397Z","steps":["trace[1236470708] 'read index received' (duration: 137.889631ms)","trace[1236470708] 'applied index is now lower than readState.Index' (duration: 193.254µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:12.287578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.27058ms","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-04-12T20:51:12.287551Z","caller":"traceutil/trace.go:171","msg":"trace[1551087446] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"167.547502ms","start":"2026-04-12T20:51:12.119972Z","end":"2026-04-12T20:51:12.28752Z","steps":["trace[1551087446] 'process raft request' (duration: 167.276876ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:12.287602Z","caller":"traceutil/trace.go:171","msg":"trace[785756309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1161; }","duration":"138.353632ms","start":"2026-04-12T20:51:12.149242Z","end":"2026-04-12T20:51:12.287595Z","steps":["trace[785756309] 'agreement among raft nodes before linearized reading' (duration: 138.225079ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:14.494208Z","caller":"traceutil/trace.go:171","msg":"trace[379767625] linearizableReadLoop","detail":"{readStateIndex:1208; appliedIndex:1207; }","duration":"198.759458ms","start":"2026-04-12T20:51:14.295416Z","end":"2026-04-12T20:51:14.494175Z","steps":["trace[379767625] 'read index received' (duration: 198.555543ms)","trace[379767625] 'applied index is now lower than readState.Index' (duration: 202.105µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:14.494255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:13.86551Z","time spent":"628.74184ms","remote":"127.0.0.1:54284","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-12T20:51:14.494394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.972222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-12T20:51:14.494421Z","caller":"traceutil/trace.go:171","msg":"trace[255087987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1166; }","duration":"199.080325ms","start":"2026-04-12T20:51:14.295334Z","end":"2026-04-12T20:51:14.494415Z","steps":["trace[255087987] 'agreement among raft nodes before linearized reading' (duration: 198.957673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:14.494538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.218102ms","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-04-12T20:51:14.494643Z","caller":"traceutil/trace.go:171","msg":"trace[1163151523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1166; }","duration":"194.382856ms","start":"2026-04-12T20:51:14.300242Z","end":"2026-04-12T20:51:14.494625Z","steps":["trace[1163151523] 'agreement among raft nodes before linearized reading' (duration: 194.11313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:14.893872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.899269ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:51:14.894034Z","caller":"traceutil/trace.go:171","msg":"trace[297073845] linearizableReadLoop","detail":"{readStateIndex:1211; appliedIndex:1210; }","duration":"394.452773ms","start":"2026-04-12T20:51:14.499562Z","end":"2026-04-12T20:51:14.894015Z","steps":["trace[297073845] 'read index received' (duration: 31.249837ms)","trace[297073845] 'applied index is now lower than readState.Index' (duration: 363.201606ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:14.89412Z","caller":"traceutil/trace.go:171","msg":"trace[2062367309] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"394.847334ms","start":"2026-04-12T20:51:14.499242Z","end":"2026-04-12T20:51:14.894089Z","steps":["trace[2062367309] 'process raft request' (duration: 31.613187ms)","trace[2062367309] 'compare' (duration: 362.718195ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:14.89435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:14.49923Z","time spent":"394.956686ms","remote":"127.0.0.1:54536","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-04-12T20:51:14.89433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.7572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-12T20:51:14.894405Z","caller":"traceutil/trace.go:171","msg":"trace[1516209301] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1169; }","duration":"394.866783ms","start":"2026-04-12T20:51:14.499527Z","end":"2026-04-12T20:51:14.894394Z","steps":["trace[1516209301] 'agreement among raft nodes before linearized reading' (duration: 394.571086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:14.894662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:14.499513Z","time spent":"395.133089ms","remote":"127.0.0.1:54284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-04-12T20:51:14.901732Z","caller":"traceutil/trace.go:171","msg":"trace[872419289] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"181.022855ms","start":"2026-04-12T20:51:14.720437Z","end":"2026-04-12T20:51:14.90146Z","steps":["trace[872419289] 'process raft request' (duration: 180.457602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:32.158499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.762774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:32.158581Z","caller":"traceutil/trace.go:171","msg":"trace[1103064080] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1246; }","duration":"380.884797ms","start":"2026-04-12T20:51:31.777675Z","end":"2026-04-12T20:51:32.15856Z","steps":["trace[1103064080] 'range keys from in-memory index tree' (duration: 380.684262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:32.158619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:31.777656Z","time spent":"380.955028ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-12T20:51:32.158851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.673383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-12T20:51:32.158904Z","caller":"traceutil/trace.go:171","msg":"trace[1583366601] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1246; }","duration":"329.776035ms","start":"2026-04-12T20:51:31.829114Z","end":"2026-04-12T20:51:32.158891Z","steps":["trace[1583366601] 'range keys from in-memory index tree' (duration: 329.535719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:32.158932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:31.829093Z","time spent":"329.834186ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-12T20:51:32.165302Z","caller":"traceutil/trace.go:171","msg":"trace[1219111709] linearizableReadLoop","detail":"{readStateIndex:1293; appliedIndex:1292; }","duration":"240.121257ms","start":"2026-04-12T20:51:31.925165Z","end":"2026-04-12T20:51:32.165286Z","steps":["trace[1219111709] 'read index received' (duration: 239.954413ms)","trace[1219111709] 'applied index is now lower than readState.Index' (duration: 165.974µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:32.16539Z","caller":"traceutil/trace.go:171","msg":"trace[925396941] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"301.10201ms","start":"2026-04-12T20:51:31.864276Z","end":"2026-04-12T20:51:32.165378Z","steps":["trace[925396941] 'process raft request' (duration: 300.905696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:32.165473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:31.864252Z","time spent":"301.168192ms","remote":"127.0.0.1:54332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:51:32.165703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.536707ms","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-04-12T20:51:32.165734Z","caller":"traceutil/trace.go:171","msg":"trace[675995953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1247; }","duration":"240.602288ms","start":"2026-04-12T20:51:31.925124Z","end":"2026-04-12T20:51:32.165726Z","steps":["trace[675995953] 'agreement among raft nodes before linearized reading' (duration: 240.482965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:32.165735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.52913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:32.165796Z","caller":"traceutil/trace.go:171","msg":"trace[401823650] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1247; }","duration":"130.652483ms","start":"2026-04-12T20:51:32.035128Z","end":"2026-04-12T20:51:32.165781Z","steps":["trace[401823650] 'agreement among raft nodes before linearized reading' (duration: 130.487659ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:32.661075Z","caller":"traceutil/trace.go:171","msg":"trace[213249888] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1294; }","duration":"472.839327ms","start":"2026-04-12T20:51:32.188212Z","end":"2026-04-12T20:51:32.661051Z","steps":["trace[213249888] 'read index received' (duration: 472.331385ms)","trace[213249888] 'applied index is now lower than readState.Index' (duration: 506.782µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:32.661077Z","caller":"traceutil/trace.go:171","msg":"trace[950677457] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"485.758489ms","start":"2026-04-12T20:51:32.175279Z","end":"2026-04-12T20:51:32.661037Z","steps":["trace[950677457] 'process raft request' (duration: 485.509353ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:32.661131Z","caller":"traceutil/trace.go:171","msg":"trace[1423708883] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"485.627636ms","start":"2026-04-12T20:51:32.175463Z","end":"2026-04-12T20:51:32.661091Z","steps":["trace[1423708883] 'process raft request' (duration: 485.469042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:32.661247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.012911ms","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-04-12T20:51:32.661276Z","caller":"traceutil/trace.go:171","msg":"trace[29026116] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1251; }","duration":"473.082643ms","start":"2026-04-12T20:51:32.188186Z","end":"2026-04-12T20:51:32.661268Z","steps":["trace[29026116] 'agreement among raft nodes before linearized reading' (duration: 472.94552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:32.661269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:32.175267Z","time spent":"485.890801ms","remote":"127.0.0.1:54332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:51:32.661307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:32.188176Z","time spent":"473.125123ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-12T20:51:32.66132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:32.175456Z","time spent":"485.770119ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:51:32.661542Z","caller":"traceutil/trace.go:171","msg":"trace[2118024375] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"485.645706ms","start":"2026-04-12T20:51:32.175889Z","end":"2026-04-12T20:51:32.661534Z","steps":["trace[2118024375] 'process raft request' (duration: 485.107334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:32.661701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:32.175879Z","time spent":"485.686056ms","remote":"127.0.0.1:54536","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-04-12T20:51:33.088252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.063917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:51:33.088445Z","caller":"traceutil/trace.go:171","msg":"trace[42511420] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1297; }","duration":"379.73164ms","start":"2026-04-12T20:51:32.708695Z","end":"2026-04-12T20:51:33.088427Z","steps":["trace[42511420] 'read index received' (duration: 218.431478ms)","trace[42511420] 'applied index is now lower than readState.Index' (duration: 161.298762ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:33.088646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.943914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-12T20:51:33.088684Z","caller":"traceutil/trace.go:171","msg":"trace[1830316244] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1253; }","duration":"380.064707ms","start":"2026-04-12T20:51:32.70861Z","end":"2026-04-12T20:51:33.088674Z","steps":["trace[1830316244] 'agreement among raft nodes before linearized reading' (duration: 379.888273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:33.088762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:32.708587Z","time spent":"380.16372ms","remote":"127.0.0.1:54536","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-04-12T20:51:33.088766Z","caller":"traceutil/trace.go:171","msg":"trace[1591033292] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"897.693023ms","start":"2026-04-12T20:51:32.191046Z","end":"2026-04-12T20:51:33.088739Z","steps":["trace[1591033292] 'process raft request' (duration: 736.052894ms)","trace[1591033292] 'compare' (duration: 160.932073ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:33.088895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:32.191036Z","time spent":"897.796776ms","remote":"127.0.0.1:54332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:51:33.088952Z","caller":"traceutil/trace.go:171","msg":"trace[1898087321] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"422.751109ms","start":"2026-04-12T20:51:32.66615Z","end":"2026-04-12T20:51:33.088901Z","steps":["trace[1898087321] 'process raft request' (duration: 422.205527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:33.089123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:32.666131Z","time spent":"422.908262ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:51:33.365543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.504411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:51:33.365614Z","caller":"traceutil/trace.go:171","msg":"trace[494740692] linearizableReadLoop","detail":"{readStateIndex:1301; appliedIndex:1300; }","duration":"201.771983ms","start":"2026-04-12T20:51:33.16383Z","end":"2026-04-12T20:51:33.365602Z","steps":["trace[494740692] 'read index received' (duration: 46.101158ms)","trace[494740692] 'applied index is now lower than readState.Index' (duration: 155.670065ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:33.365706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.887206ms","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-04-12T20:51:33.365719Z","caller":"traceutil/trace.go:171","msg":"trace[1467743616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1255; }","duration":"201.932537ms","start":"2026-04-12T20:51:33.163782Z","end":"2026-04-12T20:51:33.365714Z","steps":["trace[1467743616] 'agreement among raft nodes before linearized reading' (duration: 201.846755ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:33.365772Z","caller":"traceutil/trace.go:171","msg":"trace[283768205] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"235.476082ms","start":"2026-04-12T20:51:33.13027Z","end":"2026-04-12T20:51:33.365746Z","steps":["trace[283768205] 'process raft request' (duration: 79.709785ms)","trace[283768205] 'compare' (duration: 155.42945ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:33.826373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.858853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:51:33.826704Z","caller":"traceutil/trace.go:171","msg":"trace[1256576521] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1301; }","duration":"311.350601ms","start":"2026-04-12T20:51:33.51534Z","end":"2026-04-12T20:51:33.82669Z","steps":["trace[1256576521] 'read index received' (duration: 76.212µs)","trace[1256576521] 'applied index is now lower than readState.Index' (duration: 311.272619ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:33.826732Z","caller":"traceutil/trace.go:171","msg":"trace[394630773] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"452.731924ms","start":"2026-04-12T20:51:33.373967Z","end":"2026-04-12T20:51:33.826699Z","steps":["trace[394630773] 'process raft request' (duration: 112.472682ms)","trace[394630773] 'compare' (duration: 339.519755ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:33.826877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:33.373949Z","time spent":"452.846457ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:51:33.827072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.705198ms","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-04-12T20:51:33.827401Z","caller":"traceutil/trace.go:171","msg":"trace[583773979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1256; }","duration":"311.870662ms","start":"2026-04-12T20:51:33.515288Z","end":"2026-04-12T20:51:33.827158Z","steps":["trace[583773979] 'agreement among raft nodes before linearized reading' (duration: 311.498854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:33.827458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:33.515267Z","time spent":"312.179869ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-12T20:51:34.285851Z","caller":"traceutil/trace.go:171","msg":"trace[352350821] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1302; }","duration":"419.735871ms","start":"2026-04-12T20:51:33.86608Z","end":"2026-04-12T20:51:34.285816Z","steps":["trace[352350821] 'read index received' (duration: 419.331061ms)","trace[352350821] 'applied index is now lower than readState.Index' (duration: 403.16µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:34.28597Z","caller":"traceutil/trace.go:171","msg":"trace[1590368024] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"454.432883ms","start":"2026-04-12T20:51:33.83149Z","end":"2026-04-12T20:51:34.285922Z","steps":["trace[1590368024] 'process raft request' (duration: 454.088326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:34.286169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.059249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-12T20:51:34.286218Z","caller":"traceutil/trace.go:171","msg":"trace[579008239] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:1257; }","duration":"420.174041ms","start":"2026-04-12T20:51:33.866026Z","end":"2026-04-12T20:51:34.2862Z","steps":["trace[579008239] 'agreement among raft nodes before linearized reading' (duration: 419.951806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:34.286203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:33.83147Z","time spent":"454.603547ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:51:34.286273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:33.866002Z","time spent":"420.261413ms","remote":"127.0.0.1:54284","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.210\" "} {"level":"info","ts":"2026-04-12T20:51:34.715384Z","caller":"traceutil/trace.go:171","msg":"trace[1875998974] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"190.830067ms","start":"2026-04-12T20:51:34.52449Z","end":"2026-04-12T20:51:34.71532Z","steps":["trace[1875998974] 'process raft request' (duration: 105.767302ms)","trace[1875998974] 'compare' (duration: 84.82603ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:45.825724Z","caller":"traceutil/trace.go:171","msg":"trace[1555506989] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"314.545062ms","start":"2026-04-12T20:51:45.511132Z","end":"2026-04-12T20:51:45.825677Z","steps":["trace[1555506989] 'process raft request' (duration: 314.349898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:45.825915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:45.511103Z","time spent":"314.703456ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:51:46.604073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.501348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:51:46.604171Z","caller":"traceutil/trace.go:171","msg":"trace[1313923279] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1384; }","duration":"517.121155ms","start":"2026-04-12T20:51:46.087032Z","end":"2026-04-12T20:51:46.604153Z","steps":["trace[1313923279] 'read index received' (duration: 295.515424ms)","trace[1313923279] 'applied index is now lower than readState.Index' (duration: 221.604751ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:46.6046Z","caller":"traceutil/trace.go:171","msg":"trace[262224276] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"714.402076ms","start":"2026-04-12T20:51:45.890182Z","end":"2026-04-12T20:51:46.604584Z","steps":["trace[262224276] 'process raft request' (duration: 492.312845ms)","trace[262224276] 'compare' (duration: 221.409196ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:46.6048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:45.890156Z","time spent":"714.482778ms","remote":"127.0.0.1:54536","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-04-12T20:51:46.604881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.095294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-12T20:51:46.604978Z","caller":"traceutil/trace.go:171","msg":"trace[1291975340] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1336; }","duration":"215.227906ms","start":"2026-04-12T20:51:46.389726Z","end":"2026-04-12T20:51:46.604954Z","steps":["trace[1291975340] 'agreement among raft nodes before linearized reading' (duration: 214.989021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:46.605066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.975843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:46.60523Z","caller":"traceutil/trace.go:171","msg":"trace[819486409] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1336; }","duration":"518.24314ms","start":"2026-04-12T20:51:46.086965Z","end":"2026-04-12T20:51:46.605208Z","steps":["trace[819486409] 'agreement among raft nodes before linearized reading' (duration: 517.828291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:46.605355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:46.086938Z","time spent":"518.399243ms","remote":"127.0.0.1:50478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-12T20:51:46.604869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.004572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:46.605481Z","caller":"traceutil/trace.go:171","msg":"trace[166367750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1336; }","duration":"235.662846ms","start":"2026-04-12T20:51:46.369792Z","end":"2026-04-12T20:51:46.605455Z","steps":["trace[166367750] 'agreement among raft nodes before linearized reading' (duration: 234.982671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:46.605115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.03581ms","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-04-12T20:51:46.605676Z","caller":"traceutil/trace.go:171","msg":"trace[1432529067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1336; }","duration":"266.661794ms","start":"2026-04-12T20:51:46.338999Z","end":"2026-04-12T20:51:46.605661Z","steps":["trace[1432529067] 'agreement among raft nodes before linearized reading' (duration: 265.914377ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:55:05.163616Z","caller":"traceutil/trace.go:171","msg":"trace[775320796] transaction","detail":"{read_only:false; response_revision:2272; number_of_response:1; }","duration":"155.497058ms","start":"2026-04-12T20:55:05.008083Z","end":"2026-04-12T20:55:05.16358Z","steps":["trace[775320796] 'process raft request' (duration: 155.291923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:55:10.402235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.676848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:55:10.402388Z","caller":"traceutil/trace.go:171","msg":"trace[1360044947] transaction","detail":"{read_only:false; response_revision:2302; number_of_response:1; }","duration":"151.99514ms","start":"2026-04-12T20:55:10.250377Z","end":"2026-04-12T20:55:10.402372Z","steps":["trace[1360044947] 'process raft request' (duration: 42.089577ms)","trace[1360044947] 'compare' (duration: 109.513014ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:55:10.894589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.298622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:55:10.894747Z","caller":"traceutil/trace.go:171","msg":"trace[805596723] linearizableReadLoop","detail":"{readStateIndex:2394; appliedIndex:2393; }","duration":"342.560087ms","start":"2026-04-12T20:55:10.55217Z","end":"2026-04-12T20:55:10.89473Z","steps":["trace[805596723] 'read index received' (duration: 67.844336ms)","trace[805596723] 'applied index is now lower than readState.Index' (duration: 274.714261ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:55:10.894952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.779713ms","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-04-12T20:55:10.894979Z","caller":"traceutil/trace.go:171","msg":"trace[2032749412] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2303; }","duration":"342.846095ms","start":"2026-04-12T20:55:10.552123Z","end":"2026-04-12T20:55:10.894969Z","steps":["trace[2032749412] 'agreement among raft nodes before linearized reading' (duration: 342.705892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:55:10.894959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.324488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-12T20:55:10.895015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:55:10.552107Z","time spent":"342.892805ms","remote":"127.0.0.1:54536","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-04-12T20:55:10.895049Z","caller":"traceutil/trace.go:171","msg":"trace[1696631651] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2303; }","duration":"176.490191ms","start":"2026-04-12T20:55:10.718544Z","end":"2026-04-12T20:55:10.895034Z","steps":["trace[1696631651] 'agreement among raft nodes before linearized reading' (duration: 176.307727ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:55:10.895535Z","caller":"traceutil/trace.go:171","msg":"trace[1427732552] transaction","detail":"{read_only:false; response_revision:2303; number_of_response:1; }","duration":"486.715302ms","start":"2026-04-12T20:55:10.408058Z","end":"2026-04-12T20:55:10.894773Z","steps":["trace[1427732552] 'process raft request' (duration: 212.134494ms)","trace[1427732552] 'compare' (duration: 274.143449ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:55:10.895663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:55:10.408033Z","time spent":"487.54716ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}