{"level":"warn","ts":"2026-07-25T16:21:00.101393Z","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-25T16:21:00.101474Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-25T16:21:00.101535Z","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-25T16:21:00.101548Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-07-25T16:21:00.101562Z","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-25T16:21:00.102213Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-07-25T16:21:00.102282Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-25T16:21:00.109519Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.02014ms"} {"level":"info","ts":"2026-07-25T16:21:00.1254Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-07-25T16:21:00.12547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-07-25T16:21:00.125488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-07-25T16:21:00.125496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-25T16:21:00.125505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-07-25T16:21:00.125546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-07-25T16:21:00.131416Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-25T16:21:00.143432Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-25T16:21:00.15121Z","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-25T16:21:00.153628Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-25T16:21:00.15378Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-25T16:21:00.153893Z","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-25T16:21:00.153947Z","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-25T16:21:00.153956Z","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-25T16:21:00.15469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-07-25T16:21:00.154836Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-07-25T16:21:00.157058Z","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-25T16:21:00.157184Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-07-25T16:21:00.157221Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-07-25T16:21:00.158059Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-25T16:21:00.158131Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-25T16:21:00.926648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-07-25T16:21:00.926709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-25T16:21:00.926744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-07-25T16:21:00.926766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-07-25T16:21:00.92678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-07-25T16:21:00.926797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-07-25T16:21:00.926821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-07-25T16:21:00.927603Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T16:21:00.928769Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-07-25T16:21:00.928846Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T16:21:00.928948Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T16:21:00.929031Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-25T16:21:00.929103Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-25T16:21:00.930739Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-07-25T16:21:00.931259Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T16:21:00.931374Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T16:21:00.931417Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T16:21:00.932435Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-25T16:21:12.48626Z","caller":"traceutil/trace.go:171","msg":"trace[1025318318] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:361; }","duration":"110.356171ms","start":"2026-07-25T16:21:12.375875Z","end":"2026-07-25T16:21:12.486231Z","steps":["trace[1025318318] 'read index received' (duration: 110.149305ms)","trace[1025318318] 'applied index is now lower than readState.Index' (duration: 205.296µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:21:12.486546Z","caller":"traceutil/trace.go:171","msg":"trace[748254201] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"293.285759ms","start":"2026-07-25T16:21:12.193243Z","end":"2026-07-25T16:21:12.486529Z","steps":["trace[748254201] 'process raft request' (duration: 292.744485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:12.486652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.757191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T16:21:12.486738Z","caller":"traceutil/trace.go:171","msg":"trace[1724742837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:350; }","duration":"110.903994ms","start":"2026-07-25T16:21:12.375819Z","end":"2026-07-25T16:21:12.486723Z","steps":["trace[1724742837] 'agreement among raft nodes before linearized reading' (duration: 110.765061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:12.802105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.238495ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T16:21:12.802297Z","caller":"traceutil/trace.go:171","msg":"trace[88275514] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:362; }","duration":"261.441177ms","start":"2026-07-25T16:21:12.540839Z","end":"2026-07-25T16:21:12.80228Z","steps":["trace[88275514] 'read index received' (duration: 58.435004ms)","trace[88275514] 'applied index is now lower than readState.Index' (duration: 203.005213ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:21:12.802497Z","caller":"traceutil/trace.go:171","msg":"trace[627531329] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"310.883904ms","start":"2026-07-25T16:21:12.491579Z","end":"2026-07-25T16:21:12.802463Z","steps":["trace[627531329] 'process raft request' (duration: 310.644948ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:21:12.802539Z","caller":"traceutil/trace.go:171","msg":"trace[328113062] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"311.947299ms","start":"2026-07-25T16:21:12.490546Z","end":"2026-07-25T16:21:12.802493Z","steps":["trace[328113062] 'process raft request' (duration: 108.71418ms)","trace[328113062] 'compare' (duration: 202.093892ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:21:12.802634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.786466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"warn","ts":"2026-07-25T16:21:12.802637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:12.491571Z","time spent":"310.989956ms","remote":"127.0.0.1:47246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:21:12.802678Z","caller":"traceutil/trace.go:171","msg":"trace[254870694] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:352; }","duration":"261.847957ms","start":"2026-07-25T16:21:12.540819Z","end":"2026-07-25T16:21:12.802667Z","steps":["trace[254870694] 'agreement among raft nodes before linearized reading' (duration: 261.760584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:12.802685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.074627ms","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-25T16:21:12.802705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:12.490528Z","time spent":"312.078473ms","remote":"127.0.0.1:47246","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-25T16:21:12.802742Z","caller":"traceutil/trace.go:171","msg":"trace[1385627915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:352; }","duration":"125.19254ms","start":"2026-07-25T16:21:12.677534Z","end":"2026-07-25T16:21:12.802727Z","steps":["trace[1385627915] 'agreement among raft nodes before linearized reading' (duration: 125.090928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:13.32635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.452301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T16:21:13.326573Z","caller":"traceutil/trace.go:171","msg":"trace[230499239] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:365; }","duration":"214.109936ms","start":"2026-07-25T16:21:13.112439Z","end":"2026-07-25T16:21:13.326549Z","steps":["trace[230499239] 'read index received' (duration: 45.551µs)","trace[230499239] 'applied index is now lower than readState.Index' (duration: 214.062675ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:21:13.326676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.238859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-25T16:21:13.326717Z","caller":"traceutil/trace.go:171","msg":"trace[1429165861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:355; }","duration":"214.325511ms","start":"2026-07-25T16:21:13.112378Z","end":"2026-07-25T16:21:13.326704Z","steps":["trace[1429165861] 'agreement among raft nodes before linearized reading' (duration: 214.244139ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:21:13.326791Z","caller":"traceutil/trace.go:171","msg":"trace[440701604] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"515.677321ms","start":"2026-07-25T16:21:12.811068Z","end":"2026-07-25T16:21:13.326746Z","steps":["trace[440701604] 'process raft request' (duration: 515.389044ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:21:13.326814Z","caller":"traceutil/trace.go:171","msg":"trace[1098016855] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"518.561091ms","start":"2026-07-25T16:21:12.808224Z","end":"2026-07-25T16:21:13.326785Z","steps":["trace[1098016855] 'process raft request' (duration: 231.568446ms)","trace[1098016855] 'compare' (duration: 286.291177ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:21:13.327237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:12.808204Z","time spent":"518.780575ms","remote":"127.0.0.1:47246","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-25T16:21:13.327828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:12.81106Z","time spent":"515.857875ms","remote":"127.0.0.1:47172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:21:39.319581Z","caller":"traceutil/trace.go:171","msg":"trace[631599388] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"375.07107ms","start":"2026-07-25T16:21:38.944483Z","end":"2026-07-25T16:21:39.319554Z","steps":["trace[631599388] 'process raft request' (duration: 374.889207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:39.319714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:38.944426Z","time spent":"375.222753ms","remote":"127.0.0.1:47246","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-25T16:21:39.320822Z","caller":"traceutil/trace.go:171","msg":"trace[960139388] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"374.115331ms","start":"2026-07-25T16:21:38.946696Z","end":"2026-07-25T16:21:39.320812Z","steps":["trace[960139388] 'process raft request' (duration: 373.781495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:39.320874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:38.946682Z","time spent":"374.164162ms","remote":"127.0.0.1:47246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:21:39.380146Z","caller":"traceutil/trace.go:171","msg":"trace[1117908507] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:496; }","duration":"104.549951ms","start":"2026-07-25T16:21:39.275526Z","end":"2026-07-25T16:21:39.380076Z","steps":["trace[1117908507] 'read index received' (duration: 43.837175ms)","trace[1117908507] 'applied index is now lower than readState.Index' (duration: 60.711556ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:21:39.38024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.706575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T16:21:39.380279Z","caller":"traceutil/trace.go:171","msg":"trace[1152076356] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:480; }","duration":"104.778985ms","start":"2026-07-25T16:21:39.275487Z","end":"2026-07-25T16:21:39.380266Z","steps":["trace[1152076356] 'agreement among raft nodes before linearized reading' (duration: 104.710214ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:21:41.431538Z","caller":"traceutil/trace.go:171","msg":"trace[457170121] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"102.801339ms","start":"2026-07-25T16:21:41.328707Z","end":"2026-07-25T16:21:41.431508Z","steps":["trace[457170121] 'process raft request' (duration: 102.618716ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:21:41.48311Z","caller":"traceutil/trace.go:171","msg":"trace[991400135] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"152.354033ms","start":"2026-07-25T16:21:41.330714Z","end":"2026-07-25T16:21:41.483068Z","steps":["trace[991400135] 'process raft request' (duration: 152.113767ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:21:42.856795Z","caller":"traceutil/trace.go:171","msg":"trace[784824114] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:581; }","duration":"481.004355ms","start":"2026-07-25T16:21:42.375765Z","end":"2026-07-25T16:21:42.856769Z","steps":["trace[784824114] 'read index received' (duration: 480.418253ms)","trace[784824114] 'applied index is now lower than readState.Index' (duration: 584.852µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:21:42.856886Z","caller":"traceutil/trace.go:171","msg":"trace[1281983028] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"903.089898ms","start":"2026-07-25T16:21:41.953754Z","end":"2026-07-25T16:21:42.856844Z","steps":["trace[1281983028] 'process raft request' (duration: 902.647959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:42.856965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.150757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T16:21:42.857723Z","caller":"traceutil/trace.go:171","msg":"trace[1575459989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"482.000063ms","start":"2026-07-25T16:21:42.375698Z","end":"2026-07-25T16:21:42.857698Z","steps":["trace[1575459989] 'agreement among raft nodes before linearized reading' (duration: 481.174348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:42.857798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:41.953716Z","time spent":"903.283352ms","remote":"127.0.0.1:47012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T16:21:42.857895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:42.375673Z","time spent":"482.141175ms","remote":"127.0.0.1:48322","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-25T16:21:49.210585Z","caller":"traceutil/trace.go:171","msg":"trace[1653252479] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"267.534846ms","start":"2026-07-25T16:21:48.943002Z","end":"2026-07-25T16:21:49.210537Z","steps":["trace[1653252479] 'process raft request' (duration: 266.462336ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:21:49.387187Z","caller":"traceutil/trace.go:171","msg":"trace[945842193] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"121.299512ms","start":"2026-07-25T16:21:49.265808Z","end":"2026-07-25T16:21:49.387108Z","steps":["trace[945842193] 'process raft request' (duration: 120.855733ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:21:49.542863Z","caller":"traceutil/trace.go:171","msg":"trace[1846801982] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"139.148917ms","start":"2026-07-25T16:21:49.403675Z","end":"2026-07-25T16:21:49.542824Z","steps":["trace[1846801982] 'process raft request' (duration: 101.41009ms)","trace[1846801982] 'compare' (duration: 37.537484ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:21:50.401675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.929151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T16:21:50.401837Z","caller":"traceutil/trace.go:171","msg":"trace[577488534] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"854.138238ms","start":"2026-07-25T16:21:49.547679Z","end":"2026-07-25T16:21:50.401817Z","steps":["trace[577488534] 'process raft request' (duration: 173.973774ms)","trace[577488534] 'compare' (duration: 679.807238ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:21:50.401902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:49.547634Z","time spent":"854.23736ms","remote":"127.0.0.1:47246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:21:50.402184Z","caller":"traceutil/trace.go:171","msg":"trace[390490920] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"854.304301ms","start":"2026-07-25T16:21:49.547795Z","end":"2026-07-25T16:21:50.402099Z","steps":["trace[390490920] 'process raft request' (duration: 853.975365ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:21:50.402322Z","caller":"traceutil/trace.go:171","msg":"trace[682926833] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"854.169628ms","start":"2026-07-25T16:21:49.548105Z","end":"2026-07-25T16:21:50.402275Z","steps":["trace[682926833] 'process raft request' (duration: 853.881483ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:21:50.402483Z","caller":"traceutil/trace.go:171","msg":"trace[957337866] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"854.174639ms","start":"2026-07-25T16:21:49.548295Z","end":"2026-07-25T16:21:50.402469Z","steps":["trace[957337866] 'process raft request' (duration: 853.861253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:50.402533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:49.548099Z","time spent":"854.335271ms","remote":"127.0.0.1:47056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T16:21:50.402559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:49.548288Z","time spent":"854.23451ms","remote":"127.0.0.1:47172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T16:21:50.402489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:49.547773Z","time spent":"854.547565ms","remote":"127.0.0.1:47056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T16:21:50.402543Z","caller":"traceutil/trace.go:171","msg":"trace[1354921502] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:624; }","duration":"803.577015ms","start":"2026-07-25T16:21:49.598702Z","end":"2026-07-25T16:21:50.402279Z","steps":["trace[1354921502] 'read index received' (duration: 122.88604ms)","trace[1354921502] 'applied index is now lower than readState.Index' (duration: 680.689895ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:21:50.402591Z","caller":"traceutil/trace.go:171","msg":"trace[818824107] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"853.655469ms","start":"2026-07-25T16:21:49.548901Z","end":"2026-07-25T16:21:50.402557Z","steps":["trace[818824107] 'process raft request' (duration: 853.320813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:50.402857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.139296ms","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-25T16:21:50.402909Z","caller":"traceutil/trace.go:171","msg":"trace[416452814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:606; }","duration":"804.257738ms","start":"2026-07-25T16:21:49.598635Z","end":"2026-07-25T16:21:50.402893Z","steps":["trace[416452814] 'agreement among raft nodes before linearized reading' (duration: 804.077695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:50.402913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.170499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jvdb4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-25T16:21:50.402956Z","caller":"traceutil/trace.go:171","msg":"trace[1269309660] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jvdb4; range_end:; response_count:1; response_revision:606; }","duration":"768.237471ms","start":"2026-07-25T16:21:49.634707Z","end":"2026-07-25T16:21:50.402944Z","steps":["trace[1269309660] 'agreement among raft nodes before linearized reading' (duration: 768.093429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:50.402994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:49.634696Z","time spent":"768.291752ms","remote":"127.0.0.1:47172","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-jvdb4\" "} {"level":"warn","ts":"2026-07-25T16:21:50.402991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:49.548894Z","time spent":"853.966225ms","remote":"127.0.0.1:47224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T16:21:50.403006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:49.598619Z","time spent":"804.35471ms","remote":"127.0.0.1:47246","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-25T16:21:50.403278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.215782ms","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-25T16:21:50.40333Z","caller":"traceutil/trace.go:171","msg":"trace[1113525167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:606; }","duration":"739.303374ms","start":"2026-07-25T16:21:49.664013Z","end":"2026-07-25T16:21:50.403316Z","steps":["trace[1113525167] 'agreement among raft nodes before linearized reading' (duration: 738.867845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:50.403429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:49.663996Z","time spent":"739.419975ms","remote":"127.0.0.1:47246","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-25T16:21:51.098868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.864372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T16:21:51.099017Z","caller":"traceutil/trace.go:171","msg":"trace[265256345] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"687.215698ms","start":"2026-07-25T16:21:50.411763Z","end":"2026-07-25T16:21:51.098978Z","steps":["trace[265256345] 'process raft request' (duration: 361.139352ms)","trace[265256345] 'compare' (duration: 325.70319ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:21:51.099091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:50.411742Z","time spent":"687.316581ms","remote":"127.0.0.1:47246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:21:51.164256Z","caller":"traceutil/trace.go:171","msg":"trace[1055713087] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"744.408198ms","start":"2026-07-25T16:21:50.419814Z","end":"2026-07-25T16:21:51.164222Z","steps":["trace[1055713087] 'process raft request' (duration: 744.332347ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:21:51.164694Z","caller":"traceutil/trace.go:171","msg":"trace[415639289] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"752.493783ms","start":"2026-07-25T16:21:50.412166Z","end":"2026-07-25T16:21:51.16466Z","steps":["trace[415639289] 'process raft request' (duration: 751.803522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:51.164881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:50.412139Z","time spent":"752.662427ms","remote":"127.0.0.1:47246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T16:21:51.165164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:50.419786Z","time spent":"744.680443ms","remote":"127.0.0.1:47172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T16:21:51.580138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.887894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T16:21:51.580228Z","caller":"traceutil/trace.go:171","msg":"trace[1217770133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"202.029576ms","start":"2026-07-25T16:21:51.378177Z","end":"2026-07-25T16:21:51.580207Z","steps":["trace[1217770133] 'range keys from in-memory index tree' (duration: 201.034457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:52.874783Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.288904262s","expected-duration":"1s"} {"level":"info","ts":"2026-07-25T16:21:52.875195Z","caller":"traceutil/trace.go:171","msg":"trace[2110470646] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"468.949937ms","start":"2026-07-25T16:21:52.406223Z","end":"2026-07-25T16:21:52.875173Z","steps":["trace[2110470646] 'read index received' (duration: 468.746994ms)","trace[2110470646] 'applied index is now lower than readState.Index' (duration: 201.823µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:21:52.875409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.173172ms","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-25T16:21:52.875447Z","caller":"traceutil/trace.go:171","msg":"trace[1748498053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"469.266483ms","start":"2026-07-25T16:21:52.406171Z","end":"2026-07-25T16:21:52.875437Z","steps":["trace[1748498053] 'agreement among raft nodes before linearized reading' (duration: 469.09243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:52.875486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:52.406151Z","time spent":"469.325105ms","remote":"127.0.0.1:47246","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-25T16:21:52.875663Z","caller":"traceutil/trace.go:171","msg":"trace[1621336186] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"1.289836338s","start":"2026-07-25T16:21:51.585787Z","end":"2026-07-25T16:21:52.875623Z","steps":["trace[1621336186] 'process raft request' (duration: 1.289212307s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:52.876258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:51.585765Z","time spent":"1.290253546s","remote":"127.0.0.1:47246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T16:21:53.604348Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833401934504559,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T16:21:53.741999Z","caller":"traceutil/trace.go:171","msg":"trace[1790917748] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"638.417597ms","start":"2026-07-25T16:21:53.103558Z","end":"2026-07-25T16:21:53.741976Z","steps":["trace[1790917748] 'read index received' (duration: 638.231394ms)","trace[1790917748] 'applied index is now lower than readState.Index' (duration: 185.263µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:21:53.74203Z","caller":"traceutil/trace.go:171","msg":"trace[885106697] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"862.536265ms","start":"2026-07-25T16:21:52.879473Z","end":"2026-07-25T16:21:53.74201Z","steps":["trace[885106697] 'process raft request' (duration: 862.358902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:53.742152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.441636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T16:21:53.742169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.6153ms","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-25T16:21:53.742173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.689819ms","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-25T16:21:53.742187Z","caller":"traceutil/trace.go:171","msg":"trace[1960059777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"365.539847ms","start":"2026-07-25T16:21:53.376636Z","end":"2026-07-25T16:21:53.742176Z","steps":["trace[1960059777] 'agreement among raft nodes before linearized reading' (duration: 365.462925ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:21:53.742199Z","caller":"traceutil/trace.go:171","msg":"trace[608998177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"638.650171ms","start":"2026-07-25T16:21:53.10354Z","end":"2026-07-25T16:21:53.74219Z","steps":["trace[608998177] 'agreement among raft nodes before linearized reading' (duration: 638.530549ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:21:53.742206Z","caller":"traceutil/trace.go:171","msg":"trace[1636449049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:611; }","duration":"572.74851ms","start":"2026-07-25T16:21:53.169446Z","end":"2026-07-25T16:21:53.742195Z","steps":["trace[1636449049] 'agreement among raft nodes before linearized reading' (duration: 572.638748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:53.742221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:53.103473Z","time spent":"638.742902ms","remote":"127.0.0.1:47246","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-25T16:21:53.742221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:53.376594Z","time spent":"365.619368ms","remote":"127.0.0.1:48322","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-25T16:21:53.742236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:53.169432Z","time spent":"572.797231ms","remote":"127.0.0.1:47246","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-25T16:21:53.742937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:52.87945Z","time spent":"863.340459ms","remote":"127.0.0.1:47246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T16:21:55.198699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.307773ms","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-25T16:21:55.198775Z","caller":"traceutil/trace.go:171","msg":"trace[2037123525] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"317.558358ms","start":"2026-07-25T16:21:54.881196Z","end":"2026-07-25T16:21:55.198754Z","steps":["trace[2037123525] 'range keys from in-memory index tree' (duration: 317.09444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:21:55.198822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:21:54.881177Z","time spent":"317.629319ms","remote":"127.0.0.1:47246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-25T16:21:58.931384Z","caller":"traceutil/trace.go:171","msg":"trace[724216638] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"110.526575ms","start":"2026-07-25T16:21:58.820835Z","end":"2026-07-25T16:21:58.931362Z","steps":["trace[724216638] 'process raft request' (duration: 110.124348ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:22:07.51773Z","caller":"traceutil/trace.go:171","msg":"trace[611046011] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"134.142367ms","start":"2026-07-25T16:22:07.38356Z","end":"2026-07-25T16:22:07.517703Z","steps":["trace[611046011] 'process raft request' (duration: 133.976824ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:22:36.122501Z","caller":"traceutil/trace.go:171","msg":"trace[1399350083] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"158.873001ms","start":"2026-07-25T16:22:35.963602Z","end":"2026-07-25T16:22:36.122475Z","steps":["trace[1399350083] 'read index received' (duration: 158.594467ms)","trace[1399350083] 'applied index is now lower than readState.Index' (duration: 277.314µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:22:36.122586Z","caller":"traceutil/trace.go:171","msg":"trace[1893125940] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"161.173155ms","start":"2026-07-25T16:22:35.961383Z","end":"2026-07-25T16:22:36.122556Z","steps":["trace[1893125940] 'process raft request' (duration: 160.928042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:22:36.122765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.138575ms","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-25T16:22:36.12287Z","caller":"traceutil/trace.go:171","msg":"trace[11321800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:984; }","duration":"159.273317ms","start":"2026-07-25T16:22:35.963573Z","end":"2026-07-25T16:22:36.122846Z","steps":["trace[11321800] 'agreement among raft nodes before linearized reading' (duration: 159.014304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:22:36.122789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.252178ms","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-25T16:22:36.123002Z","caller":"traceutil/trace.go:171","msg":"trace[1424372644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:984; }","duration":"150.507782ms","start":"2026-07-25T16:22:35.972469Z","end":"2026-07-25T16:22:36.122977Z","steps":["trace[1424372644] 'agreement among raft nodes before linearized reading' (duration: 150.148087ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:22:58.140302Z","caller":"traceutil/trace.go:171","msg":"trace[650065285] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"102.251653ms","start":"2026-07-25T16:22:58.038024Z","end":"2026-07-25T16:22:58.140276Z","steps":["trace[650065285] 'process raft request' (duration: 102.093021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:30.305256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.441840128s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T16:24:30.305473Z","caller":"traceutil/trace.go:171","msg":"trace[2053328677] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"1.565597658s","start":"2026-07-25T16:24:28.739838Z","end":"2026-07-25T16:24:30.305436Z","steps":["trace[2053328677] 'process raft request' (duration: 123.486477ms)","trace[2053328677] 'compare' (duration: 1.441653376s)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:24:30.305705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:28.739823Z","time spent":"1.565728169s","remote":"127.0.0.1:47246","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-25T16:24:30.305823Z","caller":"traceutil/trace.go:171","msg":"trace[1072028231] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"1.565743808s","start":"2026-07-25T16:24:28.740055Z","end":"2026-07-25T16:24:30.305798Z","steps":["trace[1072028231] 'process raft request' (duration: 1.565302244s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:30.305907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:28.740045Z","time spent":"1.565822619s","remote":"127.0.0.1:47246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:24:30.306006Z","caller":"traceutil/trace.go:171","msg":"trace[2098744161] linearizableReadLoop","detail":"{readStateIndex:1434; appliedIndex:1432; }","duration":"1.476456619s","start":"2026-07-25T16:24:28.829536Z","end":"2026-07-25T16:24:30.305992Z","steps":["trace[2098744161] 'read index received' (duration: 33.713111ms)","trace[2098744161] 'applied index is now lower than readState.Index' (duration: 1.442742168s)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:24:31.024283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.815564ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-25T16:24:31.024648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.195099662s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-938630a3-43c4-4c91-b76a-17dc246c9b20\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-07-25T16:24:31.024746Z","caller":"traceutil/trace.go:171","msg":"trace[2146213588] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-938630a3-43c4-4c91-b76a-17dc246c9b20; range_end:; response_count:1; response_revision:1376; }","duration":"2.195241723s","start":"2026-07-25T16:24:28.829482Z","end":"2026-07-25T16:24:31.024724Z","steps":["trace[2146213588] 'agreement among raft nodes before linearized reading' (duration: 1.476573461s)","trace[2146213588] 'range keys from in-memory index tree' (duration: 718.326499ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:24:31.024874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:28.82946Z","time spent":"2.195400355s","remote":"127.0.0.1:47172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3602,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-938630a3-43c4-4c91-b76a-17dc246c9b20\" "} {"level":"info","ts":"2026-07-25T16:24:31.024975Z","caller":"traceutil/trace.go:171","msg":"trace[631197601] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"1.407764774s","start":"2026-07-25T16:24:29.617189Z","end":"2026-07-25T16:24:31.024954Z","steps":["trace[631197601] 'process raft request' (duration: 1.407347859s)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:24:31.025018Z","caller":"traceutil/trace.go:171","msg":"trace[488527335] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1434; }","duration":"718.949015ms","start":"2026-07-25T16:24:30.306033Z","end":"2026-07-25T16:24:31.024982Z","steps":["trace[488527335] 'read index received' (duration: 187.210152ms)","trace[488527335] 'applied index is now lower than readState.Index' (duration: 531.736753ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:24:31.025173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:29.617162Z","time spent":"1.407890936s","remote":"127.0.0.1:47246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T16:24:31.096406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.878822116s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-938630a3-43c4-4c91-b76a-17dc246c9b20\" ","response":"range_response_count:1 size:3579"} {"level":"warn","ts":"2026-07-25T16:24:31.09642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.720626939s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T16:24:31.096469Z","caller":"traceutil/trace.go:171","msg":"trace[1365830515] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-938630a3-43c4-4c91-b76a-17dc246c9b20; range_end:; response_count:1; response_revision:1377; }","duration":"1.878925546s","start":"2026-07-25T16:24:29.217524Z","end":"2026-07-25T16:24:31.096449Z","steps":["trace[1365830515] 'agreement among raft nodes before linearized reading' (duration: 1.807562071s)","trace[1365830515] 'range keys from in-memory index tree' (duration: 71.213554ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:24:31.096482Z","caller":"traceutil/trace.go:171","msg":"trace[212074543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1377; }","duration":"1.720723589s","start":"2026-07-25T16:24:29.37574Z","end":"2026-07-25T16:24:31.096464Z","steps":["trace[212074543] 'agreement among raft nodes before linearized reading' (duration: 1.649271793s)","trace[212074543] 'range keys from in-memory index tree' (duration: 71.357595ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:24:31.096506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:29.217505Z","time spent":"1.878993217s","remote":"127.0.0.1:47172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3602,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-938630a3-43c4-4c91-b76a-17dc246c9b20\" "} {"level":"warn","ts":"2026-07-25T16:24:31.096532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:29.375729Z","time spent":"1.72079395s","remote":"127.0.0.1:48322","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-25T16:24:31.09685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142603258s","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-25T16:24:31.096996Z","caller":"traceutil/trace.go:171","msg":"trace[2137587733] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1377; }","duration":"1.14278574s","start":"2026-07-25T16:24:29.954192Z","end":"2026-07-25T16:24:31.096978Z","steps":["trace[2137587733] 'agreement among raft nodes before linearized reading' (duration: 1.070803978s)","trace[2137587733] 'range keys from in-memory index tree' (duration: 71.352865ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:24:31.097067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.784263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T16:24:31.097151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:29.954174Z","time spent":"1.142962672s","remote":"127.0.0.1:47246","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-25T16:24:31.097311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.414431ms","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-25T16:24:31.097458Z","caller":"traceutil/trace.go:171","msg":"trace[421885937] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"494.659663ms","start":"2026-07-25T16:24:30.602318Z","end":"2026-07-25T16:24:31.096978Z","steps":["trace[421885937] 'process raft request' (duration: 422.652571ms)","trace[421885937] 'compare' (duration: 71.558297ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T16:24:31.097487Z","caller":"traceutil/trace.go:171","msg":"trace[565523757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1378; }","duration":"609.892754ms","start":"2026-07-25T16:24:30.487217Z","end":"2026-07-25T16:24:31.097109Z","steps":["trace[565523757] 'agreement among raft nodes before linearized reading' (duration: 609.763472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:31.097527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:30.487161Z","time spent":"610.356249ms","remote":"127.0.0.1:48322","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-25T16:24:31.09757Z","caller":"traceutil/trace.go:171","msg":"trace[2064577884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1378; }","duration":"316.503752ms","start":"2026-07-25T16:24:30.780841Z","end":"2026-07-25T16:24:31.097345Z","steps":["trace[2064577884] 'agreement among raft nodes before linearized reading' (duration: 316.181277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T16:24:31.097584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.639004ms","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":"warn","ts":"2026-07-25T16:24:31.09764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:30.78081Z","time spent":"316.803045ms","remote":"127.0.0.1:47246","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-25T16:24:31.097638Z","caller":"traceutil/trace.go:171","msg":"trace[1987856966] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1377; }","duration":"836.734405ms","start":"2026-07-25T16:24:30.260887Z","end":"2026-07-25T16:24:31.097621Z","steps":["trace[1987856966] 'agreement among raft nodes before linearized reading' (duration: 764.097775ms)","trace[1987856966] 'range keys from in-memory index tree' (duration: 71.336425ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T16:24:31.097682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:30.260835Z","time spent":"836.839336ms","remote":"127.0.0.1:47246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-25T16:24:31.09786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T16:24:30.602301Z","time spent":"495.201339ms","remote":"127.0.0.1:47246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T16:24:31.229835Z","caller":"traceutil/trace.go:171","msg":"trace[1031882723] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"127.540458ms","start":"2026-07-25T16:24:31.10227Z","end":"2026-07-25T16:24:31.22981Z","steps":["trace[1031882723] 'process raft request' (duration: 118.138023ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:24:31.23008Z","caller":"traceutil/trace.go:171","msg":"trace[1902577790] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"127.158614ms","start":"2026-07-25T16:24:31.102887Z","end":"2026-07-25T16:24:31.230045Z","steps":["trace[1902577790] 'process raft request' (duration: 126.86273ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:24:31.230294Z","caller":"traceutil/trace.go:171","msg":"trace[169802038] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"126.854181ms","start":"2026-07-25T16:24:31.103192Z","end":"2026-07-25T16:24:31.230046Z","steps":["trace[169802038] 'process raft request' (duration: 126.739539ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T16:24:44.035516Z","caller":"traceutil/trace.go:171","msg":"trace[1974583607] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"101.051303ms","start":"2026-07-25T16:24:43.934437Z","end":"2026-07-25T16:24:44.035488Z","steps":["trace[1974583607] 'process raft request' (duration: 88.005213ms)","trace[1974583607] 'compare' (duration: 12.933737ms)"],"step_count":2}