{"level":"warn","ts":"2026-07-03T15:37:36.470978Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-03T15:37:36.47107Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.163:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.163:2380","--initial-cluster=instance=https://199.19.213.163:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.163:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.163:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-03T15:37:36.471142Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-03T15:37:36.471155Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.163:2380"]} {"level":"info","ts":"2026-07-03T15:37:36.471173Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-03T15:37:36.471874Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"]} {"level":"info","ts":"2026-07-03T15:37:36.471951Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.163:2380"],"listen-peer-urls":["https://199.19.213.163:2380"],"advertise-client-urls":["https://199.19.213.163:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.163:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-03T15:37:36.477949Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.779376ms"} {"level":"info","ts":"2026-07-03T15:37:36.48789Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e7941a908d261618","cluster-id":"5b6cb15db6ea8c10"} {"level":"info","ts":"2026-07-03T15:37:36.488141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=()"} {"level":"info","ts":"2026-07-03T15:37:36.488223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became follower at term 0"} {"level":"info","ts":"2026-07-03T15:37:36.488254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e7941a908d261618 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-03T15:37:36.488303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became follower at term 1"} {"level":"info","ts":"2026-07-03T15:37:36.488424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=(16686991727460226584)"} {"level":"warn","ts":"2026-07-03T15:37:36.496896Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-03T15:37:36.498416Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-03T15:37:36.50148Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-03T15:37:36.503786Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e7941a908d261618","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-03T15:37:36.504791Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e7941a908d261618","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-03T15:37:36.504603Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-03T15:37:36.505286Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-03T15:37:36.505389Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-03T15:37:36.506055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=(16686991727460226584)"} {"level":"info","ts":"2026-07-03T15:37:36.513449Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5b6cb15db6ea8c10","local-member-id":"e7941a908d261618","added-peer-id":"e7941a908d261618","added-peer-peer-urls":["https://199.19.213.163:2380"]} {"level":"info","ts":"2026-07-03T15:37:36.514521Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-03T15:37:36.514732Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.163:2380"} {"level":"info","ts":"2026-07-03T15:37:36.514768Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e7941a908d261618","initial-advertise-peer-urls":["https://199.19.213.163:2380"],"listen-peer-urls":["https://199.19.213.163:2380"],"advertise-client-urls":["https://199.19.213.163:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-03T15:37:36.514816Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-03T15:37:36.514773Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.163:2380"} {"level":"info","ts":"2026-07-03T15:37:36.89032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 is starting a new election at term 1"} {"level":"info","ts":"2026-07-03T15:37:36.890385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-03T15:37:36.890399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 received MsgPreVoteResp from e7941a908d261618 at term 1"} {"level":"info","ts":"2026-07-03T15:37:36.89041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became candidate at term 2"} {"level":"info","ts":"2026-07-03T15:37:36.890416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 received MsgVoteResp from e7941a908d261618 at term 2"} {"level":"info","ts":"2026-07-03T15:37:36.890439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became leader at term 2"} {"level":"info","ts":"2026-07-03T15:37:36.89045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e7941a908d261618 elected leader e7941a908d261618 at term 2"} {"level":"info","ts":"2026-07-03T15:37:36.959875Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T15:37:36.961467Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e7941a908d261618","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.163:2379]}","request-path":"/0/members/e7941a908d261618/attributes","cluster-id":"5b6cb15db6ea8c10","publish-timeout":"7s"} {"level":"info","ts":"2026-07-03T15:37:36.961672Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-03T15:37:36.96173Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-03T15:37:36.961992Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-03T15:37:36.962025Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-03T15:37:36.963817Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5b6cb15db6ea8c10","local-member-id":"e7941a908d261618","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T15:37:36.963941Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-03T15:37:36.96487Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T15:37:36.964958Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T15:37:36.967331Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.163:2379"} {"level":"warn","ts":"2026-07-03T15:38:11.870521Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592197465121222000,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T15:38:12.065587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.020513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T15:38:12.065738Z","caller":"traceutil/trace.go:171","msg":"trace[820739219] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"696.171056ms","start":"2026-07-03T15:38:11.369525Z","end":"2026-07-03T15:38:12.065696Z","steps":["trace[820739219] 'read index received' (duration: 532.537757ms)","trace[820739219] 'applied index is now lower than readState.Index' (duration: 163.632149ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:38:12.065887Z","caller":"traceutil/trace.go:171","msg":"trace[1345673676] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"959.466134ms","start":"2026-07-03T15:38:11.106408Z","end":"2026-07-03T15:38:12.065874Z","steps":["trace[1345673676] 'process raft request' (duration: 796.599467ms)","trace[1345673676] 'compare' (duration: 161.828085ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T15:38:12.065968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:11.106388Z","time spent":"959.533746ms","remote":"127.0.0.1:54960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317275,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T15:38:12.066277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.278961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-03T15:38:12.066274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.751368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-07-03T15:38:12.066318Z","caller":"traceutil/trace.go:171","msg":"trace[199861163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:519; }","duration":"360.415357ms","start":"2026-07-03T15:38:11.705893Z","end":"2026-07-03T15:38:12.066308Z","steps":["trace[199861163] 'agreement among raft nodes before linearized reading' (duration: 360.281751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:12.066351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:11.705872Z","time spent":"360.472838ms","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-03T15:38:12.06636Z","caller":"traceutil/trace.go:171","msg":"trace[1240929029] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:519; }","duration":"695.908515ms","start":"2026-07-03T15:38:11.370428Z","end":"2026-07-03T15:38:12.066336Z","steps":["trace[1240929029] 'agreement among raft nodes before linearized reading' (duration: 695.682676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:12.066339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.696598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"warn","ts":"2026-07-03T15:38:12.066413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:11.370422Z","time spent":"695.982298ms","remote":"127.0.0.1:55058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":543,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"info","ts":"2026-07-03T15:38:12.067294Z","caller":"traceutil/trace.go:171","msg":"trace[1197769402] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:519; }","duration":"696.939447ms","start":"2026-07-03T15:38:11.369487Z","end":"2026-07-03T15:38:12.066426Z","steps":["trace[1197769402] 'agreement among raft nodes before linearized reading' (duration: 696.612934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:12.067356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:11.369421Z","time spent":"697.921186ms","remote":"127.0.0.1:55058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":561,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-07-03T15:38:14.486187Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592197465121222021,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T15:38:14.888Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.166412545s","expected-duration":"1s"} {"level":"info","ts":"2026-07-03T15:38:14.889019Z","caller":"traceutil/trace.go:171","msg":"trace[302326676] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"1.167584432s","start":"2026-07-03T15:38:13.721416Z","end":"2026-07-03T15:38:14.889Z","steps":["trace[302326676] 'process raft request' (duration: 1.167476038s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:14.889146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:13.721394Z","time spent":"1.167683436s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T15:38:14.905171Z","caller":"traceutil/trace.go:171","msg":"trace[553992024] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.180084733s","start":"2026-07-03T15:38:13.725053Z","end":"2026-07-03T15:38:14.905138Z","steps":["trace[553992024] 'process raft request' (duration: 1.179869995s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:14.905302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:13.725041Z","time spent":"1.180206588s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T15:38:14.905337Z","caller":"traceutil/trace.go:171","msg":"trace[1572991960] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.17422821s","start":"2026-07-03T15:38:13.731083Z","end":"2026-07-03T15:38:14.905311Z","steps":["trace[1572991960] 'process raft request' (duration: 1.173952979s)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:14.905315Z","caller":"traceutil/trace.go:171","msg":"trace[219683332] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.170735911s","start":"2026-07-03T15:38:13.734555Z","end":"2026-07-03T15:38:14.905291Z","steps":["trace[219683332] 'process raft request' (duration: 1.170510962s)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:14.905423Z","caller":"traceutil/trace.go:171","msg":"trace[1901865050] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.166821304s","start":"2026-07-03T15:38:13.738559Z","end":"2026-07-03T15:38:14.90538Z","steps":["trace[1901865050] 'process raft request' (duration: 1.166674529s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:14.905461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:13.731072Z","time spent":"1.174328784s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T15:38:14.905468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:13.734537Z","time spent":"1.170875497s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T15:38:14.905528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:13.738546Z","time spent":"1.166934168s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T15:38:14.905593Z","caller":"traceutil/trace.go:171","msg":"trace[575947926] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.166265852s","start":"2026-07-03T15:38:13.739255Z","end":"2026-07-03T15:38:14.905521Z","steps":["trace[575947926] 'process raft request' (duration: 1.166010602s)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:14.905631Z","caller":"traceutil/trace.go:171","msg":"trace[1233110586] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.167830634s","start":"2026-07-03T15:38:13.737785Z","end":"2026-07-03T15:38:14.905616Z","steps":["trace[1233110586] 'process raft request' (duration: 1.167404837s)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:14.905661Z","caller":"traceutil/trace.go:171","msg":"trace[1223192265] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.161281024s","start":"2026-07-03T15:38:13.744369Z","end":"2026-07-03T15:38:14.90565Z","steps":["trace[1223192265] 'process raft request' (duration: 1.160935759s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:14.905659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:13.739246Z","time spent":"1.166385557s","remote":"127.0.0.1:54926","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-07-03T15:38:14.906456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:13.737758Z","time spent":"1.167917719s","remote":"127.0.0.1:54926","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-07-03T15:38:14.905724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:13.744344Z","time spent":"1.161350337s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T15:38:14.907143Z","caller":"traceutil/trace.go:171","msg":"trace[450156524] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.13563618s","start":"2026-07-03T15:38:13.771457Z","end":"2026-07-03T15:38:14.907093Z","steps":["trace[450156524] 'process raft request' (duration: 1.134164072s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:14.907276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:13.77143Z","time spent":"1.135775806s","remote":"127.0.0.1:54926","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-07-03T15:38:14.919021Z","caller":"traceutil/trace.go:171","msg":"trace[1585199558] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.130301603s","start":"2026-07-03T15:38:13.788688Z","end":"2026-07-03T15:38:14.918989Z","steps":["trace[1585199558] 'process raft request' (duration: 1.130029282s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:14.919104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:13.788658Z","time spent":"1.130409417s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T15:38:14.911764Z","caller":"traceutil/trace.go:171","msg":"trace[170643695] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.133210612s","start":"2026-07-03T15:38:13.777938Z","end":"2026-07-03T15:38:14.911149Z","steps":["trace[170643695] 'process raft request' (duration: 1.129963264s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:14.919196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:13.777925Z","time spent":"1.14125869s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T15:38:14.919621Z","caller":"traceutil/trace.go:171","msg":"trace[164243633] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.018213945s","start":"2026-07-03T15:38:13.90139Z","end":"2026-07-03T15:38:14.919604Z","steps":["trace[164243633] 'process raft request' (duration: 1.017438375s)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:14.919673Z","caller":"traceutil/trace.go:171","msg":"trace[1700735411] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:542; }","duration":"933.822092ms","start":"2026-07-03T15:38:13.985826Z","end":"2026-07-03T15:38:14.919648Z","steps":["trace[1700735411] 'read index received' (duration: 903.540769ms)","trace[1700735411] 'applied index is now lower than readState.Index' (duration: 30.280343ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T15:38:14.919776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.939157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T15:38:14.919806Z","caller":"traceutil/trace.go:171","msg":"trace[943629817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"934.00301ms","start":"2026-07-03T15:38:13.985795Z","end":"2026-07-03T15:38:14.919798Z","steps":["trace[943629817] 'agreement among raft nodes before linearized reading' (duration: 933.929627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:14.91983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:13.985778Z","time spent":"934.044372ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-03T15:38:14.919902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:13.901366Z","time spent":"1.018298499s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T15:38:14.928343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.48027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T15:38:14.928394Z","caller":"traceutil/trace.go:171","msg":"trace[1516546536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:544; }","duration":"850.574574ms","start":"2026-07-03T15:38:14.077802Z","end":"2026-07-03T15:38:14.928377Z","steps":["trace[1516546536] 'agreement among raft nodes before linearized reading' (duration: 850.448679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:14.928413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:14.077745Z","time spent":"850.664207ms","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T15:38:14.928344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.047487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-03T15:38:14.928574Z","caller":"traceutil/trace.go:171","msg":"trace[404909899] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:544; }","duration":"359.354468ms","start":"2026-07-03T15:38:14.569212Z","end":"2026-07-03T15:38:14.928567Z","steps":["trace[404909899] 'agreement among raft nodes before linearized reading' (duration: 358.987204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:14.928596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:14.569145Z","time spent":"359.445171ms","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-03T15:38:16.413775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.201282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T15:38:16.414232Z","caller":"traceutil/trace.go:171","msg":"trace[513220146] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"179.513475ms","start":"2026-07-03T15:38:16.234693Z","end":"2026-07-03T15:38:16.414206Z","steps":["trace[513220146] 'process raft request' (duration: 40.800674ms)","trace[513220146] 'compare' (duration: 138.009584ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:38:18.341184Z","caller":"traceutil/trace.go:171","msg":"trace[279894914] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"143.207678ms","start":"2026-07-03T15:38:18.197917Z","end":"2026-07-03T15:38:18.341125Z","steps":["trace[279894914] 'process raft request' (duration: 142.812433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:18.519556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.506682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T15:38:18.519644Z","caller":"traceutil/trace.go:171","msg":"trace[1884615911] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:613; }","duration":"120.647236ms","start":"2026-07-03T15:38:18.398977Z","end":"2026-07-03T15:38:18.519625Z","steps":["trace[1884615911] 'range keys from in-memory index tree' (duration: 120.373046ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:19.057462Z","caller":"traceutil/trace.go:171","msg":"trace[968432577] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"101.366956ms","start":"2026-07-03T15:38:18.956064Z","end":"2026-07-03T15:38:19.057431Z","steps":["trace[968432577] 'read index received' (duration: 99.011398ms)","trace[968432577] 'applied index is now lower than readState.Index' (duration: 2.353678ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:38:19.057488Z","caller":"traceutil/trace.go:171","msg":"trace[321613522] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"142.781209ms","start":"2026-07-03T15:38:18.914683Z","end":"2026-07-03T15:38:19.057464Z","steps":["trace[321613522] 'process raft request' (duration: 141.085435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:19.057612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.525082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-03T15:38:19.05764Z","caller":"traceutil/trace.go:171","msg":"trace[989155824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:614; }","duration":"101.604335ms","start":"2026-07-03T15:38:18.956028Z","end":"2026-07-03T15:38:19.057632Z","steps":["trace[989155824] 'agreement among raft nodes before linearized reading' (duration: 101.488271ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:19.20045Z","caller":"traceutil/trace.go:171","msg":"trace[209379400] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"136.453948ms","start":"2026-07-03T15:38:19.063955Z","end":"2026-07-03T15:38:19.200409Z","steps":["trace[209379400] 'process raft request' (duration: 131.388408ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:19.200461Z","caller":"traceutil/trace.go:171","msg":"trace[532856214] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"136.394876ms","start":"2026-07-03T15:38:19.064043Z","end":"2026-07-03T15:38:19.200438Z","steps":["trace[532856214] 'process raft request' (duration: 136.285422ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:19.200617Z","caller":"traceutil/trace.go:171","msg":"trace[1076573887] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"134.022717ms","start":"2026-07-03T15:38:19.066579Z","end":"2026-07-03T15:38:19.200601Z","steps":["trace[1076573887] 'process raft request' (duration: 133.792199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:22.214341Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592197465121222265,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-03T15:38:22.615285Z","caller":"traceutil/trace.go:171","msg":"trace[303723702] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"901.346076ms","start":"2026-07-03T15:38:21.713862Z","end":"2026-07-03T15:38:22.615208Z","steps":["trace[303723702] 'read index received' (duration: 898.605027ms)","trace[303723702] 'applied index is now lower than readState.Index' (duration: 2.739429ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:38:22.617563Z","caller":"traceutil/trace.go:171","msg":"trace[377854014] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"985.759083ms","start":"2026-07-03T15:38:21.63176Z","end":"2026-07-03T15:38:22.617519Z","steps":["trace[377854014] 'process raft request' (duration: 980.858626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:22.617714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.813405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t99md\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-03T15:38:22.61779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:21.631723Z","time spent":"985.94505ms","remote":"127.0.0.1:54960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T15:38:22.617882Z","caller":"traceutil/trace.go:171","msg":"trace[66924226] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t99md; range_end:; response_count:1; response_revision:637; }","duration":"903.96561ms","start":"2026-07-03T15:38:21.713824Z","end":"2026-07-03T15:38:22.61779Z","steps":["trace[66924226] 'agreement among raft nodes before linearized reading' (duration: 903.67837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:22.617917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:21.713801Z","time spent":"904.106225ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-t99md\" "} {"level":"warn","ts":"2026-07-03T15:38:22.625617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.202327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T15:38:22.625689Z","caller":"traceutil/trace.go:171","msg":"trace[1672156780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"638.336982ms","start":"2026-07-03T15:38:21.987336Z","end":"2026-07-03T15:38:22.625673Z","steps":["trace[1672156780] 'agreement among raft nodes before linearized reading' (duration: 638.214058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:22.62572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:21.987305Z","time spent":"638.409205ms","remote":"127.0.0.1:54898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-03T15:38:22.625904Z","caller":"traceutil/trace.go:171","msg":"trace[496070699] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"133.115128ms","start":"2026-07-03T15:38:22.49277Z","end":"2026-07-03T15:38:22.625886Z","steps":["trace[496070699] 'process raft request' (duration: 132.680472ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:23.417425Z","caller":"traceutil/trace.go:171","msg":"trace[691010855] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"778.016218ms","start":"2026-07-03T15:38:22.639366Z","end":"2026-07-03T15:38:23.417382Z","steps":["trace[691010855] 'process raft request' (duration: 777.716768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:23.417714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:22.639342Z","time spent":"778.195105ms","remote":"127.0.0.1:55046","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-07-03T15:38:23.454725Z","caller":"traceutil/trace.go:171","msg":"trace[1692751266] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:657; }","duration":"467.026137ms","start":"2026-07-03T15:38:22.987678Z","end":"2026-07-03T15:38:23.454704Z","steps":["trace[1692751266] 'read index received' (duration: 429.387069ms)","trace[1692751266] 'applied index is now lower than readState.Index' (duration: 37.638178ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:38:23.454859Z","caller":"traceutil/trace.go:171","msg":"trace[1431049656] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"755.82179ms","start":"2026-07-03T15:38:22.698986Z","end":"2026-07-03T15:38:23.454808Z","steps":["trace[1431049656] 'process raft request' (duration: 755.597452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:23.455029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.187973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-03T15:38:23.455065Z","caller":"traceutil/trace.go:171","msg":"trace[1900063536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"233.289677ms","start":"2026-07-03T15:38:23.221763Z","end":"2026-07-03T15:38:23.455053Z","steps":["trace[1900063536] 'agreement among raft nodes before linearized reading' (duration: 233.174153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:23.455081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:22.698967Z","time spent":"756.008387ms","remote":"127.0.0.1:55338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T15:38:23.455182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.256235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-03T15:38:23.455323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.625313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-03T15:38:23.455412Z","caller":"traceutil/trace.go:171","msg":"trace[782193298] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"239.754788ms","start":"2026-07-03T15:38:23.215635Z","end":"2026-07-03T15:38:23.455389Z","steps":["trace[782193298] 'agreement among raft nodes before linearized reading' (duration: 239.262981ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:23.455421Z","caller":"traceutil/trace.go:171","msg":"trace[537743138] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"233.428072ms","start":"2026-07-03T15:38:23.221842Z","end":"2026-07-03T15:38:23.45527Z","steps":["trace[537743138] 'agreement among raft nodes before linearized reading' (duration: 233.156252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:23.455183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.454134ms","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-07-03T15:38:23.455564Z","caller":"traceutil/trace.go:171","msg":"trace[486414594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"235.869179ms","start":"2026-07-03T15:38:23.219669Z","end":"2026-07-03T15:38:23.455538Z","steps":["trace[486414594] 'agreement among raft nodes before linearized reading' (duration: 235.276138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:23.454903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.210813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T15:38:23.455665Z","caller":"traceutil/trace.go:171","msg":"trace[686335794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"467.989851ms","start":"2026-07-03T15:38:22.98766Z","end":"2026-07-03T15:38:23.45565Z","steps":["trace[686335794] 'agreement among raft nodes before linearized reading' (duration: 467.14949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:23.455729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:22.987602Z","time spent":"468.104245ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-03T15:38:25.488774Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592197465121222279,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T15:38:25.989193Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592197465121222279,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T15:38:26.490084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592197465121222279,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T15:38:26.988232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999912345s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-03T15:38:26.98829Z","caller":"traceutil/trace.go:171","msg":"trace[993863041] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00005371s","start":"2026-07-03T15:38:24.988223Z","end":"2026-07-03T15:38:26.988277Z","steps":["trace[993863041] 'agreement among raft nodes before linearized reading' (duration: 1.999967027s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:26.988318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:24.988184Z","time spent":"2.000129352s","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/03 15:38:26 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-03T15:38:26.990442Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592197465121222279,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T15:38:27.15077Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.228701469s","expected-duration":"1s"} {"level":"info","ts":"2026-07-03T15:38:27.151995Z","caller":"traceutil/trace.go:171","msg":"trace[923993479] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"3.231390543s","start":"2026-07-03T15:38:23.920584Z","end":"2026-07-03T15:38:27.151974Z","steps":["trace[923993479] 'process raft request' (duration: 3.230841025s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:27.152132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:23.920558Z","time spent":"3.231504437s","remote":"127.0.0.1:54960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T15:38:27.182022Z","caller":"traceutil/trace.go:171","msg":"trace[89559396] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:663; }","duration":"2.193702662s","start":"2026-07-03T15:38:24.988296Z","end":"2026-07-03T15:38:27.181999Z","steps":["trace[89559396] 'read index received' (duration: 2.162884282s)","trace[89559396] 'applied index is now lower than readState.Index' (duration: 30.81722ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T15:38:27.182311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.700552264s","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-07-03T15:38:27.182356Z","caller":"traceutil/trace.go:171","msg":"trace[334107140] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:645; }","duration":"1.700649807s","start":"2026-07-03T15:38:25.481695Z","end":"2026-07-03T15:38:27.182345Z","steps":["trace[334107140] 'agreement among raft nodes before linearized reading' (duration: 1.700491751s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:27.182396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:25.481672Z","time spent":"1.700715009s","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-03T15:38:27.182614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.001404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T15:38:27.182649Z","caller":"traceutil/trace.go:171","msg":"trace[1748705788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"194.071497ms","start":"2026-07-03T15:38:26.98857Z","end":"2026-07-03T15:38:27.182641Z","steps":["trace[1748705788] 'agreement among raft nodes before linearized reading' (duration: 194.019465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:27.182836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098310121s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T15:38:27.182905Z","caller":"traceutil/trace.go:171","msg":"trace[1130853913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"1.098402344s","start":"2026-07-03T15:38:26.084492Z","end":"2026-07-03T15:38:27.182895Z","steps":["trace[1130853913] 'agreement among raft nodes before linearized reading' (duration: 1.098317381s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:27.182988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:26.084479Z","time spent":"1.098498667s","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-03T15:38:27.183279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.687882307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-03T15:38:27.183316Z","caller":"traceutil/trace.go:171","msg":"trace[1598851673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:645; }","duration":"1.687937319s","start":"2026-07-03T15:38:25.49537Z","end":"2026-07-03T15:38:27.183308Z","steps":["trace[1598851673] 'agreement among raft nodes before linearized reading' (duration: 1.687844296s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:27.183347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:25.495362Z","time spent":"1.68797666s","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T15:38:27.183582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.68881029s","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-07-03T15:38:27.183618Z","caller":"traceutil/trace.go:171","msg":"trace[677181675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:645; }","duration":"1.688874373s","start":"2026-07-03T15:38:25.494735Z","end":"2026-07-03T15:38:27.183609Z","steps":["trace[677181675] 'agreement among raft nodes before linearized reading' (duration: 1.688780429s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:27.183647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:25.494716Z","time spent":"1.688925515s","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T15:38:27.18383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.690971435s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-03T15:38:27.183866Z","caller":"traceutil/trace.go:171","msg":"trace[384090894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"1.691037027s","start":"2026-07-03T15:38:25.492821Z","end":"2026-07-03T15:38:27.183858Z","steps":["trace[384090894] 'agreement among raft nodes before linearized reading' (duration: 1.690938344s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:27.183895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:25.492803Z","time spent":"1.691085429s","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-03T15:38:29.48752Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592197465121222304,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T15:38:30.705783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.589944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-03T15:38:30.705868Z","caller":"traceutil/trace.go:171","msg":"trace[699326493] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.807722578s","start":"2026-07-03T15:38:28.898132Z","end":"2026-07-03T15:38:30.705855Z","steps":["trace[699326493] 'process raft request' (duration: 812.01178ms)","trace[699326493] 'compare' (duration: 995.369946ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T15:38:30.705908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:28.898092Z","time spent":"1.807797751s","remote":"127.0.0.1:54948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T15:38:30.74286Z","caller":"traceutil/trace.go:171","msg":"trace[131760844] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:684; }","duration":"1.756395606s","start":"2026-07-03T15:38:28.986442Z","end":"2026-07-03T15:38:30.742838Z","steps":["trace[131760844] 'read index received' (duration: 723.715086ms)","trace[131760844] 'applied index is now lower than readState.Index' (duration: 1.03267964s)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:38:30.742962Z","caller":"traceutil/trace.go:171","msg":"trace[945684046] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"1.606133208s","start":"2026-07-03T15:38:29.136804Z","end":"2026-07-03T15:38:30.742937Z","steps":["trace[945684046] 'process raft request' (duration: 1.605912661s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:30.74301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.756550811s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T15:38:30.743048Z","caller":"traceutil/trace.go:171","msg":"trace[1110777767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:666; }","duration":"1.756644335s","start":"2026-07-03T15:38:28.986393Z","end":"2026-07-03T15:38:30.743038Z","steps":["trace[1110777767] 'agreement among raft nodes before linearized reading' (duration: 1.756573162s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:30.743079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:29.136776Z","time spent":"1.606241932s","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T15:38:30.743094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:28.986372Z","time spent":"1.756711346s","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-03T15:38:30.743245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.547605267s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T15:38:30.743267Z","caller":"traceutil/trace.go:171","msg":"trace[1844865697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:666; }","duration":"1.547646369s","start":"2026-07-03T15:38:29.195615Z","end":"2026-07-03T15:38:30.743261Z","steps":["trace[1844865697] 'agreement among raft nodes before linearized reading' (duration: 1.547547636s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:30.743286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:29.195605Z","time spent":"1.54767748s","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T15:38:30.743257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.548781466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-03T15:38:30.743452Z","caller":"traceutil/trace.go:171","msg":"trace[1110114970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:666; }","duration":"1.549005494s","start":"2026-07-03T15:38:29.194433Z","end":"2026-07-03T15:38:30.743438Z","steps":["trace[1110114970] 'agreement among raft nodes before linearized reading' (duration: 1.548705024s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:30.743562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:29.194414Z","time spent":"1.549134168s","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-03T15:38:30.74398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.414313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.163\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-03T15:38:30.744027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.549425517s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-03T15:38:30.744057Z","caller":"traceutil/trace.go:171","msg":"trace[2056706583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:666; }","duration":"1.549564722s","start":"2026-07-03T15:38:29.194484Z","end":"2026-07-03T15:38:30.744049Z","steps":["trace[2056706583] 'agreement among raft nodes before linearized reading' (duration: 1.54890246s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:30.744082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:29.194454Z","time spent":"1.549622284s","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-03T15:38:30.744035Z","caller":"traceutil/trace.go:171","msg":"trace[1983293531] range","detail":"{range_begin:/registry/masterleases/199.19.213.163; range_end:; response_count:1; response_revision:666; }","duration":"704.472445ms","start":"2026-07-03T15:38:30.039551Z","end":"2026-07-03T15:38:30.744023Z","steps":["trace[1983293531] 'agreement among raft nodes before linearized reading' (duration: 703.672699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:30.744172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:30.039507Z","time spent":"704.653362ms","remote":"127.0.0.1:54908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.163\" "} {"level":"warn","ts":"2026-07-03T15:38:30.744001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.54891393s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-03T15:38:30.744271Z","caller":"traceutil/trace.go:171","msg":"trace[642881786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:666; }","duration":"1.549183799s","start":"2026-07-03T15:38:29.19508Z","end":"2026-07-03T15:38:30.744264Z","steps":["trace[642881786] 'agreement among raft nodes before linearized reading' (duration: 1.548250008s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:30.744302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:29.195046Z","time spent":"1.549242881s","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T15:38:30.74614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.618286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t99md\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-07-03T15:38:30.749043Z","caller":"traceutil/trace.go:171","msg":"trace[1717582083] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t99md; range_end:; response_count:1; response_revision:666; }","duration":"975.424698ms","start":"2026-07-03T15:38:29.773585Z","end":"2026-07-03T15:38:30.749009Z","steps":["trace[1717582083] 'agreement among raft nodes before linearized reading' (duration: 969.751239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:30.749184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:29.773566Z","time spent":"975.50747ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17547,"request content":"key:\"/registry/pods/kube-system/cilium-t99md\" "} {"level":"info","ts":"2026-07-03T15:38:30.905922Z","caller":"traceutil/trace.go:171","msg":"trace[35309685] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"156.570862ms","start":"2026-07-03T15:38:30.749324Z","end":"2026-07-03T15:38:30.905895Z","steps":["trace[35309685] 'process raft request' (duration: 102.161068ms)","trace[35309685] 'compare' (duration: 54.210097ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:38:30.9062Z","caller":"traceutil/trace.go:171","msg":"trace[1050007909] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"156.836111ms","start":"2026-07-03T15:38:30.749332Z","end":"2026-07-03T15:38:30.906168Z","steps":["trace[1050007909] 'process raft request' (duration: 156.520531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:31.309254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.305246ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T15:38:31.309488Z","caller":"traceutil/trace.go:171","msg":"trace[2093952270] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"560.079509ms","start":"2026-07-03T15:38:30.749378Z","end":"2026-07-03T15:38:31.309457Z","steps":["trace[2093952270] 'process raft request' (duration: 421.486573ms)","trace[2093952270] 'compare' (duration: 138.054058ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:38:31.309596Z","caller":"traceutil/trace.go:171","msg":"trace[1945677945] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:686; }","duration":"552.119274ms","start":"2026-07-03T15:38:30.75746Z","end":"2026-07-03T15:38:31.309579Z","steps":["trace[1945677945] 'read index received' (duration: 94.1205ms)","trace[1945677945] 'applied index is now lower than readState.Index' (duration: 457.997744ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T15:38:31.309655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:30.749345Z","time spent":"560.218413ms","remote":"127.0.0.1:55094","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-07-03T15:38:31.3097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.238587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T15:38:31.309741Z","caller":"traceutil/trace.go:171","msg":"trace[1465375882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:671; }","duration":"552.31518ms","start":"2026-07-03T15:38:30.757411Z","end":"2026-07-03T15:38:31.309727Z","steps":["trace[1465375882] 'agreement among raft nodes before linearized reading' (duration: 552.235207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:31.309783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:30.757398Z","time spent":"552.372852ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-03T15:38:31.310047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:30.751015Z","time spent":"559.030153ms","remote":"127.0.0.1:54908","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-03T15:38:31.310159Z","caller":"traceutil/trace.go:171","msg":"trace[1943207807] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"549.300419ms","start":"2026-07-03T15:38:30.760803Z","end":"2026-07-03T15:38:31.310104Z","steps":["trace[1943207807] 'process raft request' (duration: 548.72766ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:31.310153Z","caller":"traceutil/trace.go:171","msg":"trace[935782456] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"555.558228ms","start":"2026-07-03T15:38:30.754573Z","end":"2026-07-03T15:38:31.310131Z","steps":["trace[935782456] 'process raft request' (duration: 554.871905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:31.310259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:30.760794Z","time spent":"549.418443ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17693,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T15:38:31.31028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:30.754551Z","time spent":"555.669032ms","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T15:38:31.375566Z","caller":"traceutil/trace.go:171","msg":"trace[932533559] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"427.375121ms","start":"2026-07-03T15:38:30.948168Z","end":"2026-07-03T15:38:31.375543Z","steps":["trace[932533559] 'process raft request' (duration: 427.215266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:31.37571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:30.948149Z","time spent":"427.496076ms","remote":"127.0.0.1:54948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":765,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T15:38:31.567294Z","caller":"traceutil/trace.go:171","msg":"trace[1190347997] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:692; }","duration":"252.742555ms","start":"2026-07-03T15:38:31.314529Z","end":"2026-07-03T15:38:31.567271Z","steps":["trace[1190347997] 'read index received' (duration: 60.88379ms)","trace[1190347997] 'applied index is now lower than readState.Index' (duration: 191.857785ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:38:31.567314Z","caller":"traceutil/trace.go:171","msg":"trace[1139714622] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"256.017073ms","start":"2026-07-03T15:38:31.31127Z","end":"2026-07-03T15:38:31.567287Z","steps":["trace[1139714622] 'process raft request' (duration: 159.179626ms)","trace[1139714622] 'compare' (duration: 96.705563ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T15:38:31.567488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.940722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t99md\" ","response":"range_response_count:1 size:17708"} {"level":"info","ts":"2026-07-03T15:38:31.56752Z","caller":"traceutil/trace.go:171","msg":"trace[308825534] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t99md; range_end:; response_count:1; response_revision:673; }","duration":"253.016865ms","start":"2026-07-03T15:38:31.314495Z","end":"2026-07-03T15:38:31.567512Z","steps":["trace[308825534] 'agreement among raft nodes before linearized reading' (duration: 252.852119ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:31.705486Z","caller":"traceutil/trace.go:171","msg":"trace[1787308112] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"125.141651ms","start":"2026-07-03T15:38:31.580323Z","end":"2026-07-03T15:38:31.705465Z","steps":["trace[1787308112] 'process raft request' (duration: 124.909104ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:33.717489Z","caller":"traceutil/trace.go:171","msg":"trace[695662875] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"120.401259ms","start":"2026-07-03T15:38:33.597063Z","end":"2026-07-03T15:38:33.717464Z","steps":["trace[695662875] 'process raft request' (duration: 120.269964ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:33.832823Z","caller":"traceutil/trace.go:171","msg":"trace[1529599293] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"107.348536ms","start":"2026-07-03T15:38:33.725446Z","end":"2026-07-03T15:38:33.832795Z","steps":["trace[1529599293] 'process raft request' (duration: 106.85371ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:41.571537Z","caller":"traceutil/trace.go:171","msg":"trace[730905599] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"222.019036ms","start":"2026-07-03T15:38:41.349497Z","end":"2026-07-03T15:38:41.571516Z","steps":["trace[730905599] 'read index received' (duration: 221.769928ms)","trace[730905599] 'applied index is now lower than readState.Index' (duration: 247.938µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:38:41.571663Z","caller":"traceutil/trace.go:171","msg":"trace[929294592] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"508.822624ms","start":"2026-07-03T15:38:41.06283Z","end":"2026-07-03T15:38:41.571653Z","steps":["trace[929294592] 'process raft request' (duration: 508.477704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:41.571737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.229752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-03T15:38:41.572051Z","caller":"traceutil/trace.go:171","msg":"trace[1610385267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:792; }","duration":"222.580522ms","start":"2026-07-03T15:38:41.349457Z","end":"2026-07-03T15:38:41.572037Z","steps":["trace[1610385267] 'agreement among raft nodes before linearized reading' (duration: 222.16989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:41.571772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:41.062808Z","time spent":"508.904287ms","remote":"127.0.0.1:55300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T15:38:41.571836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.583713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-03T15:38:41.572216Z","caller":"traceutil/trace.go:171","msg":"trace[79932581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:792; }","duration":"221.981765ms","start":"2026-07-03T15:38:41.35021Z","end":"2026-07-03T15:38:41.572192Z","steps":["trace[79932581] 'agreement among raft nodes before linearized reading' (duration: 221.587813ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:43.773797Z","caller":"traceutil/trace.go:171","msg":"trace[550468365] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"182.273614ms","start":"2026-07-03T15:38:43.591504Z","end":"2026-07-03T15:38:43.773778Z","steps":["trace[550468365] 'process raft request' (duration: 182.15038ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:43.775039Z","caller":"traceutil/trace.go:171","msg":"trace[788774259] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"183.266303ms","start":"2026-07-03T15:38:43.591742Z","end":"2026-07-03T15:38:43.775009Z","steps":["trace[788774259] 'process raft request' (duration: 183.125329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:45.066578Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592197465121222610,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T15:38:45.566986Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592197465121222610,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T15:38:45.755651Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.722729601s","expected-duration":"1s"} {"level":"info","ts":"2026-07-03T15:38:45.756114Z","caller":"traceutil/trace.go:171","msg":"trace[518721896] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"1.723263515s","start":"2026-07-03T15:38:44.032829Z","end":"2026-07-03T15:38:45.756092Z","steps":["trace[518721896] 'process raft request' (duration: 1.723095041s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:45.756273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:44.032811Z","time spent":"1.723376669s","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T15:38:45.756536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190902024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T15:38:45.756641Z","caller":"traceutil/trace.go:171","msg":"trace[1312895018] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:811; }","duration":"1.191064368s","start":"2026-07-03T15:38:44.565546Z","end":"2026-07-03T15:38:45.75661Z","steps":["trace[1312895018] 'agreement among raft nodes before linearized reading' (duration: 1.190845872s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:45.75669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:44.565528Z","time spent":"1.19115112s","remote":"127.0.0.1:55246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-07-03T15:38:45.756559Z","caller":"traceutil/trace.go:171","msg":"trace[1425506624] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:838; }","duration":"1.190493901s","start":"2026-07-03T15:38:44.565594Z","end":"2026-07-03T15:38:45.756088Z","steps":["trace[1425506624] 'read index received' (duration: 1.190486181s)","trace[1425506624] 'applied index is now lower than readState.Index' (duration: 6.22µs)"],"step_count":2} {"level":"warn","ts":"2026-07-03T15:38:45.757057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181492329s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T15:38:45.757088Z","caller":"traceutil/trace.go:171","msg":"trace[1916435503] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:811; }","duration":"1.181569391s","start":"2026-07-03T15:38:44.575509Z","end":"2026-07-03T15:38:45.757079Z","steps":["trace[1916435503] 'agreement among raft nodes before linearized reading' (duration: 1.181511709s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:45.757144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:44.575477Z","time spent":"1.181659233s","remote":"127.0.0.1:55034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-07-03T15:38:45.757379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.786532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T15:38:45.757408Z","caller":"traceutil/trace.go:171","msg":"trace[93350929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:811; }","duration":"784.846094ms","start":"2026-07-03T15:38:44.972553Z","end":"2026-07-03T15:38:45.757399Z","steps":["trace[93350929] 'agreement among raft nodes before linearized reading' (duration: 784.72836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:45.757433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:44.972534Z","time spent":"784.893446ms","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T15:38:45.757631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.374836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-03T15:38:45.757683Z","caller":"traceutil/trace.go:171","msg":"trace[487682852] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:811; }","duration":"790.480259ms","start":"2026-07-03T15:38:44.967188Z","end":"2026-07-03T15:38:45.757668Z","steps":["trace[487682852] 'agreement among raft nodes before linearized reading' (duration: 790.326195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:45.757717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:44.967148Z","time spent":"790.560062ms","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-03T15:38:57.215518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.135362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-03T15:38:57.215634Z","caller":"traceutil/trace.go:171","msg":"trace[415569828] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"219.455411ms","start":"2026-07-03T15:38:56.996157Z","end":"2026-07-03T15:38:57.215612Z","steps":["trace[415569828] 'process raft request' (duration: 33.150184ms)","trace[415569828] 'compare' (duration: 185.840234ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:38:57.216377Z","caller":"traceutil/trace.go:171","msg":"trace[2023043559] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"219.648305ms","start":"2026-07-03T15:38:56.996717Z","end":"2026-07-03T15:38:57.216366Z","steps":["trace[2023043559] 'process raft request' (duration: 219.4389ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:57.216358Z","caller":"traceutil/trace.go:171","msg":"trace[1589672786] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"213.75636ms","start":"2026-07-03T15:38:57.002571Z","end":"2026-07-03T15:38:57.216327Z","steps":["trace[1589672786] 'process raft request' (duration: 213.698039ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:57.45457Z","caller":"traceutil/trace.go:171","msg":"trace[1476407515] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"197.756974ms","start":"2026-07-03T15:38:57.256786Z","end":"2026-07-03T15:38:57.454543Z","steps":["trace[1476407515] 'process raft request' (duration: 128.757449ms)","trace[1476407515] 'compare' (duration: 68.868901ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:38:57.909662Z","caller":"traceutil/trace.go:171","msg":"trace[2005343693] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"199.421816ms","start":"2026-07-03T15:38:57.710214Z","end":"2026-07-03T15:38:57.909635Z","steps":["trace[2005343693] 'process raft request' (duration: 177.192868ms)","trace[2005343693] 'compare' (duration: 21.819648ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:38:57.950281Z","caller":"traceutil/trace.go:171","msg":"trace[908876549] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"233.895304ms","start":"2026-07-03T15:38:57.716357Z","end":"2026-07-03T15:38:57.950252Z","steps":["trace[908876549] 'process raft request' (duration: 233.710999ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:58.181152Z","caller":"traceutil/trace.go:171","msg":"trace[139698671] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"266.430132ms","start":"2026-07-03T15:38:57.91466Z","end":"2026-07-03T15:38:58.18109Z","steps":["trace[139698671] 'process raft request' (duration: 241.355815ms)","trace[139698671] 'compare' (duration: 24.854861ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:38:58.181769Z","caller":"traceutil/trace.go:171","msg":"trace[967363935] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"265.917469ms","start":"2026-07-03T15:38:57.915805Z","end":"2026-07-03T15:38:58.181723Z","steps":["trace[967363935] 'process raft request' (duration: 265.2339ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:58.181667Z","caller":"traceutil/trace.go:171","msg":"trace[1240125072] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:974; }","duration":"266.637548ms","start":"2026-07-03T15:38:57.915015Z","end":"2026-07-03T15:38:58.181653Z","steps":["trace[1240125072] 'read index received' (duration: 35.160025ms)","trace[1240125072] 'applied index is now lower than readState.Index' (duration: 231.476503ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T15:38:58.181997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.958406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T15:38:58.182077Z","caller":"traceutil/trace.go:171","msg":"trace[841479035] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:947; }","duration":"267.078769ms","start":"2026-07-03T15:38:57.914972Z","end":"2026-07-03T15:38:58.182051Z","steps":["trace[841479035] 'agreement among raft nodes before linearized reading' (duration: 266.937685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:58.182098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.036834ms","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-07-03T15:38:58.182135Z","caller":"traceutil/trace.go:171","msg":"trace[132166388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:947; }","duration":"104.097715ms","start":"2026-07-03T15:38:58.078025Z","end":"2026-07-03T15:38:58.182123Z","steps":["trace[132166388] 'agreement among raft nodes before linearized reading' (duration: 103.978182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:58.18217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.531808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-03T15:38:58.182254Z","caller":"traceutil/trace.go:171","msg":"trace[299382576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:947; }","duration":"120.652252ms","start":"2026-07-03T15:38:58.061584Z","end":"2026-07-03T15:38:58.182236Z","steps":["trace[299382576] 'agreement among raft nodes before linearized reading' (duration: 120.440196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:58.182254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.063453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-03T15:38:58.182256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.296018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-07-03T15:38:58.182314Z","caller":"traceutil/trace.go:171","msg":"trace[1153444607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:947; }","duration":"114.167425ms","start":"2026-07-03T15:38:58.06813Z","end":"2026-07-03T15:38:58.182298Z","steps":["trace[1153444607] 'agreement among raft nodes before linearized reading' (duration: 114.008901ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:58.182323Z","caller":"traceutil/trace.go:171","msg":"trace[1755265649] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:947; }","duration":"164.40312ms","start":"2026-07-03T15:38:58.017902Z","end":"2026-07-03T15:38:58.182305Z","steps":["trace[1755265649] 'agreement among raft nodes before linearized reading' (duration: 164.233576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:58.18239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.737866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-03T15:38:58.182431Z","caller":"traceutil/trace.go:171","msg":"trace[1731353616] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:947; }","duration":"103.827508ms","start":"2026-07-03T15:38:58.078593Z","end":"2026-07-03T15:38:58.182421Z","steps":["trace[1731353616] 'agreement among raft nodes before linearized reading' (duration: 103.721226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:58.1825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.423603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T15:38:58.182542Z","caller":"traceutil/trace.go:171","msg":"trace[219728703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:947; }","duration":"198.506015ms","start":"2026-07-03T15:38:57.984023Z","end":"2026-07-03T15:38:58.182529Z","steps":["trace[219728703] 'agreement among raft nodes before linearized reading' (duration: 198.420603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:58.182588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.238882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T15:38:58.182616Z","caller":"traceutil/trace.go:171","msg":"trace[804038626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:947; }","duration":"267.283794ms","start":"2026-07-03T15:38:57.915323Z","end":"2026-07-03T15:38:58.182606Z","steps":["trace[804038626] 'agreement among raft nodes before linearized reading' (duration: 266.629947ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:58.395204Z","caller":"traceutil/trace.go:171","msg":"trace[1206533899] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"207.392409ms","start":"2026-07-03T15:38:58.187779Z","end":"2026-07-03T15:38:58.395171Z","steps":["trace[1206533899] 'process raft request' (duration: 127.825986ms)","trace[1206533899] 'compare' (duration: 79.306766ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:38:58.395686Z","caller":"traceutil/trace.go:171","msg":"trace[181555838] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"207.811271ms","start":"2026-07-03T15:38:58.18785Z","end":"2026-07-03T15:38:58.395661Z","steps":["trace[181555838] 'process raft request' (duration: 207.215685ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:58.396034Z","caller":"traceutil/trace.go:171","msg":"trace[1798963156] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"208.127629ms","start":"2026-07-03T15:38:58.187885Z","end":"2026-07-03T15:38:58.396013Z","steps":["trace[1798963156] 'process raft request' (duration: 207.468442ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:58.396641Z","caller":"traceutil/trace.go:171","msg":"trace[1169033877] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:977; }","duration":"208.555049ms","start":"2026-07-03T15:38:58.188064Z","end":"2026-07-03T15:38:58.396619Z","steps":["trace[1169033877] 'read index received' (duration: 77.371535ms)","trace[1169033877] 'applied index is now lower than readState.Index' (duration: 131.182214ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:38:58.396907Z","caller":"traceutil/trace.go:171","msg":"trace[802687541] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"155.646874ms","start":"2026-07-03T15:38:58.241245Z","end":"2026-07-03T15:38:58.396892Z","steps":["trace[802687541] 'process raft request' (duration: 155.327786ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:58.396962Z","caller":"traceutil/trace.go:171","msg":"trace[1994134222] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"207.765489ms","start":"2026-07-03T15:38:58.18917Z","end":"2026-07-03T15:38:58.396935Z","steps":["trace[1994134222] 'process raft request' (duration: 207.350089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:58.397255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.167395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3386"} {"level":"info","ts":"2026-07-03T15:38:58.39752Z","caller":"traceutil/trace.go:171","msg":"trace[2089818174] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"209.441623ms","start":"2026-07-03T15:38:58.188061Z","end":"2026-07-03T15:38:58.397502Z","steps":["trace[2089818174] 'process raft request' (duration: 207.519323ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:58.397318Z","caller":"traceutil/trace.go:171","msg":"trace[1643930513] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:954; }","duration":"209.268427ms","start":"2026-07-03T15:38:58.188033Z","end":"2026-07-03T15:38:58.397301Z","steps":["trace[1643930513] 'agreement among raft nodes before linearized reading' (duration: 209.050932ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:58.525594Z","caller":"traceutil/trace.go:171","msg":"trace[172330145] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"255.769397ms","start":"2026-07-03T15:38:58.269799Z","end":"2026-07-03T15:38:58.525569Z","steps":["trace[172330145] 'process raft request' (duration: 255.186482ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:58.525781Z","caller":"traceutil/trace.go:171","msg":"trace[1389655964] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:984; }","duration":"129.054339ms","start":"2026-07-03T15:38:58.3967Z","end":"2026-07-03T15:38:58.525754Z","steps":["trace[1389655964] 'read index received' (duration: 128.30048ms)","trace[1389655964] 'applied index is now lower than readState.Index' (duration: 752.729µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:38:58.525836Z","caller":"traceutil/trace.go:171","msg":"trace[251223306] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"255.895141ms","start":"2026-07-03T15:38:58.269895Z","end":"2026-07-03T15:38:58.52579Z","steps":["trace[251223306] 'process raft request' (duration: 255.543612ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:58.525981Z","caller":"traceutil/trace.go:171","msg":"trace[136083299] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"255.251114ms","start":"2026-07-03T15:38:58.270718Z","end":"2026-07-03T15:38:58.525969Z","steps":["trace[136083299] 'process raft request' (duration: 254.816803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:58.526266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.79327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-07-03T15:38:58.526331Z","caller":"traceutil/trace.go:171","msg":"trace[1134412541] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:957; }","duration":"288.906683ms","start":"2026-07-03T15:38:58.237405Z","end":"2026-07-03T15:38:58.526311Z","steps":["trace[1134412541] 'agreement among raft nodes before linearized reading' (duration: 288.461181ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:58.528265Z","caller":"traceutil/trace.go:171","msg":"trace[655643667] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"124.953682ms","start":"2026-07-03T15:38:58.403296Z","end":"2026-07-03T15:38:58.52825Z","steps":["trace[655643667] 'process raft request' (duration: 124.87041ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:38:58.836106Z","caller":"traceutil/trace.go:171","msg":"trace[1705300598] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"301.908597ms","start":"2026-07-03T15:38:58.533778Z","end":"2026-07-03T15:38:58.835687Z","steps":["trace[1705300598] 'process raft request' (duration: 299.71402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:58.83627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:58.533764Z","time spent":"302.42181ms","remote":"127.0.0.1:55322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3249,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T15:38:58.865159Z","caller":"traceutil/trace.go:171","msg":"trace[1093321236] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"323.441082ms","start":"2026-07-03T15:38:58.541704Z","end":"2026-07-03T15:38:58.865145Z","steps":["trace[1093321236] 'process raft request' (duration: 323.289849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:38:58.86525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:38:58.54169Z","time spent":"323.510285ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3596,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T15:39:18.704871Z","caller":"traceutil/trace.go:171","msg":"trace[6371209] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"127.331075ms","start":"2026-07-03T15:39:18.577512Z","end":"2026-07-03T15:39:18.704843Z","steps":["trace[6371209] 'process raft request' (duration: 75.708043ms)","trace[6371209] 'compare' (duration: 51.224413ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:39:18.70491Z","caller":"traceutil/trace.go:171","msg":"trace[1741541997] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"127.319635ms","start":"2026-07-03T15:39:18.577571Z","end":"2026-07-03T15:39:18.70489Z","steps":["trace[1741541997] 'process raft request' (duration: 127.041849ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:39:18.705187Z","caller":"traceutil/trace.go:171","msg":"trace[467831911] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"127.389097ms","start":"2026-07-03T15:39:18.577789Z","end":"2026-07-03T15:39:18.705178Z","steps":["trace[467831911] 'process raft request' (duration: 126.862755ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:39:18.704982Z","caller":"traceutil/trace.go:171","msg":"trace[1706388220] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"126.516138ms","start":"2026-07-03T15:39:18.578431Z","end":"2026-07-03T15:39:18.704947Z","steps":["trace[1706388220] 'process raft request' (duration: 126.250312ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:39:19.344424Z","caller":"traceutil/trace.go:171","msg":"trace[428644835] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1086; }","duration":"120.691219ms","start":"2026-07-03T15:39:19.223711Z","end":"2026-07-03T15:39:19.344402Z","steps":["trace[428644835] 'read index received' (duration: 120.503715ms)","trace[428644835] 'applied index is now lower than readState.Index' (duration: 186.664µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:39:19.344552Z","caller":"traceutil/trace.go:171","msg":"trace[606463100] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1051; }","duration":"166.348282ms","start":"2026-07-03T15:39:19.178165Z","end":"2026-07-03T15:39:19.344514Z","steps":["trace[606463100] 'process raft request' (duration: 166.101616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:39:19.34457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.830052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T15:39:19.344619Z","caller":"traceutil/trace.go:171","msg":"trace[345477773] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1051; }","duration":"120.940745ms","start":"2026-07-03T15:39:19.223666Z","end":"2026-07-03T15:39:19.344607Z","steps":["trace[345477773] 'agreement among raft nodes before linearized reading' (duration: 120.826332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:39:20.879045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.854475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T15:39:20.879097Z","caller":"traceutil/trace.go:171","msg":"trace[72499939] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1055; }","duration":"198.964258ms","start":"2026-07-03T15:39:20.680121Z","end":"2026-07-03T15:39:20.879086Z","steps":["trace[72499939] 'count revisions from in-memory index tree' (duration: 198.752493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:39:20.879075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.652549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-03T15:39:20.879122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.022017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-03T15:39:20.879104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.495306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T15:39:20.879171Z","caller":"traceutil/trace.go:171","msg":"trace[1746399237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1055; }","duration":"169.754771ms","start":"2026-07-03T15:39:20.709399Z","end":"2026-07-03T15:39:20.879154Z","steps":["trace[1746399237] 'range keys from in-memory index tree' (duration: 169.417524ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:39:20.879177Z","caller":"traceutil/trace.go:171","msg":"trace[837420513] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1055; }","duration":"170.115548ms","start":"2026-07-03T15:39:20.709048Z","end":"2026-07-03T15:39:20.879163Z","steps":["trace[837420513] 'range keys from in-memory index tree' (duration: 169.853153ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:39:20.879193Z","caller":"traceutil/trace.go:171","msg":"trace[1828843303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1055; }","duration":"169.635909ms","start":"2026-07-03T15:39:20.709539Z","end":"2026-07-03T15:39:20.879175Z","steps":["trace[1828843303] 'range keys from in-memory index tree' (duration: 169.341912ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:39:20.879608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.818073ms","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-07-03T15:39:20.879657Z","caller":"traceutil/trace.go:171","msg":"trace[920915471] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1055; }","duration":"169.884444ms","start":"2026-07-03T15:39:20.709758Z","end":"2026-07-03T15:39:20.879642Z","steps":["trace[920915471] 'range keys from in-memory index tree' (duration: 169.570857ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:39:21.197412Z","caller":"traceutil/trace.go:171","msg":"trace[978029404] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"313.799762ms","start":"2026-07-03T15:39:20.883579Z","end":"2026-07-03T15:39:21.197379Z","steps":["trace[978029404] 'process raft request' (duration: 303.362457ms)","trace[978029404] 'compare' (duration: 10.259321ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:39:21.197447Z","caller":"traceutil/trace.go:171","msg":"trace[1357665505] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"312.626816ms","start":"2026-07-03T15:39:20.88478Z","end":"2026-07-03T15:39:21.197407Z","steps":["trace[1357665505] 'process raft request' (duration: 312.541524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:39:21.197521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:39:20.88357Z","time spent":"313.895494ms","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T15:39:21.197595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:39:20.884771Z","time spent":"312.744768ms","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T15:39:21.197615Z","caller":"traceutil/trace.go:171","msg":"trace[410249314] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1093; }","duration":"211.946441ms","start":"2026-07-03T15:39:20.985648Z","end":"2026-07-03T15:39:21.197594Z","steps":["trace[410249314] 'read index received' (duration: 201.022955ms)","trace[410249314] 'applied index is now lower than readState.Index' (duration: 10.921575ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T15:39:21.197732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.072433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T15:39:21.197688Z","caller":"traceutil/trace.go:171","msg":"trace[241741477] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"311.030481ms","start":"2026-07-03T15:39:20.88665Z","end":"2026-07-03T15:39:21.19768Z","steps":["trace[241741477] 'process raft request' (duration: 310.719785ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:39:21.19781Z","caller":"traceutil/trace.go:171","msg":"trace[1660563758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1059; }","duration":"212.150216ms","start":"2026-07-03T15:39:20.985642Z","end":"2026-07-03T15:39:21.197792Z","steps":["trace[1660563758] 'agreement among raft nodes before linearized reading' (duration: 212.034202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:39:21.197887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:39:20.886636Z","time spent":"311.206406ms","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T15:39:21.476245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.996603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T15:39:21.476358Z","caller":"traceutil/trace.go:171","msg":"trace[1245110221] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1059; }","duration":"160.178087ms","start":"2026-07-03T15:39:21.316154Z","end":"2026-07-03T15:39:21.476332Z","steps":["trace[1245110221] 'count revisions from in-memory index tree' (duration: 159.894812ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:39:22.800811Z","caller":"traceutil/trace.go:171","msg":"trace[86835866] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"118.828285ms","start":"2026-07-03T15:39:22.681952Z","end":"2026-07-03T15:39:22.800781Z","steps":["trace[86835866] 'process raft request' (duration: 57.921405ms)","trace[86835866] 'compare' (duration: 60.784278ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:39:23.056059Z","caller":"traceutil/trace.go:171","msg":"trace[393304656] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"121.69027ms","start":"2026-07-03T15:39:22.934336Z","end":"2026-07-03T15:39:23.056027Z","steps":["trace[393304656] 'process raft request' (duration: 121.443575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:39:23.317995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.026332ms","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-07-03T15:39:23.318048Z","caller":"traceutil/trace.go:171","msg":"trace[934008653] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1063; }","duration":"114.134985ms","start":"2026-07-03T15:39:23.203903Z","end":"2026-07-03T15:39:23.318038Z","steps":["trace[934008653] 'range keys from in-memory index tree' (duration: 113.88433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:39:23.31814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.855651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T15:39:23.318195Z","caller":"traceutil/trace.go:171","msg":"trace[1322610353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1063; }","duration":"115.958244ms","start":"2026-07-03T15:39:23.202226Z","end":"2026-07-03T15:39:23.318184Z","steps":["trace[1322610353] 'range keys from in-memory index tree' (duration: 115.720069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:39:23.318365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.144868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-03T15:39:23.31857Z","caller":"traceutil/trace.go:171","msg":"trace[1376717482] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1063; }","duration":"116.350543ms","start":"2026-07-03T15:39:23.202161Z","end":"2026-07-03T15:39:23.318511Z","steps":["trace[1376717482] 'range keys from in-memory index tree' (duration: 115.939433ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:39:23.431858Z","caller":"traceutil/trace.go:171","msg":"trace[337358895] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"110.271133ms","start":"2026-07-03T15:39:23.321568Z","end":"2026-07-03T15:39:23.431839Z","steps":["trace[337358895] 'process raft request' (duration: 110.12967ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:39:23.438371Z","caller":"traceutil/trace.go:171","msg":"trace[795287084] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"116.764822ms","start":"2026-07-03T15:39:23.321592Z","end":"2026-07-03T15:39:23.438357Z","steps":["trace[795287084] 'process raft request' (duration: 116.66248ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:39:23.438467Z","caller":"traceutil/trace.go:171","msg":"trace[816529515] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"116.365983ms","start":"2026-07-03T15:39:23.322077Z","end":"2026-07-03T15:39:23.438443Z","steps":["trace[816529515] 'process raft request' (duration: 116.254801ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:39:24.971405Z","caller":"traceutil/trace.go:171","msg":"trace[261218278] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"162.487547ms","start":"2026-07-03T15:39:24.808898Z","end":"2026-07-03T15:39:24.971386Z","steps":["trace[261218278] 'process raft request' (duration: 162.368054ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:39:48.949307Z","caller":"traceutil/trace.go:171","msg":"trace[1955608400] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"177.270275ms","start":"2026-07-03T15:39:48.772013Z","end":"2026-07-03T15:39:48.949284Z","steps":["trace[1955608400] 'process raft request' (duration: 177.126712ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:40:13.904758Z","caller":"traceutil/trace.go:171","msg":"trace[702929210] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"163.057154ms","start":"2026-07-03T15:40:13.741683Z","end":"2026-07-03T15:40:13.90474Z","steps":["trace[702929210] 'process raft request' (duration: 66.040809ms)","trace[702929210] 'compare' (duration: 96.880743ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:40:13.904967Z","caller":"traceutil/trace.go:171","msg":"trace[1448987020] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"163.104054ms","start":"2026-07-03T15:40:13.741839Z","end":"2026-07-03T15:40:13.904943Z","steps":["trace[1448987020] 'process raft request' (duration: 162.86272ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:40:31.84187Z","caller":"traceutil/trace.go:171","msg":"trace[2120417407] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"212.818728ms","start":"2026-07-03T15:40:31.628932Z","end":"2026-07-03T15:40:31.841751Z","steps":["trace[2120417407] 'process raft request' (duration: 185.810606ms)","trace[2120417407] 'compare' (duration: 26.449324ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:40:31.947496Z","caller":"traceutil/trace.go:171","msg":"trace[2040636928] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"101.194509ms","start":"2026-07-03T15:40:31.84627Z","end":"2026-07-03T15:40:31.947464Z","steps":["trace[2040636928] 'process raft request' (duration: 99.727246ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:40:32.327591Z","caller":"traceutil/trace.go:171","msg":"trace[1662973736] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1312; }","duration":"210.627695ms","start":"2026-07-03T15:40:32.116943Z","end":"2026-07-03T15:40:32.327571Z","steps":["trace[1662973736] 'process raft request' (duration: 144.954883ms)","trace[1662973736] 'compare' (duration: 65.479929ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:40:32.515747Z","caller":"traceutil/trace.go:171","msg":"trace[624484776] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1365; }","duration":"117.848598ms","start":"2026-07-03T15:40:32.39787Z","end":"2026-07-03T15:40:32.515718Z","steps":["trace[624484776] 'read index received' (duration: 114.266173ms)","trace[624484776] 'applied index is now lower than readState.Index' (duration: 3.577265ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T15:40:32.51599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.094251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c526b101-3762-41e4-9cd1-e1bc6650b6f0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T15:40:32.51604Z","caller":"traceutil/trace.go:171","msg":"trace[1082142201] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c526b101-3762-41e4-9cd1-e1bc6650b6f0; range_end:; response_count:0; response_revision:1313; }","duration":"118.202523ms","start":"2026-07-03T15:40:32.397824Z","end":"2026-07-03T15:40:32.516027Z","steps":["trace[1082142201] 'agreement among raft nodes before linearized reading' (duration: 118.055631ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:40:32.516016Z","caller":"traceutil/trace.go:171","msg":"trace[601039106] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"181.887995ms","start":"2026-07-03T15:40:32.333899Z","end":"2026-07-03T15:40:32.515787Z","steps":["trace[601039106] 'process raft request' (duration: 178.28769ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:40:32.690755Z","caller":"traceutil/trace.go:171","msg":"trace[1597933617] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"151.935038ms","start":"2026-07-03T15:40:32.538791Z","end":"2026-07-03T15:40:32.690726Z","steps":["trace[1597933617] 'process raft request' (duration: 151.044305ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:40:33.63249Z","caller":"traceutil/trace.go:171","msg":"trace[1845115201] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"125.723721ms","start":"2026-07-03T15:40:33.506751Z","end":"2026-07-03T15:40:33.632475Z","steps":["trace[1845115201] 'process raft request' (duration: 125.62798ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:40:33.73391Z","caller":"traceutil/trace.go:171","msg":"trace[942344887] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"105.438194ms","start":"2026-07-03T15:40:33.628425Z","end":"2026-07-03T15:40:33.733863Z","steps":["trace[942344887] 'process raft request' (duration: 92.535747ms)","trace[942344887] 'compare' (duration: 12.530382ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T15:40:44.480232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.1968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-03T15:40:44.480372Z","caller":"traceutil/trace.go:171","msg":"trace[427665354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1367; }","duration":"300.290651ms","start":"2026-07-03T15:40:44.180009Z","end":"2026-07-03T15:40:44.4803Z","steps":["trace[427665354] 'range keys from in-memory index tree' (duration: 300.040538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:40:44.480427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:40:44.179965Z","time spent":"300.447374ms","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T15:40:44.480923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.51158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-03T15:40:44.481067Z","caller":"traceutil/trace.go:171","msg":"trace[2047824708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1367; }","duration":"301.701633ms","start":"2026-07-03T15:40:44.179343Z","end":"2026-07-03T15:40:44.481044Z","steps":["trace[2047824708] 'range keys from in-memory index tree' (duration: 300.568506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:40:44.481182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T15:40:44.179323Z","time spent":"301.843435ms","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-03T15:40:45.271884Z","caller":"traceutil/trace.go:171","msg":"trace[979501999] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"224.468227ms","start":"2026-07-03T15:40:45.047393Z","end":"2026-07-03T15:40:45.271862Z","steps":["trace[979501999] 'process raft request' (duration: 224.322544ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:40:58.744774Z","caller":"traceutil/trace.go:171","msg":"trace[154067681] linearizableReadLoop","detail":"{readStateIndex:1479; appliedIndex:1478; }","duration":"107.260404ms","start":"2026-07-03T15:40:58.637488Z","end":"2026-07-03T15:40:58.744748Z","steps":["trace[154067681] 'read index received' (duration: 70.141877ms)","trace[154067681] 'applied index is now lower than readState.Index' (duration: 37.115077ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T15:40:58.744908Z","caller":"traceutil/trace.go:171","msg":"trace[671696572] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"136.231467ms","start":"2026-07-03T15:40:58.608608Z","end":"2026-07-03T15:40:58.744839Z","steps":["trace[671696572] 'process raft request' (duration: 99.13155ms)","trace[671696572] 'compare' (duration: 36.863993ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T15:40:58.745364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.68328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpointslicemirroring-controller\" ","response":"range_response_count:1 size:807"} {"level":"info","ts":"2026-07-03T15:40:58.745527Z","caller":"traceutil/trace.go:171","msg":"trace[241238928] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpointslicemirroring-controller; range_end:; response_count:1; response_revision:1420; }","duration":"108.044535ms","start":"2026-07-03T15:40:58.637421Z","end":"2026-07-03T15:40:58.745465Z","steps":["trace[241238928] 'agreement among raft nodes before linearized reading' (duration: 107.487858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T15:41:00.50261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.828405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pvc-protection-controller\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-07-03T15:41:00.502686Z","caller":"traceutil/trace.go:171","msg":"trace[1976926294] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pvc-protection-controller; range_end:; response_count:1; response_revision:1425; }","duration":"119.960377ms","start":"2026-07-03T15:41:00.382709Z","end":"2026-07-03T15:41:00.502669Z","steps":["trace[1976926294] 'range keys from in-memory index tree' (duration: 119.731354ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T15:41:00.874922Z","caller":"traceutil/trace.go:171","msg":"trace[453583601] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"123.318845ms","start":"2026-07-03T15:41:00.75158Z","end":"2026-07-03T15:41:00.874899Z","steps":["trace[453583601] 'process raft request' (duration: 123.129302ms)"],"step_count":1}