{"level":"warn","ts":"2026-05-27T00:35:50.869816Z","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-27T00:35:50.870031Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.25: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.25:2380","--initial-cluster=instance=https://199.204.45.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.25: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-27T00:35:50.87017Z","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-27T00:35:50.870184Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-05-27T00:35:50.87024Z","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-27T00:35:50.87094Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"]} {"level":"info","ts":"2026-05-27T00:35:50.871081Z","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.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.25: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-27T00:35:50.875064Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.734668ms"} {"level":"info","ts":"2026-05-27T00:35:50.881536Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-05-27T00:35:50.881609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T00:35:50.881632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-05-27T00:35:50.881642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ba52c55cf4193752 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-27T00:35:50.881649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-05-27T00:35:50.881692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-05-27T00:35:50.887194Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T00:35:50.888301Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T00:35:50.889448Z","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-27T00:35:50.892583Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ba52c55cf4193752","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-27T00:35:50.896042Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ba52c55cf4193752","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-27T00:35:50.896125Z","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-27T00:35:50.896165Z","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-27T00:35:50.896172Z","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-27T00:35:50.896472Z","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-27T00:35:50.896579Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-05-27T00:35:50.896651Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-05-27T00:35:50.896695Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ba52c55cf4193752","initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-27T00:35:50.896806Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T00:35:50.896958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-05-27T00:35:50.897084Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","added-peer-id":"ba52c55cf4193752","added-peer-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-05-27T00:35:51.582682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T00:35:51.58276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T00:35:51.582794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-05-27T00:35:51.582814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-05-27T00:35:51.582825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-05-27T00:35:51.582845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-05-27T00:35:51.582859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-05-27T00:35:51.59046Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T00:35:51.610177Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T00:35:51.610204Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T00:35:51.610176Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ba52c55cf4193752","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.25:2379]}","request-path":"/0/members/ba52c55cf4193752/attributes","cluster-id":"4468ed52294bc271","publish-timeout":"7s"} {"level":"info","ts":"2026-05-27T00:35:51.610659Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T00:35:51.610794Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T00:35:51.614435Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-27T00:35:51.614544Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"info","ts":"2026-05-27T00:35:51.618935Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T00:35:51.61905Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T00:35:51.619075Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T00:36:12.308957Z","caller":"traceutil/trace.go:171","msg":"trace[33862580] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"153.631141ms","start":"2026-05-27T00:36:12.155302Z","end":"2026-05-27T00:36:12.308934Z","steps":["trace[33862580] 'process raft request' (duration: 153.498135ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:14.280387Z","caller":"traceutil/trace.go:171","msg":"trace[2084150034] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"108.43748ms","start":"2026-05-27T00:36:14.171919Z","end":"2026-05-27T00:36:14.280357Z","steps":["trace[2084150034] 'process raft request' (duration: 17.418918ms)","trace[2084150034] 'compare' (duration: 90.855095ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:36:14.282885Z","caller":"traceutil/trace.go:171","msg":"trace[590426768] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"108.65814ms","start":"2026-05-27T00:36:14.174205Z","end":"2026-05-27T00:36:14.282863Z","steps":["trace[590426768] 'process raft request' (duration: 108.469901ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:14.28303Z","caller":"traceutil/trace.go:171","msg":"trace[1557693891] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"108.646449ms","start":"2026-05-27T00:36:14.174344Z","end":"2026-05-27T00:36:14.28299Z","steps":["trace[1557693891] 'process raft request' (duration: 108.43968ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:14.716235Z","caller":"traceutil/trace.go:171","msg":"trace[995881076] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"147.051529ms","start":"2026-05-27T00:36:14.569137Z","end":"2026-05-27T00:36:14.716188Z","steps":["trace[995881076] 'process raft request' (duration: 99.789129ms)","trace[995881076] 'compare' (duration: 46.943456ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:36:16.568851Z","caller":"traceutil/trace.go:171","msg":"trace[1564858065] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"220.184307ms","start":"2026-05-27T00:36:16.348638Z","end":"2026-05-27T00:36:16.568822Z","steps":["trace[1564858065] 'process raft request' (duration: 220.01788ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:19.243417Z","caller":"traceutil/trace.go:171","msg":"trace[1461115034] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"107.446052ms","start":"2026-05-27T00:36:19.135947Z","end":"2026-05-27T00:36:19.243393Z","steps":["trace[1461115034] 'process raft request' (duration: 106.89011ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:20.306398Z","caller":"traceutil/trace.go:171","msg":"trace[1101159961] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:490; }","duration":"300.851753ms","start":"2026-05-27T00:36:20.005523Z","end":"2026-05-27T00:36:20.306374Z","steps":["trace[1101159961] 'read index received' (duration: 295.986277ms)","trace[1101159961] 'applied index is now lower than readState.Index' (duration: 4.863996ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:36:20.306669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.651967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-27T00:36:20.306655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.038581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T00:36:20.306819Z","caller":"traceutil/trace.go:171","msg":"trace[1482768956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:474; }","duration":"301.343043ms","start":"2026-05-27T00:36:20.005444Z","end":"2026-05-27T00:36:20.306787Z","steps":["trace[1482768956] 'agreement among raft nodes before linearized reading' (duration: 301.01539ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:20.306737Z","caller":"traceutil/trace.go:171","msg":"trace[1995014889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:474; }","duration":"196.841694ms","start":"2026-05-27T00:36:20.109883Z","end":"2026-05-27T00:36:20.306724Z","steps":["trace[1995014889] 'agreement among raft nodes before linearized reading' (duration: 196.637986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:20.306887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:20.005417Z","time spent":"301.459207ms","remote":"127.0.0.1:50038","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-27T00:36:24.875391Z","caller":"traceutil/trace.go:171","msg":"trace[1774089393] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:501; }","duration":"200.353302ms","start":"2026-05-27T00:36:24.675019Z","end":"2026-05-27T00:36:24.875372Z","steps":["trace[1774089393] 'read index received' (duration: 200.245148ms)","trace[1774089393] 'applied index is now lower than readState.Index' (duration: 107.374µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:36:24.875504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.472106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-27T00:36:24.875446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:24.51726Z","time spent":"358.177741ms","remote":"127.0.0.1:50068","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-27T00:36:24.875526Z","caller":"traceutil/trace.go:171","msg":"trace[259450995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:484; }","duration":"200.548559ms","start":"2026-05-27T00:36:24.674972Z","end":"2026-05-27T00:36:24.87552Z","steps":["trace[259450995] 'agreement among raft nodes before linearized reading' (duration: 200.466706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:28.741006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.633946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T00:36:28.74115Z","caller":"traceutil/trace.go:171","msg":"trace[1347039937] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"715.060649ms","start":"2026-05-27T00:36:28.026072Z","end":"2026-05-27T00:36:28.741133Z","steps":["trace[1347039937] 'process raft request' (duration: 546.76884ms)","trace[1347039937] 'compare' (duration: 167.538812ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:36:28.741216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:28.026063Z","time spent":"715.119932ms","remote":"127.0.0.1:50096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:36:28.940439Z","caller":"traceutil/trace.go:171","msg":"trace[1910751271] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"913.62433ms","start":"2026-05-27T00:36:28.026793Z","end":"2026-05-27T00:36:28.940417Z","steps":["trace[1910751271] 'process raft request' (duration: 913.409862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:28.940551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:28.026768Z","time spent":"913.725134ms","remote":"127.0.0.1:50096","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-27T00:36:28.940647Z","caller":"traceutil/trace.go:171","msg":"trace[319557046] transaction","detail":"{read_only:false; number_of_response:1; response_revision:531; }","duration":"904.574076ms","start":"2026-05-27T00:36:28.036047Z","end":"2026-05-27T00:36:28.940622Z","steps":["trace[319557046] 'process raft request' (duration: 904.309766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:28.940777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:28.036019Z","time spent":"904.6847ms","remote":"127.0.0.1:50096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:36:28.940786Z","caller":"traceutil/trace.go:171","msg":"trace[1607499235] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"904.587356ms","start":"2026-05-27T00:36:28.03618Z","end":"2026-05-27T00:36:28.940767Z","steps":["trace[1607499235] 'process raft request' (duration: 904.243673ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:28.940897Z","caller":"traceutil/trace.go:171","msg":"trace[1532662658] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"904.622807ms","start":"2026-05-27T00:36:28.036249Z","end":"2026-05-27T00:36:28.940871Z","steps":["trace[1532662658] 'process raft request' (duration: 904.339516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:28.940995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:28.036162Z","time spent":"904.717ms","remote":"127.0.0.1:50096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T00:36:28.941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:28.036244Z","time spent":"904.7124ms","remote":"127.0.0.1:50096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T00:36:28.941055Z","caller":"traceutil/trace.go:171","msg":"trace[1776157075] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"904.656428ms","start":"2026-05-27T00:36:28.036383Z","end":"2026-05-27T00:36:28.94104Z","steps":["trace[1776157075] 'process raft request' (duration: 904.492262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:28.941097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:28.036378Z","time spent":"904.701899ms","remote":"127.0.0.1:50606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T00:36:28.941021Z","caller":"traceutil/trace.go:171","msg":"trace[848144938] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"904.70268ms","start":"2026-05-27T00:36:28.036293Z","end":"2026-05-27T00:36:28.940995Z","steps":["trace[848144938] 'process raft request' (duration: 904.447441ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:28.941211Z","caller":"traceutil/trace.go:171","msg":"trace[1893431101] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"904.771422ms","start":"2026-05-27T00:36:28.036412Z","end":"2026-05-27T00:36:28.941184Z","steps":["trace[1893431101] 'process raft request' (duration: 904.556024ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:28.941289Z","caller":"traceutil/trace.go:171","msg":"trace[2007073938] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"899.697785ms","start":"2026-05-27T00:36:28.041585Z","end":"2026-05-27T00:36:28.941282Z","steps":["trace[2007073938] 'process raft request' (duration: 899.485997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:28.94128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:28.036403Z","time spent":"904.847446ms","remote":"127.0.0.1:50606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T00:36:28.941329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:28.041567Z","time spent":"899.743306ms","remote":"127.0.0.1:50096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T00:36:28.941395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:28.036282Z","time spent":"904.948999ms","remote":"127.0.0.1:50096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T00:36:28.941443Z","caller":"traceutil/trace.go:171","msg":"trace[403732228] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"899.676584ms","start":"2026-05-27T00:36:28.041761Z","end":"2026-05-27T00:36:28.941437Z","steps":["trace[403732228] 'process raft request' (duration: 899.417284ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:28.941539Z","caller":"traceutil/trace.go:171","msg":"trace[1265576921] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"893.234386ms","start":"2026-05-27T00:36:28.048291Z","end":"2026-05-27T00:36:28.941525Z","steps":["trace[1265576921] 'process raft request' (duration: 893.039569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:28.941565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:28.041739Z","time spent":"899.799328ms","remote":"127.0.0.1:50096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T00:36:28.94163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:28.048275Z","time spent":"893.325348ms","remote":"127.0.0.1:50096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T00:36:28.941743Z","caller":"traceutil/trace.go:171","msg":"trace[1693218938] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"855.802699ms","start":"2026-05-27T00:36:28.085932Z","end":"2026-05-27T00:36:28.941734Z","steps":["trace[1693218938] 'process raft request' (duration: 855.594642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:28.941783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:28.085912Z","time spent":"855.853342ms","remote":"127.0.0.1:50096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T00:36:28.941989Z","caller":"traceutil/trace.go:171","msg":"trace[1848525046] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"849.310489ms","start":"2026-05-27T00:36:28.092669Z","end":"2026-05-27T00:36:28.941979Z","steps":["trace[1848525046] 'process raft request' (duration: 848.983277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:28.942055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:28.092654Z","time spent":"849.384423ms","remote":"127.0.0.1:50096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T00:36:28.942264Z","caller":"traceutil/trace.go:171","msg":"trace[1488236900] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"827.183912ms","start":"2026-05-27T00:36:28.115071Z","end":"2026-05-27T00:36:28.942255Z","steps":["trace[1488236900] 'process raft request' (duration: 826.794896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:28.942304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:28.115062Z","time spent":"827.223673ms","remote":"127.0.0.1:50096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T00:36:28.943003Z","caller":"traceutil/trace.go:171","msg":"trace[337196249] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"726.475932ms","start":"2026-05-27T00:36:28.216508Z","end":"2026-05-27T00:36:28.942984Z","steps":["trace[337196249] 'process raft request' (duration: 725.710684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:28.943149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:28.21649Z","time spent":"726.625909ms","remote":"127.0.0.1:50096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T00:36:28.943416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.344161ms","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-27T00:36:28.943495Z","caller":"traceutil/trace.go:171","msg":"trace[1716964026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:543; }","duration":"490.477276ms","start":"2026-05-27T00:36:28.453Z","end":"2026-05-27T00:36:28.943477Z","steps":["trace[1716964026] 'agreement among raft nodes before linearized reading' (duration: 490.276359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:28.943636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:28.452967Z","time spent":"490.656023ms","remote":"127.0.0.1:50348","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-27T00:36:28.942527Z","caller":"traceutil/trace.go:171","msg":"trace[1655325821] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:547; }","duration":"489.402707ms","start":"2026-05-27T00:36:28.453055Z","end":"2026-05-27T00:36:28.942458Z","steps":["trace[1655325821] 'read index received' (duration: 119.819876ms)","trace[1655325821] 'applied index is now lower than readState.Index' (duration: 369.581541ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:36:35.525301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422784790226562,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T00:36:35.834047Z","caller":"traceutil/trace.go:171","msg":"trace[331604192] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"808.808942ms","start":"2026-05-27T00:36:35.025207Z","end":"2026-05-27T00:36:35.834016Z","steps":["trace[331604192] 'read index received' (duration: 807.856859ms)","trace[331604192] 'applied index is now lower than readState.Index' (duration: 950.573µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:36:35.834166Z","caller":"traceutil/trace.go:171","msg":"trace[371672150] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"893.868237ms","start":"2026-05-27T00:36:34.940267Z","end":"2026-05-27T00:36:35.834135Z","steps":["trace[371672150] 'process raft request' (duration: 893.0893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:35.83439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.216026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-27T00:36:35.834411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.157382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8tt4x\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-05-27T00:36:35.834447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.223996ms","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-27T00:36:35.834602Z","caller":"traceutil/trace.go:171","msg":"trace[934636935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"809.386212ms","start":"2026-05-27T00:36:35.025202Z","end":"2026-05-27T00:36:35.834588Z","steps":["trace[934636935] 'agreement among raft nodes before linearized reading' (duration: 808.956547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:35.834581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:34.940239Z","time spent":"894.225269ms","remote":"127.0.0.1:50140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T00:36:35.834661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:35.025156Z","time spent":"809.490046ms","remote":"127.0.0.1:50348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T00:36:35.834447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.475766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-27T00:36:35.834855Z","caller":"traceutil/trace.go:171","msg":"trace[369659857] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"807.937211ms","start":"2026-05-27T00:36:35.026862Z","end":"2026-05-27T00:36:35.834799Z","steps":["trace[369659857] 'agreement among raft nodes before linearized reading' (duration: 807.32044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:35.834358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.645645ms","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-27T00:36:35.834948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:35.026849Z","time spent":"808.080606ms","remote":"127.0.0.1:50348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T00:36:35.834991Z","caller":"traceutil/trace.go:171","msg":"trace[1297422973] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"484.332998ms","start":"2026-05-27T00:36:35.350642Z","end":"2026-05-27T00:36:35.834975Z","steps":["trace[1297422973] 'agreement among raft nodes before linearized reading' (duration: 483.615733ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:35.834503Z","caller":"traceutil/trace.go:171","msg":"trace[138976666] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8tt4x; range_end:; response_count:1; response_revision:634; }","duration":"364.292986ms","start":"2026-05-27T00:36:35.470194Z","end":"2026-05-27T00:36:35.834487Z","steps":["trace[138976666] 'agreement among raft nodes before linearized reading' (duration: 364.11512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:35.835077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:35.35062Z","time spent":"484.439372ms","remote":"127.0.0.1:50348","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-05-27T00:36:35.835085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:35.470171Z","time spent":"364.900177ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-8tt4x\" "} {"level":"info","ts":"2026-05-27T00:36:35.834494Z","caller":"traceutil/trace.go:171","msg":"trace[1448634686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:634; }","duration":"382.378472ms","start":"2026-05-27T00:36:35.452089Z","end":"2026-05-27T00:36:35.834467Z","steps":["trace[1448634686] 'agreement among raft nodes before linearized reading' (duration: 382.141683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:35.835281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:35.452062Z","time spent":"383.2024ms","remote":"127.0.0.1:50348","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":"info","ts":"2026-05-27T00:36:36.471619Z","caller":"traceutil/trace.go:171","msg":"trace[361067400] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"630.005405ms","start":"2026-05-27T00:36:35.841591Z","end":"2026-05-27T00:36:36.471596Z","steps":["trace[361067400] 'process raft request' (duration: 629.826389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:36.471762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:35.841571Z","time spent":"630.117479ms","remote":"127.0.0.1:50348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:36:36.471921Z","caller":"traceutil/trace.go:171","msg":"trace[1104075335] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"629.662032ms","start":"2026-05-27T00:36:35.842231Z","end":"2026-05-27T00:36:36.471893Z","steps":["trace[1104075335] 'process raft request' (duration: 629.503347ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:36.471967Z","caller":"traceutil/trace.go:171","msg":"trace[221260998] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"630.098298ms","start":"2026-05-27T00:36:35.841847Z","end":"2026-05-27T00:36:36.471946Z","steps":["trace[221260998] 'process raft request' (duration: 629.708235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:36.472052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:35.84222Z","time spent":"629.758436ms","remote":"127.0.0.1:50348","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-27T00:36:36.472075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:35.8418Z","time spent":"630.217972ms","remote":"127.0.0.1:50348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:36:36.472129Z","caller":"traceutil/trace.go:171","msg":"trace[998910754] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"620.691343ms","start":"2026-05-27T00:36:35.851418Z","end":"2026-05-27T00:36:36.47211Z","steps":["trace[998910754] 'process raft request' (duration: 620.410224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:36.472234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:35.851398Z","time spent":"620.778956ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:36:36.482041Z","caller":"traceutil/trace.go:171","msg":"trace[449979565] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:658; }","duration":"474.031842ms","start":"2026-05-27T00:36:36.007993Z","end":"2026-05-27T00:36:36.482025Z","steps":["trace[449979565] 'read index received' (duration: 463.498082ms)","trace[449979565] 'applied index is now lower than readState.Index' (duration: 10.53282ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:36:36.482137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.145137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T00:36:36.482164Z","caller":"traceutil/trace.go:171","msg":"trace[1263022298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"474.218019ms","start":"2026-05-27T00:36:36.007938Z","end":"2026-05-27T00:36:36.482156Z","steps":["trace[1263022298] 'agreement among raft nodes before linearized reading' (duration: 474.146276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:36.482187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:36.007917Z","time spent":"474.266021ms","remote":"127.0.0.1:50038","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-27T00:36:36.482314Z","caller":"traceutil/trace.go:171","msg":"trace[1417035120] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"590.011891ms","start":"2026-05-27T00:36:35.892286Z","end":"2026-05-27T00:36:36.482298Z","steps":["trace[1417035120] 'process raft request' (duration: 589.655639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:36.482418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:35.89226Z","time spent":"590.112485ms","remote":"127.0.0.1:50600","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-05-27T00:36:37.855991Z","caller":"traceutil/trace.go:171","msg":"trace[248823949] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"271.99826ms","start":"2026-05-27T00:36:37.583966Z","end":"2026-05-27T00:36:37.855964Z","steps":["trace[248823949] 'process raft request' (duration: 271.914878ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:37.856459Z","caller":"traceutil/trace.go:171","msg":"trace[651990141] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"952.199282ms","start":"2026-05-27T00:36:36.904243Z","end":"2026-05-27T00:36:37.856443Z","steps":["trace[651990141] 'process raft request' (duration: 949.752449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:37.856563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:36.904212Z","time spent":"952.278565ms","remote":"127.0.0.1:50140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:36:38.740201Z","caller":"traceutil/trace.go:171","msg":"trace[1032709751] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"258.540951ms","start":"2026-05-27T00:36:38.481636Z","end":"2026-05-27T00:36:38.740177Z","steps":["trace[1032709751] 'read index received' (duration: 258.419247ms)","trace[1032709751] 'applied index is now lower than readState.Index' (duration: 120.754µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:36:38.740242Z","caller":"traceutil/trace.go:171","msg":"trace[733405673] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"259.088829ms","start":"2026-05-27T00:36:38.481121Z","end":"2026-05-27T00:36:38.74021Z","steps":["trace[733405673] 'process raft request' (duration: 258.914943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:38.740439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.779148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8tt4x\" ","response":"range_response_count:1 size:17145"} {"level":"info","ts":"2026-05-27T00:36:38.740484Z","caller":"traceutil/trace.go:171","msg":"trace[2010401532] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8tt4x; range_end:; response_count:1; response_revision:650; }","duration":"258.850501ms","start":"2026-05-27T00:36:38.481622Z","end":"2026-05-27T00:36:38.740472Z","steps":["trace[2010401532] 'agreement among raft nodes before linearized reading' (duration: 258.646315ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:38.76812Z","caller":"traceutil/trace.go:171","msg":"trace[1054754056] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"284.806001ms","start":"2026-05-27T00:36:38.483293Z","end":"2026-05-27T00:36:38.768099Z","steps":["trace[1054754056] 'process raft request' (duration: 284.76561ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:38.768168Z","caller":"traceutil/trace.go:171","msg":"trace[1584410153] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"286.218498ms","start":"2026-05-27T00:36:38.481918Z","end":"2026-05-27T00:36:38.768137Z","steps":["trace[1584410153] 'process raft request' (duration: 285.929809ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:38.768327Z","caller":"traceutil/trace.go:171","msg":"trace[1681132150] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"285.824155ms","start":"2026-05-27T00:36:38.482451Z","end":"2026-05-27T00:36:38.768275Z","steps":["trace[1681132150] 'process raft request' (duration: 285.559816ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:38.958052Z","caller":"traceutil/trace.go:171","msg":"trace[512416337] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"202.080319ms","start":"2026-05-27T00:36:38.755938Z","end":"2026-05-27T00:36:38.958018Z","steps":["trace[512416337] 'process raft request' (duration: 201.81965ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:39.38436Z","caller":"traceutil/trace.go:171","msg":"trace[1197857473] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"111.938974ms","start":"2026-05-27T00:36:39.272389Z","end":"2026-05-27T00:36:39.384328Z","steps":["trace[1197857473] 'process raft request' (duration: 111.759278ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:40.619094Z","caller":"traceutil/trace.go:171","msg":"trace[980975478] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"123.5561ms","start":"2026-05-27T00:36:40.495513Z","end":"2026-05-27T00:36:40.619069Z","steps":["trace[980975478] 'process raft request' (duration: 123.393314ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:40.626674Z","caller":"traceutil/trace.go:171","msg":"trace[1541339222] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"119.965601ms","start":"2026-05-27T00:36:40.506699Z","end":"2026-05-27T00:36:40.626664Z","steps":["trace[1541339222] 'process raft request' (duration: 119.883028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:41.556241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.450462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-27T00:36:41.556355Z","caller":"traceutil/trace.go:171","msg":"trace[1574387238] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:667; }","duration":"313.608327ms","start":"2026-05-27T00:36:41.242722Z","end":"2026-05-27T00:36:41.556331Z","steps":["trace[1574387238] 'range keys from in-memory index tree' (duration: 313.314008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:41.556815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:41.242702Z","time spent":"314.094082ms","remote":"127.0.0.1:50158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-27T00:36:42.94788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.574165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-27T00:36:42.947962Z","caller":"traceutil/trace.go:171","msg":"trace[1193802482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:701; }","duration":"159.687239ms","start":"2026-05-27T00:36:42.788254Z","end":"2026-05-27T00:36:42.947942Z","steps":["trace[1193802482] 'range keys from in-memory index tree' (duration: 159.444641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:42.948111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.851584ms","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-27T00:36:42.948165Z","caller":"traceutil/trace.go:171","msg":"trace[387246411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:701; }","duration":"159.922126ms","start":"2026-05-27T00:36:42.788228Z","end":"2026-05-27T00:36:42.94815Z","steps":["trace[387246411] 'range keys from in-memory index tree' (duration: 159.74596ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:42.948315Z","caller":"traceutil/trace.go:171","msg":"trace[873683591] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:727; }","duration":"150.805342ms","start":"2026-05-27T00:36:42.797487Z","end":"2026-05-27T00:36:42.948293Z","steps":["trace[873683591] 'read index received' (duration: 103.105724ms)","trace[873683591] 'applied index is now lower than readState.Index' (duration: 47.697968ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:36:42.948442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.943087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T00:36:42.948452Z","caller":"traceutil/trace.go:171","msg":"trace[1755521963] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"159.334667ms","start":"2026-05-27T00:36:42.789102Z","end":"2026-05-27T00:36:42.948436Z","steps":["trace[1755521963] 'process raft request' (duration: 111.494165ms)","trace[1755521963] 'compare' (duration: 47.14647ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:36:42.948516Z","caller":"traceutil/trace.go:171","msg":"trace[1515013652] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:702; }","duration":"151.013059ms","start":"2026-05-27T00:36:42.797458Z","end":"2026-05-27T00:36:42.948471Z","steps":["trace[1515013652] 'agreement among raft nodes before linearized reading' (duration: 150.929026ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:43.765809Z","caller":"traceutil/trace.go:171","msg":"trace[155657208] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:731; }","duration":"156.977664ms","start":"2026-05-27T00:36:43.6088Z","end":"2026-05-27T00:36:43.765778Z","steps":["trace[155657208] 'read index received' (duration: 154.739902ms)","trace[155657208] 'applied index is now lower than readState.Index' (duration: 2.236732ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:36:43.765961Z","caller":"traceutil/trace.go:171","msg":"trace[196324203] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"166.441245ms","start":"2026-05-27T00:36:43.599487Z","end":"2026-05-27T00:36:43.765928Z","steps":["trace[196324203] 'process raft request' (duration: 164.126351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:43.766027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.211411ms","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-27T00:36:43.766064Z","caller":"traceutil/trace.go:171","msg":"trace[1638415649] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:706; }","duration":"157.299474ms","start":"2026-05-27T00:36:43.608753Z","end":"2026-05-27T00:36:43.766052Z","steps":["trace[1638415649] 'agreement among raft nodes before linearized reading' (duration: 157.152089ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:44.945427Z","caller":"traceutil/trace.go:171","msg":"trace[366071670] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"143.062957ms","start":"2026-05-27T00:36:44.802327Z","end":"2026-05-27T00:36:44.94539Z","steps":["trace[366071670] 'process raft request' (duration: 142.936313ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:45.381232Z","caller":"traceutil/trace.go:171","msg":"trace[917425923] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"153.741619ms","start":"2026-05-27T00:36:45.22745Z","end":"2026-05-27T00:36:45.381191Z","steps":["trace[917425923] 'process raft request' (duration: 78.450839ms)","trace[917425923] 'compare' (duration: 74.838376ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:36:45.381381Z","caller":"traceutil/trace.go:171","msg":"trace[999370225] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"116.935706ms","start":"2026-05-27T00:36:45.264415Z","end":"2026-05-27T00:36:45.38135Z","steps":["trace[999370225] 'process raft request' (duration: 116.700329ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:45.796493Z","caller":"traceutil/trace.go:171","msg":"trace[171043398] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:760; }","duration":"188.210339ms","start":"2026-05-27T00:36:45.608262Z","end":"2026-05-27T00:36:45.796472Z","steps":["trace[171043398] 'read index received' (duration: 132.840134ms)","trace[171043398] 'applied index is now lower than readState.Index' (duration: 55.369295ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:36:45.796603Z","caller":"traceutil/trace.go:171","msg":"trace[93948416] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"192.774013ms","start":"2026-05-27T00:36:45.603817Z","end":"2026-05-27T00:36:45.796591Z","steps":["trace[93948416] 'process raft request' (duration: 137.276633ms)","trace[93948416] 'compare' (duration: 55.114068ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:36:45.796756Z","caller":"traceutil/trace.go:171","msg":"trace[1176668772] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"186.074522ms","start":"2026-05-27T00:36:45.610647Z","end":"2026-05-27T00:36:45.796721Z","steps":["trace[1176668772] 'process raft request' (duration: 185.782183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:45.796819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.537679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8tt4x\" ","response":"range_response_count:1 size:18333"} {"level":"info","ts":"2026-05-27T00:36:45.796855Z","caller":"traceutil/trace.go:171","msg":"trace[654074968] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8tt4x; range_end:; response_count:1; response_revision:735; }","duration":"188.596692ms","start":"2026-05-27T00:36:45.608247Z","end":"2026-05-27T00:36:45.796843Z","steps":["trace[654074968] 'agreement among raft nodes before linearized reading' (duration: 188.322873ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:45.979459Z","caller":"traceutil/trace.go:171","msg":"trace[1460971644] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"162.142933ms","start":"2026-05-27T00:36:45.817286Z","end":"2026-05-27T00:36:45.979429Z","steps":["trace[1460971644] 'process raft request' (duration: 126.106913ms)","trace[1460971644] 'compare' (duration: 35.479702ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:36:53.380598Z","caller":"traceutil/trace.go:171","msg":"trace[699022371] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"330.485167ms","start":"2026-05-27T00:36:53.05009Z","end":"2026-05-27T00:36:53.380576Z","steps":["trace[699022371] 'read index received' (duration: 330.23709ms)","trace[699022371] 'applied index is now lower than readState.Index' (duration: 246.977µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:36:53.380748Z","caller":"traceutil/trace.go:171","msg":"trace[1133890381] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"332.295869ms","start":"2026-05-27T00:36:53.048418Z","end":"2026-05-27T00:36:53.380713Z","steps":["trace[1133890381] 'process raft request' (duration: 331.97424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:53.380853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.747165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-27T00:36:53.380888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.6036ms","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-27T00:36:53.380919Z","caller":"traceutil/trace.go:171","msg":"trace[994345487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:795; }","duration":"330.877318ms","start":"2026-05-27T00:36:53.050027Z","end":"2026-05-27T00:36:53.380905Z","steps":["trace[994345487] 'agreement among raft nodes before linearized reading' (duration: 330.662452ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:53.380955Z","caller":"traceutil/trace.go:171","msg":"trace[836865854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:795; }","duration":"294.709063ms","start":"2026-05-27T00:36:53.086229Z","end":"2026-05-27T00:36:53.380938Z","steps":["trace[836865854] 'agreement among raft nodes before linearized reading' (duration: 294.505667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:53.380972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:53.049998Z","time spent":"330.962291ms","remote":"127.0.0.1:50348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T00:36:53.380982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:53.0484Z","time spent":"332.459394ms","remote":"127.0.0.1:50348","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-27T00:36:57.000584Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422784790226919,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T00:36:57.347979Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.22236839s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T00:36:57.348486Z","caller":"traceutil/trace.go:171","msg":"trace[1308518107] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"1.222964047s","start":"2026-05-27T00:36:56.125495Z","end":"2026-05-27T00:36:57.348459Z","steps":["trace[1308518107] 'process raft request' (duration: 1.222783772s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:57.348631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:56.125469Z","time spent":"1.223074521s","remote":"127.0.0.1:50348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:36:57.383507Z","caller":"traceutil/trace.go:171","msg":"trace[2019913219] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:845; }","duration":"883.805213ms","start":"2026-05-27T00:36:56.49967Z","end":"2026-05-27T00:36:57.383475Z","steps":["trace[2019913219] 'read index received' (duration: 848.592612ms)","trace[2019913219] 'applied index is now lower than readState.Index' (duration: 35.211491ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:36:57.384128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.701732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T00:36:57.384197Z","caller":"traceutil/trace.go:171","msg":"trace[65315180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:816; }","duration":"374.869728ms","start":"2026-05-27T00:36:57.009311Z","end":"2026-05-27T00:36:57.38418Z","steps":["trace[65315180] 'agreement among raft nodes before linearized reading' (duration: 374.724864ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:36:57.384171Z","caller":"traceutil/trace.go:171","msg":"trace[1392475241] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"713.120245ms","start":"2026-05-27T00:36:56.670997Z","end":"2026-05-27T00:36:57.384117Z","steps":["trace[1392475241] 'process raft request' (duration: 712.316433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:57.384254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:57.009287Z","time spent":"374.95411ms","remote":"127.0.0.1:50038","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-27T00:36:57.38442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:56.67098Z","time spent":"713.30998ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T00:36:57.384845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.125241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T00:36:57.385193Z","caller":"traceutil/trace.go:171","msg":"trace[1381343403] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:816; }","duration":"885.566313ms","start":"2026-05-27T00:36:56.499603Z","end":"2026-05-27T00:36:57.385169Z","steps":["trace[1381343403] 'agreement among raft nodes before linearized reading' (duration: 884.520233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:36:57.386112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:36:56.499582Z","time spent":"886.50288ms","remote":"127.0.0.1:50284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-05-27T00:37:11.871949Z","caller":"traceutil/trace.go:171","msg":"trace[16801900] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"135.125106ms","start":"2026-05-27T00:37:11.736799Z","end":"2026-05-27T00:37:11.871924Z","steps":["trace[16801900] 'process raft request' (duration: 134.982663ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:37:11.944514Z","caller":"traceutil/trace.go:171","msg":"trace[1099625155] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"191.624557ms","start":"2026-05-27T00:37:11.752867Z","end":"2026-05-27T00:37:11.944491Z","steps":["trace[1099625155] 'process raft request' (duration: 191.509645ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:37:12.041488Z","caller":"traceutil/trace.go:171","msg":"trace[476535975] transaction","detail":"{read_only:false; number_of_response:1; response_revision:986; }","duration":"166.276603ms","start":"2026-05-27T00:37:11.875192Z","end":"2026-05-27T00:37:12.041468Z","steps":["trace[476535975] 'process raft request' (duration: 157.784121ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:37:12.21763Z","caller":"traceutil/trace.go:171","msg":"trace[1294598429] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"171.662613ms","start":"2026-05-27T00:37:12.045944Z","end":"2026-05-27T00:37:12.217606Z","steps":["trace[1294598429] 'process raft request' (duration: 138.63522ms)","trace[1294598429] 'compare' (duration: 32.94193ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:37:29.732968Z","caller":"traceutil/trace.go:171","msg":"trace[121717336] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"105.80058ms","start":"2026-05-27T00:37:29.627146Z","end":"2026-05-27T00:37:29.732946Z","steps":["trace[121717336] 'process raft request' (duration: 105.688837ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:37:56.279889Z","caller":"traceutil/trace.go:171","msg":"trace[1560945901] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"108.115613ms","start":"2026-05-27T00:37:56.171738Z","end":"2026-05-27T00:37:56.279854Z","steps":["trace[1560945901] 'process raft request' (duration: 108.009581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:37:58.567405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.707042ms","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-27T00:37:58.567529Z","caller":"traceutil/trace.go:171","msg":"trace[1793222453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1149; }","duration":"104.887904ms","start":"2026-05-27T00:37:58.462623Z","end":"2026-05-27T00:37:58.567511Z","steps":["trace[1793222453] 'range keys from in-memory index tree' (duration: 104.517987ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:38:12.190416Z","caller":"traceutil/trace.go:171","msg":"trace[1421532098] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"112.377543ms","start":"2026-05-27T00:38:12.078014Z","end":"2026-05-27T00:38:12.190391Z","steps":["trace[1421532098] 'process raft request' (duration: 112.225831ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:38:14.301968Z","caller":"traceutil/trace.go:171","msg":"trace[605827435] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"104.789446ms","start":"2026-05-27T00:38:14.197113Z","end":"2026-05-27T00:38:14.301903Z","steps":["trace[605827435] 'process raft request' (duration: 104.561231ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:38:16.037674Z","caller":"traceutil/trace.go:171","msg":"trace[1687947095] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"154.04702ms","start":"2026-05-27T00:38:15.883597Z","end":"2026-05-27T00:38:16.037645Z","steps":["trace[1687947095] 'process raft request' (duration: 64.106822ms)","trace[1687947095] 'compare' (duration: 89.729574ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:38:16.113481Z","caller":"traceutil/trace.go:171","msg":"trace[382470982] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"105.35346ms","start":"2026-05-27T00:38:16.008107Z","end":"2026-05-27T00:38:16.11346Z","steps":["trace[382470982] 'process raft request' (duration: 105.233038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:38:16.60305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.861416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-27T00:38:16.603152Z","caller":"traceutil/trace.go:171","msg":"trace[1131593945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1207; }","duration":"298.016739ms","start":"2026-05-27T00:38:16.30511Z","end":"2026-05-27T00:38:16.603127Z","steps":["trace[1131593945] 'range keys from in-memory index tree' (duration: 297.573581ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:38:16.603166Z","caller":"traceutil/trace.go:171","msg":"trace[537490737] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"154.976727ms","start":"2026-05-27T00:38:16.44817Z","end":"2026-05-27T00:38:16.603147Z","steps":["trace[537490737] 'process raft request' (duration: 107.158972ms)","trace[537490737] 'compare' (duration: 47.47987ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:38:17.014756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.03544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T00:38:17.014821Z","caller":"traceutil/trace.go:171","msg":"trace[2007394228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1209; }","duration":"169.155552ms","start":"2026-05-27T00:38:16.84565Z","end":"2026-05-27T00:38:17.014806Z","steps":["trace[2007394228] 'range keys from in-memory index tree' (duration: 168.961448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:38:17.01497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.974135ms","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-27T00:38:17.015003Z","caller":"traceutil/trace.go:171","msg":"trace[1878205798] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1209; }","duration":"266.075607ms","start":"2026-05-27T00:38:16.74892Z","end":"2026-05-27T00:38:17.014995Z","steps":["trace[1878205798] 'range keys from in-memory index tree' (duration: 265.795472ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:38:18.40624Z","caller":"traceutil/trace.go:171","msg":"trace[144763944] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1258; }","duration":"270.456607ms","start":"2026-05-27T00:38:18.13576Z","end":"2026-05-27T00:38:18.406217Z","steps":["trace[144763944] 'read index received' (duration: 270.278584ms)","trace[144763944] 'applied index is now lower than readState.Index' (duration: 177.173µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:38:18.406276Z","caller":"traceutil/trace.go:171","msg":"trace[1843850578] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"360.276506ms","start":"2026-05-27T00:38:18.045966Z","end":"2026-05-27T00:38:18.406243Z","steps":["trace[1843850578] 'process raft request' (duration: 360.101613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:38:18.406438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:38:18.045936Z","time spent":"360.413838ms","remote":"127.0.0.1:50348","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-27T00:38:18.406502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.736471ms","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-27T00:38:18.406542Z","caller":"traceutil/trace.go:171","msg":"trace[69248597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1212; }","duration":"270.827943ms","start":"2026-05-27T00:38:18.135704Z","end":"2026-05-27T00:38:18.406532Z","steps":["trace[69248597] 'agreement among raft nodes before linearized reading' (duration: 270.63293ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:38:19.302652Z","caller":"traceutil/trace.go:171","msg":"trace[1498539210] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"262.234178ms","start":"2026-05-27T00:38:19.040397Z","end":"2026-05-27T00:38:19.302631Z","steps":["trace[1498539210] 'process raft request' (duration: 262.053755ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:38:30.61833Z","caller":"traceutil/trace.go:171","msg":"trace[888534866] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"102.075245ms","start":"2026-05-27T00:38:30.516229Z","end":"2026-05-27T00:38:30.618304Z","steps":["trace[888534866] 'process raft request' (duration: 100.375706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:38:31.071277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.545691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T00:38:31.071395Z","caller":"traceutil/trace.go:171","msg":"trace[854460206] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"152.105912ms","start":"2026-05-27T00:38:30.919268Z","end":"2026-05-27T00:38:31.071374Z","steps":["trace[854460206] 'process raft request' (duration: 44.379388ms)","trace[854460206] 'compare' (duration: 107.436ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:38:32.589647Z","caller":"traceutil/trace.go:171","msg":"trace[547147105] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"375.764434ms","start":"2026-05-27T00:38:32.213851Z","end":"2026-05-27T00:38:32.589616Z","steps":["trace[547147105] 'process raft request' (duration: 375.428048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:38:32.589848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:38:32.213811Z","time spent":"375.922506ms","remote":"127.0.0.1:50216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T00:38:33.568322Z","caller":"traceutil/trace.go:171","msg":"trace[475823055] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1317; }","duration":"193.153537ms","start":"2026-05-27T00:38:33.375144Z","end":"2026-05-27T00:38:33.568297Z","steps":["trace[475823055] 'read index received' (duration: 192.935803ms)","trace[475823055] 'applied index is now lower than readState.Index' (duration: 216.694µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:38:33.568386Z","caller":"traceutil/trace.go:171","msg":"trace[1846333640] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"193.788638ms","start":"2026-05-27T00:38:33.374553Z","end":"2026-05-27T00:38:33.568341Z","steps":["trace[1846333640] 'process raft request' (duration: 193.583414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:38:33.568663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.503332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5547"} {"level":"info","ts":"2026-05-27T00:38:33.568739Z","caller":"traceutil/trace.go:171","msg":"trace[1100947592] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1267; }","duration":"193.614094ms","start":"2026-05-27T00:38:33.375089Z","end":"2026-05-27T00:38:33.568703Z","steps":["trace[1100947592] 'agreement among raft nodes before linearized reading' (duration: 193.384731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:38:33.568762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.856431ms","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-27T00:38:33.569079Z","caller":"traceutil/trace.go:171","msg":"trace[597160976] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1267; }","duration":"180.057695ms","start":"2026-05-27T00:38:33.388859Z","end":"2026-05-27T00:38:33.568917Z","steps":["trace[597160976] 'agreement among raft nodes before linearized reading' (duration: 179.619208ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:38:39.715505Z","caller":"traceutil/trace.go:171","msg":"trace[352719638] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"108.575489ms","start":"2026-05-27T00:38:39.606903Z","end":"2026-05-27T00:38:39.715478Z","steps":["trace[352719638] 'process raft request' (duration: 108.409497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:38:45.400496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.128461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-27T00:38:45.400575Z","caller":"traceutil/trace.go:171","msg":"trace[1415467629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1338; }","duration":"173.252784ms","start":"2026-05-27T00:38:45.227301Z","end":"2026-05-27T00:38:45.400554Z","steps":["trace[1415467629] 'range keys from in-memory index tree' (duration: 172.977509ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:38:45.902069Z","caller":"traceutil/trace.go:171","msg":"trace[524884815] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1392; }","duration":"140.49081ms","start":"2026-05-27T00:38:45.761521Z","end":"2026-05-27T00:38:45.902011Z","steps":["trace[524884815] 'read index received' (duration: 140.239266ms)","trace[524884815] 'applied index is now lower than readState.Index' (duration: 249.304µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:38:45.902615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.052458ms","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-27T00:38:45.902727Z","caller":"traceutil/trace.go:171","msg":"trace[254344905] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1339; }","duration":"141.222861ms","start":"2026-05-27T00:38:45.761478Z","end":"2026-05-27T00:38:45.902701Z","steps":["trace[254344905] 'agreement among raft nodes before linearized reading' (duration: 140.895376ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:38:45.904542Z","caller":"traceutil/trace.go:171","msg":"trace[122110160] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"500.016752ms","start":"2026-05-27T00:38:45.404478Z","end":"2026-05-27T00:38:45.904495Z","steps":["trace[122110160] 'process raft request' (duration: 497.384281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:38:45.904757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:38:45.404454Z","time spent":"500.193715ms","remote":"127.0.0.1:50348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:38:46.042953Z","caller":"traceutil/trace.go:171","msg":"trace[1048961782] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"136.002618ms","start":"2026-05-27T00:38:45.90692Z","end":"2026-05-27T00:38:46.042922Z","steps":["trace[1048961782] 'process raft request' (duration: 135.185696ms)"],"step_count":1}