{"level":"warn","ts":"2026-07-22T21:59:33.957646Z","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-22T21:59:33.957791Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-22T21:59:33.957905Z","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-22T21:59:33.95793Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-07-22T21:59:33.957963Z","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-22T21:59:33.959189Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-07-22T21:59:33.95936Z","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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-22T21:59:33.964539Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.787065ms"} {"level":"info","ts":"2026-07-22T21:59:33.977317Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-07-22T21:59:33.977659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-07-22T21:59:33.977715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-07-22T21:59:33.977732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-22T21:59:33.977744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-07-22T21:59:33.977809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-07-22T21:59:33.989879Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-22T21:59:33.992207Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-22T21:59:33.994741Z","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-22T21:59:33.99834Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-22T21:59:33.999319Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-22T21:59:33.999414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-07-22T21:59:33.999527Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-07-22T21:59:33.999488Z","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-22T21:59:33.999606Z","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-22T21:59:33.999619Z","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-22T21:59:34.004427Z","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-22T21:59:34.004533Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-07-22T21:59:34.004578Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-07-22T21:59:34.004658Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-22T21:59:34.004702Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-22T21:59:34.878265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-07-22T21:59:34.87832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-22T21:59:34.878369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-07-22T21:59:34.878414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-07-22T21:59:34.878444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-07-22T21:59:34.87847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-07-22T21:59:34.878494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-07-22T21:59:34.880989Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-07-22T21:59:34.881031Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T21:59:34.881024Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T21:59:34.881206Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T21:59:34.881509Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-22T21:59:34.881563Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-22T21:59:34.883992Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T21:59:34.88411Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T21:59:34.884141Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T21:59:34.884812Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-22T21:59:34.885528Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"warn","ts":"2026-07-22T22:00:02.890256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.368338ms","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-22T22:00:02.890274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.334261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:00:02.890335Z","caller":"traceutil/trace.go:171","msg":"trace[172972207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:433; }","duration":"333.511083ms","start":"2026-07-22T22:00:02.55681Z","end":"2026-07-22T22:00:02.890321Z","steps":["trace[172972207] 'range keys from in-memory index tree' (duration: 333.246873ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:02.890351Z","caller":"traceutil/trace.go:171","msg":"trace[1851738746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:433; }","duration":"277.461235ms","start":"2026-07-22T22:00:02.61287Z","end":"2026-07-22T22:00:02.890331Z","steps":["trace[1851738746] 'range keys from in-memory index tree' (duration: 277.139153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:02.890373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:02.556793Z","time spent":"333.573996ms","remote":"127.0.0.1:41090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-22T22:00:07.057516Z","caller":"traceutil/trace.go:171","msg":"trace[743008250] linearizableReadLoop","detail":"{readStateIndex:459; appliedIndex:458; }","duration":"149.649285ms","start":"2026-07-22T22:00:06.907845Z","end":"2026-07-22T22:00:07.057495Z","steps":["trace[743008250] 'read index received' (duration: 149.366075ms)","trace[743008250] 'applied index is now lower than readState.Index' (duration: 282.3µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:00:07.057664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.79821ms","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-22T22:00:07.0577Z","caller":"traceutil/trace.go:171","msg":"trace[793592547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:442; }","duration":"149.917265ms","start":"2026-07-22T22:00:06.907771Z","end":"2026-07-22T22:00:07.057688Z","steps":["trace[793592547] 'agreement among raft nodes before linearized reading' (duration: 149.832482ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:07.057737Z","caller":"traceutil/trace.go:171","msg":"trace[670562661] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"221.962034ms","start":"2026-07-22T22:00:06.835715Z","end":"2026-07-22T22:00:07.057678Z","steps":["trace[670562661] 'process raft request' (duration: 221.549109ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:10.69441Z","caller":"traceutil/trace.go:171","msg":"trace[2079684350] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"384.030356ms","start":"2026-07-22T22:00:10.310353Z","end":"2026-07-22T22:00:10.694383Z","steps":["trace[2079684350] 'process raft request' (duration: 383.905822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:10.6949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:10.310331Z","time spent":"384.159651ms","remote":"127.0.0.1:40860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T22:00:10.703304Z","caller":"traceutil/trace.go:171","msg":"trace[2076387653] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"391.689377ms","start":"2026-07-22T22:00:10.31159Z","end":"2026-07-22T22:00:10.703279Z","steps":["trace[2076387653] 'process raft request' (duration: 391.458509ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:10.703364Z","caller":"traceutil/trace.go:171","msg":"trace[1270959645] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"384.979508ms","start":"2026-07-22T22:00:10.318352Z","end":"2026-07-22T22:00:10.703331Z","steps":["trace[1270959645] 'process raft request' (duration: 384.881265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:10.703404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:10.311582Z","time spent":"391.769339ms","remote":"127.0.0.1:40860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T22:00:10.703329Z","caller":"traceutil/trace.go:171","msg":"trace[1153152238] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"390.458474ms","start":"2026-07-22T22:00:10.312846Z","end":"2026-07-22T22:00:10.703304Z","steps":["trace[1153152238] 'process raft request' (duration: 390.285529ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:10.703457Z","caller":"traceutil/trace.go:171","msg":"trace[1852154163] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"384.541633ms","start":"2026-07-22T22:00:10.318878Z","end":"2026-07-22T22:00:10.703419Z","steps":["trace[1852154163] 'process raft request' (duration: 384.417229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:10.703494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:10.318338Z","time spent":"385.095433ms","remote":"127.0.0.1:40860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T22:00:10.703505Z","caller":"traceutil/trace.go:171","msg":"trace[1493607046] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"376.47292ms","start":"2026-07-22T22:00:10.327022Z","end":"2026-07-22T22:00:10.703495Z","steps":["trace[1493607046] 'process raft request' (duration: 376.368046ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:10.703495Z","caller":"traceutil/trace.go:171","msg":"trace[1477866543] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"378.433916ms","start":"2026-07-22T22:00:10.325005Z","end":"2026-07-22T22:00:10.703439Z","steps":["trace[1477866543] 'process raft request' (duration: 378.324792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:10.703551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:10.327008Z","time spent":"376.521431ms","remote":"127.0.0.1:40860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T22:00:10.703589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:10.318866Z","time spent":"384.652828ms","remote":"127.0.0.1:40860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T22:00:10.703647Z","caller":"traceutil/trace.go:171","msg":"trace[666015941] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"373.136736ms","start":"2026-07-22T22:00:10.330502Z","end":"2026-07-22T22:00:10.703639Z","steps":["trace[666015941] 'process raft request' (duration: 372.926109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:10.703651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:10.324991Z","time spent":"378.586802ms","remote":"127.0.0.1:40860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T22:00:10.703646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:10.312838Z","time spent":"390.637721ms","remote":"127.0.0.1:40860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T22:00:10.703693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:10.330489Z","time spent":"373.185598ms","remote":"127.0.0.1:40860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T22:00:10.703827Z","caller":"traceutil/trace.go:171","msg":"trace[1737685957] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"369.581826ms","start":"2026-07-22T22:00:10.334235Z","end":"2026-07-22T22:00:10.703817Z","steps":["trace[1737685957] 'process raft request' (duration: 369.41655ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:10.703767Z","caller":"traceutil/trace.go:171","msg":"trace[286330282] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"371.17988ms","start":"2026-07-22T22:00:10.332547Z","end":"2026-07-22T22:00:10.703727Z","steps":["trace[286330282] 'process raft request' (duration: 370.943752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:10.703867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:10.334178Z","time spent":"369.672409ms","remote":"127.0.0.1:40860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T22:00:10.7039Z","caller":"traceutil/trace.go:171","msg":"trace[1578768524] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"354.960828ms","start":"2026-07-22T22:00:10.348928Z","end":"2026-07-22T22:00:10.703889Z","steps":["trace[1578768524] 'process raft request' (duration: 354.818453ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:10.703935Z","caller":"traceutil/trace.go:171","msg":"trace[1449415734] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"201.814886ms","start":"2026-07-22T22:00:10.502113Z","end":"2026-07-22T22:00:10.703928Z","steps":["trace[1449415734] 'process raft request' (duration: 201.719032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:10.703951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:10.348912Z","time spent":"355.016491ms","remote":"127.0.0.1:40860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T22:00:10.703959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:10.332527Z","time spent":"371.347946ms","remote":"127.0.0.1:40860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T22:00:10.703981Z","caller":"traceutil/trace.go:171","msg":"trace[1407175520] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:504; }","duration":"285.53866ms","start":"2026-07-22T22:00:10.418381Z","end":"2026-07-22T22:00:10.70392Z","steps":["trace[1407175520] 'read index received' (duration: 276.504793ms)","trace[1407175520] 'applied index is now lower than readState.Index' (duration: 9.032957ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:00:10.704138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.743727ms","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-22T22:00:10.70421Z","caller":"traceutil/trace.go:171","msg":"trace[1146564940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:497; }","duration":"285.858471ms","start":"2026-07-22T22:00:10.418312Z","end":"2026-07-22T22:00:10.70417Z","steps":["trace[1146564940] 'agreement among raft nodes before linearized reading' (duration: 285.713096ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:16.854976Z","caller":"traceutil/trace.go:171","msg":"trace[283022756] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"107.413319ms","start":"2026-07-22T22:00:16.747533Z","end":"2026-07-22T22:00:16.854946Z","steps":["trace[283022756] 'process raft request' (duration: 93.09136ms)","trace[283022756] 'compare' (duration: 14.05171ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:00:17.135022Z","caller":"traceutil/trace.go:171","msg":"trace[1901580957] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"265.973684ms","start":"2026-07-22T22:00:16.869021Z","end":"2026-07-22T22:00:17.134995Z","steps":["trace[1901580957] 'process raft request' (duration: 265.607453ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:17.41244Z","caller":"traceutil/trace.go:171","msg":"trace[1004822654] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"318.369892ms","start":"2026-07-22T22:00:17.094043Z","end":"2026-07-22T22:00:17.412413Z","steps":["trace[1004822654] 'process raft request' (duration: 307.525338ms)","trace[1004822654] 'compare' (duration: 10.412641ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:00:17.412535Z","caller":"traceutil/trace.go:171","msg":"trace[1653414540] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"296.371036ms","start":"2026-07-22T22:00:17.116144Z","end":"2026-07-22T22:00:17.412515Z","steps":["trace[1653414540] 'process raft request' (duration: 296.20763ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:17.412441Z","caller":"traceutil/trace.go:171","msg":"trace[752576858] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:616; }","duration":"262.271803ms","start":"2026-07-22T22:00:17.150144Z","end":"2026-07-22T22:00:17.412416Z","steps":["trace[752576858] 'read index received' (duration: 251.174312ms)","trace[752576858] 'applied index is now lower than readState.Index' (duration: 11.096421ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:00:17.412759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:17.094023Z","time spent":"318.486726ms","remote":"127.0.0.1:40906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:00:17.412775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.669287ms","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-22T22:00:17.412875Z","caller":"traceutil/trace.go:171","msg":"trace[1236813762] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"262.806041ms","start":"2026-07-22T22:00:17.150056Z","end":"2026-07-22T22:00:17.412862Z","steps":["trace[1236813762] 'agreement among raft nodes before linearized reading' (duration: 262.434249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:18.154433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.121662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-22T22:00:18.1545Z","caller":"traceutil/trace.go:171","msg":"trace[499096705] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:597; }","duration":"436.249866ms","start":"2026-07-22T22:00:17.718235Z","end":"2026-07-22T22:00:18.154485Z","steps":["trace[499096705] 'range keys from in-memory index tree' (duration: 436.019499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:18.154535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:17.718213Z","time spent":"436.315338ms","remote":"127.0.0.1:40848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-07-22T22:00:18.154485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.341418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-22T22:00:18.154669Z","caller":"traceutil/trace.go:171","msg":"trace[959882368] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:597; }","duration":"641.528664ms","start":"2026-07-22T22:00:17.513096Z","end":"2026-07-22T22:00:18.154625Z","steps":["trace[959882368] 'range keys from in-memory index tree' (duration: 641.281856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:18.154757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.343729ms","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-22T22:00:18.154773Z","caller":"traceutil/trace.go:171","msg":"trace[546745835] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:597; }","duration":"290.399032ms","start":"2026-07-22T22:00:17.864369Z","end":"2026-07-22T22:00:18.154768Z","steps":["trace[546745835] 'range keys from in-memory index tree' (duration: 290.233436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:18.154753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:17.513089Z","time spent":"641.650308ms","remote":"127.0.0.1:41026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-07-22T22:00:18.155319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.917528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:00:18.155434Z","caller":"traceutil/trace.go:171","msg":"trace[1917374558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"543.119934ms","start":"2026-07-22T22:00:17.61229Z","end":"2026-07-22T22:00:18.15541Z","steps":["trace[1917374558] 'range keys from in-memory index tree' (duration: 542.844955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:18.155546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:17.612255Z","time spent":"543.275409ms","remote":"127.0.0.1:40782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-22T22:00:18.457841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:18.155808Z","time spent":"302.030414ms","remote":"127.0.0.1:40848","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-22T22:00:18.457861Z","caller":"traceutil/trace.go:171","msg":"trace[1998041087] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:621; }","duration":"300.992151ms","start":"2026-07-22T22:00:18.156846Z","end":"2026-07-22T22:00:18.457839Z","steps":["trace[1998041087] 'read index received' (duration: 300.984811ms)","trace[1998041087] 'applied index is now lower than readState.Index' (duration: 6.21µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:00:18.458134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.241658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:00:18.458267Z","caller":"traceutil/trace.go:171","msg":"trace[389947573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"301.414354ms","start":"2026-07-22T22:00:18.156806Z","end":"2026-07-22T22:00:18.45822Z","steps":["trace[389947573] 'agreement among raft nodes before linearized reading' (duration: 301.191977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:18.458347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:18.156786Z","time spent":"301.525858ms","remote":"127.0.0.1:40796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-22T22:00:18.486649Z","caller":"traceutil/trace.go:171","msg":"trace[911665042] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"327.646038ms","start":"2026-07-22T22:00:18.158971Z","end":"2026-07-22T22:00:18.486617Z","steps":["trace[911665042] 'process raft request' (duration: 326.999927ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:18.48669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.479811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:00:18.486746Z","caller":"traceutil/trace.go:171","msg":"trace[891648366] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:598; }","duration":"325.548422ms","start":"2026-07-22T22:00:18.161182Z","end":"2026-07-22T22:00:18.48673Z","steps":["trace[891648366] 'agreement among raft nodes before linearized reading' (duration: 325.406668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:18.486785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:18.158923Z","time spent":"327.785883ms","remote":"127.0.0.1:41090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:00:18.486836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:18.161174Z","time spent":"325.648115ms","remote":"127.0.0.1:40968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-07-22T22:00:18.486849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.858208ms","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-22T22:00:18.486937Z","caller":"traceutil/trace.go:171","msg":"trace[134073630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"315.982342ms","start":"2026-07-22T22:00:18.170937Z","end":"2026-07-22T22:00:18.486919Z","steps":["trace[134073630] 'agreement among raft nodes before linearized reading' (duration: 315.711364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:18.486994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:18.170923Z","time spent":"316.056984ms","remote":"127.0.0.1:41090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-22T22:00:20.382445Z","caller":"traceutil/trace.go:171","msg":"trace[1905754171] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"390.476063ms","start":"2026-07-22T22:00:19.991941Z","end":"2026-07-22T22:00:20.382418Z","steps":["trace[1905754171] 'process raft request' (duration: 390.325728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:20.383064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:19.99191Z","time spent":"390.736111ms","remote":"127.0.0.1:40860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T22:00:20.385493Z","caller":"traceutil/trace.go:171","msg":"trace[880054195] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"194.373878ms","start":"2026-07-22T22:00:20.191092Z","end":"2026-07-22T22:00:20.385466Z","steps":["trace[880054195] 'process raft request' (duration: 194.267585ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:20.694259Z","caller":"traceutil/trace.go:171","msg":"trace[1033796511] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"120.649537ms","start":"2026-07-22T22:00:20.573576Z","end":"2026-07-22T22:00:20.694225Z","steps":["trace[1033796511] 'read index received' (duration: 45.875474ms)","trace[1033796511] 'applied index is now lower than readState.Index' (duration: 74.772853ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:00:20.694617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.024088ms","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-22T22:00:20.694671Z","caller":"traceutil/trace.go:171","msg":"trace[187764948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"121.09941ms","start":"2026-07-22T22:00:20.573559Z","end":"2026-07-22T22:00:20.694658Z","steps":["trace[187764948] 'agreement among raft nodes before linearized reading' (duration: 120.862123ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:20.694311Z","caller":"traceutil/trace.go:171","msg":"trace[471411178] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"181.687247ms","start":"2026-07-22T22:00:20.512578Z","end":"2026-07-22T22:00:20.694265Z","steps":["trace[471411178] 'process raft request' (duration: 106.901523ms)","trace[471411178] 'compare' (duration: 74.585678ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:00:20.694999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.965354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:00:20.695042Z","caller":"traceutil/trace.go:171","msg":"trace[2103980000] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:641; }","duration":"105.037836ms","start":"2026-07-22T22:00:20.589991Z","end":"2026-07-22T22:00:20.695029Z","steps":["trace[2103980000] 'agreement among raft nodes before linearized reading' (duration: 104.982134ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:21.01348Z","caller":"traceutil/trace.go:171","msg":"trace[383182174] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"179.836936ms","start":"2026-07-22T22:00:20.833615Z","end":"2026-07-22T22:00:21.013452Z","steps":["trace[383182174] 'process raft request' (duration: 112.445993ms)","trace[383182174] 'compare' (duration: 67.225228ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:00:21.035522Z","caller":"traceutil/trace.go:171","msg":"trace[299637543] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"165.687945ms","start":"2026-07-22T22:00:20.869804Z","end":"2026-07-22T22:00:21.035492Z","steps":["trace[299637543] 'read index received' (duration: 76.219128ms)","trace[299637543] 'applied index is now lower than readState.Index' (duration: 89.467057ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:00:21.035659Z","caller":"traceutil/trace.go:171","msg":"trace[533875161] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"195.600785ms","start":"2026-07-22T22:00:20.840017Z","end":"2026-07-22T22:00:21.035617Z","steps":["trace[533875161] 'process raft request' (duration: 195.322977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:21.035745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.911342ms","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-22T22:00:21.035836Z","caller":"traceutil/trace.go:171","msg":"trace[1619629633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:646; }","duration":"166.062966ms","start":"2026-07-22T22:00:20.869757Z","end":"2026-07-22T22:00:21.03582Z","steps":["trace[1619629633] 'agreement among raft nodes before linearized reading' (duration: 165.836249ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:30.929303Z","caller":"traceutil/trace.go:171","msg":"trace[853945287] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"103.517719ms","start":"2026-07-22T22:00:30.825768Z","end":"2026-07-22T22:00:30.929286Z","steps":["trace[853945287] 'process raft request' (duration: 103.417796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:33.890701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.414424ms","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-22T22:00:33.890782Z","caller":"traceutil/trace.go:171","msg":"trace[1156461489] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:760; }","duration":"360.542787ms","start":"2026-07-22T22:00:33.530222Z","end":"2026-07-22T22:00:33.890764Z","steps":["trace[1156461489] 'range keys from in-memory index tree' (duration: 360.265981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:33.890826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:33.5302Z","time spent":"360.61623ms","remote":"127.0.0.1:41090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-22T22:00:34.014886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.649803ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-22T22:00:34.01501Z","caller":"traceutil/trace.go:171","msg":"trace[1029350946] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"402.296608ms","start":"2026-07-22T22:00:33.612691Z","end":"2026-07-22T22:00:34.014988Z","steps":["trace[1029350946] 'read index received' (duration: 36.351µs)","trace[1029350946] 'applied index is now lower than readState.Index' (duration: 402.258527ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:00:34.015127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.432432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:00:34.015153Z","caller":"traceutil/trace.go:171","msg":"trace[443167626] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"402.469853ms","start":"2026-07-22T22:00:33.612675Z","end":"2026-07-22T22:00:34.015145Z","steps":["trace[443167626] 'agreement among raft nodes before linearized reading' (duration: 402.401221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:34.015196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:00:33.612626Z","time spent":"402.561975ms","remote":"127.0.0.1:40782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-22T22:00:34.016087Z","caller":"traceutil/trace.go:171","msg":"trace[1598054149] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"124.430172ms","start":"2026-07-22T22:00:33.891644Z","end":"2026-07-22T22:00:34.016074Z","steps":["trace[1598054149] 'process raft request' (duration: 124.275688ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:34.021382Z","caller":"traceutil/trace.go:171","msg":"trace[1525245405] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"127.150395ms","start":"2026-07-22T22:00:33.894206Z","end":"2026-07-22T22:00:34.021356Z","steps":["trace[1525245405] 'process raft request' (duration: 125.236234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:34.021886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.914293ms","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-22T22:00:34.02196Z","caller":"traceutil/trace.go:171","msg":"trace[349103571] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:762; }","duration":"152.024786ms","start":"2026-07-22T22:00:33.869919Z","end":"2026-07-22T22:00:34.021944Z","steps":["trace[349103571] 'agreement among raft nodes before linearized reading' (duration: 151.840111ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:45.338436Z","caller":"traceutil/trace.go:171","msg":"trace[797642335] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:917; }","duration":"144.470117ms","start":"2026-07-22T22:00:45.193945Z","end":"2026-07-22T22:00:45.338415Z","steps":["trace[797642335] 'read index received' (duration: 12.805967ms)","trace[797642335] 'applied index is now lower than readState.Index' (duration: 131.66322ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:00:45.338578Z","caller":"traceutil/trace.go:171","msg":"trace[1471008376] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"146.340734ms","start":"2026-07-22T22:00:45.192224Z","end":"2026-07-22T22:00:45.338564Z","steps":["trace[1471008376] 'process raft request' (duration: 146.145619ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:45.338563Z","caller":"traceutil/trace.go:171","msg":"trace[826443996] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"153.937992ms","start":"2026-07-22T22:00:45.184591Z","end":"2026-07-22T22:00:45.338529Z","steps":["trace[826443996] 'process raft request' (duration: 153.649675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:45.33883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.866347ms","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-22T22:00:45.33887Z","caller":"traceutil/trace.go:171","msg":"trace[714945646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:888; }","duration":"144.940329ms","start":"2026-07-22T22:00:45.19392Z","end":"2026-07-22T22:00:45.33886Z","steps":["trace[714945646] 'agreement among raft nodes before linearized reading' (duration: 144.775025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:45.338852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.089905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:00:45.338937Z","caller":"traceutil/trace.go:171","msg":"trace[579141597] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:888; }","duration":"116.222178ms","start":"2026-07-22T22:00:45.222696Z","end":"2026-07-22T22:00:45.338918Z","steps":["trace[579141597] 'agreement among raft nodes before linearized reading' (duration: 116.073204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:45.339064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.533174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:00:45.339182Z","caller":"traceutil/trace.go:171","msg":"trace[2000591991] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:888; }","duration":"103.697918ms","start":"2026-07-22T22:00:45.235457Z","end":"2026-07-22T22:00:45.339155Z","steps":["trace[2000591991] 'agreement among raft nodes before linearized reading' (duration: 103.477342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:45.339194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.453069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-22T22:00:45.339221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.432933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:00:45.33925Z","caller":"traceutil/trace.go:171","msg":"trace[167527263] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:888; }","duration":"115.549022ms","start":"2026-07-22T22:00:45.223686Z","end":"2026-07-22T22:00:45.339235Z","steps":["trace[167527263] 'agreement among raft nodes before linearized reading' (duration: 115.420959ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:00:45.339257Z","caller":"traceutil/trace.go:171","msg":"trace[249792603] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:888; }","duration":"116.511025ms","start":"2026-07-22T22:00:45.222736Z","end":"2026-07-22T22:00:45.339247Z","steps":["trace[249792603] 'agreement among raft nodes before linearized reading' (duration: 116.424293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:45.339414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.281313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:00:45.339446Z","caller":"traceutil/trace.go:171","msg":"trace[1234750511] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:888; }","duration":"104.338444ms","start":"2026-07-22T22:00:45.235098Z","end":"2026-07-22T22:00:45.339437Z","steps":["trace[1234750511] 'agreement among raft nodes before linearized reading' (duration: 104.287902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:45.33968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.518729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:00:45.339782Z","caller":"traceutil/trace.go:171","msg":"trace[1334523756] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:888; }","duration":"104.669912ms","start":"2026-07-22T22:00:45.235086Z","end":"2026-07-22T22:00:45.339756Z","steps":["trace[1334523756] 'agreement among raft nodes before linearized reading' (duration: 104.521948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:48.201504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.82845ms","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-22T22:00:48.201603Z","caller":"traceutil/trace.go:171","msg":"trace[1590088531] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:921; }","duration":"117.977124ms","start":"2026-07-22T22:00:48.083605Z","end":"2026-07-22T22:00:48.201582Z","steps":["trace[1590088531] 'range keys from in-memory index tree' (duration: 117.635655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:00:48.201599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.674773ms","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-22T22:00:48.201686Z","caller":"traceutil/trace.go:171","msg":"trace[457757933] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:921; }","duration":"121.807116ms","start":"2026-07-22T22:00:48.079855Z","end":"2026-07-22T22:00:48.201663Z","steps":["trace[457757933] 'range keys from in-memory index tree' (duration: 121.429878ms)"],"step_count":1}