{"level":"warn","ts":"2026-04-09T02:00:27.702865Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T02:00:27.702946Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-09T02:00:27.703009Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T02:00:27.703019Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-04-09T02:00:27.70304Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-09T02:00:27.703691Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-04-09T02:00:27.703762Z","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.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-09T02:00:27.709283Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.258325ms"} {"level":"info","ts":"2026-04-09T02:00:27.721999Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-04-09T02:00:27.722076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T02:00:27.722099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-04-09T02:00:27.722114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T02:00:27.722126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-04-09T02:00:27.722163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-04-09T02:00:27.730544Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T02:00:27.732019Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T02:00:27.733973Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-09T02:00:27.737378Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T02:00:27.737583Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T02:00:27.737668Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-09T02:00:27.737762Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-09T02:00:27.737772Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-09T02:00:27.739896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-04-09T02:00:27.740045Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-04-09T02:00:27.742426Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-09T02:00:27.742556Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-04-09T02:00:27.74258Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-04-09T02:00:27.742759Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T02:00:27.742862Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T02:00:27.924964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T02:00:27.925457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T02:00:27.925589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-04-09T02:00:27.925641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-04-09T02:00:27.925664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-04-09T02:00:27.92569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-04-09T02:00:27.925739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-04-09T02:00:27.928355Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:00:27.929451Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T02:00:27.929735Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T02:00:27.931262Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:00:27.931369Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:00:27.931401Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T02:00:27.931451Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:00:27.931563Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T02:00:27.931614Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T02:00:27.93323Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-04-09T02:00:27.934316Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-09T02:00:32.946581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.905375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:00:32.946696Z","caller":"traceutil/trace.go:171","msg":"trace[1801433806] linearizableReadLoop","detail":"{readStateIndex:257; appliedIndex:256; }","duration":"668.769633ms","start":"2026-04-09T02:00:32.277911Z","end":"2026-04-09T02:00:32.94668Z","steps":["trace[1801433806] 'read index received' (duration: 36.631µs)","trace[1801433806] 'applied index is now lower than readState.Index' (duration: 668.731412ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:00:32.946769Z","caller":"traceutil/trace.go:171","msg":"trace[1175585657] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"934.819057ms","start":"2026-04-09T02:00:32.011933Z","end":"2026-04-09T02:00:32.946752Z","steps":["trace[1175585657] 'process raft request' (duration: 178.260045ms)","trace[1175585657] 'compare' (duration: 755.78042ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:00:32.947006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:00:32.011922Z","time spent":"935.061374ms","remote":"127.0.0.1:47526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11444,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:00:32.946902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.989939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:00:32.947162Z","caller":"traceutil/trace.go:171","msg":"trace[1033516572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:251; }","duration":"669.249217ms","start":"2026-04-09T02:00:32.277853Z","end":"2026-04-09T02:00:32.947102Z","steps":["trace[1033516572] 'agreement among raft nodes before linearized reading' (duration: 668.916167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:00:32.94721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:00:32.277829Z","time spent":"669.368529ms","remote":"127.0.0.1:47730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T02:00:33.954985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.323504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:00:33.955603Z","caller":"traceutil/trace.go:171","msg":"trace[896018561] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"1.005314164s","start":"2026-04-09T02:00:32.950258Z","end":"2026-04-09T02:00:33.955572Z","steps":["trace[896018561] 'process raft request' (duration: 533.321222ms)","trace[896018561] 'compare' (duration: 470.692767ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:00:33.955709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:00:32.950242Z","time spent":"1.005424607s","remote":"127.0.0.1:47730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:00:33.956095Z","caller":"traceutil/trace.go:171","msg":"trace[127711897] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"1.005145259s","start":"2026-04-09T02:00:32.950938Z","end":"2026-04-09T02:00:33.956083Z","steps":["trace[127711897] 'process raft request' (duration: 1.004161632s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:00:33.956164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:00:32.950929Z","time spent":"1.00520297s","remote":"127.0.0.1:47800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":324,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:00:33.956711Z","caller":"traceutil/trace.go:171","msg":"trace[2010391251] linearizableReadLoop","detail":"{readStateIndex:261; appliedIndex:257; }","duration":"414.614188ms","start":"2026-04-09T02:00:33.542082Z","end":"2026-04-09T02:00:33.956696Z","steps":["trace[2010391251] 'read index received' (duration: 412.836289ms)","trace[2010391251] 'applied index is now lower than readState.Index' (duration: 1.776679ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:00:33.956837Z","caller":"traceutil/trace.go:171","msg":"trace[605911754] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"1.005382904s","start":"2026-04-09T02:00:32.951395Z","end":"2026-04-09T02:00:33.956778Z","steps":["trace[605911754] 'process raft request' (duration: 1.004630514s)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:00:33.956887Z","caller":"traceutil/trace.go:171","msg":"trace[1927424841] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"768.328644ms","start":"2026-04-09T02:00:33.188521Z","end":"2026-04-09T02:00:33.95685Z","steps":["trace[1927424841] 'process raft request' (duration: 768.115658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:00:33.956994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.890606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-09T02:00:33.957025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:00:32.951388Z","time spent":"1.005566119s","remote":"127.0.0.1:47730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:00:33.957093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:00:33.188493Z","time spent":"768.544951ms","remote":"127.0.0.1:47622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:00:33.957124Z","caller":"traceutil/trace.go:171","msg":"trace[1307772846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:255; }","duration":"415.020691ms","start":"2026-04-09T02:00:33.542035Z","end":"2026-04-09T02:00:33.957056Z","steps":["trace[1307772846] 'agreement among raft nodes before linearized reading' (duration: 414.781233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:00:33.957311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:00:33.542014Z","time spent":"415.220946ms","remote":"127.0.0.1:47730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T02:00:33.957313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.44538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:00:33.95783Z","caller":"traceutil/trace.go:171","msg":"trace[919720872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:255; }","duration":"316.065847ms","start":"2026-04-09T02:00:33.641738Z","end":"2026-04-09T02:00:33.957804Z","steps":["trace[919720872] 'agreement among raft nodes before linearized reading' (duration: 315.187242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:00:33.957942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:00:33.641725Z","time spent":"316.20518ms","remote":"127.0.0.1:47730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T02:00:58.884946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.398921ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:00:58.885065Z","caller":"traceutil/trace.go:171","msg":"trace[371130160] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"665.642252ms","start":"2026-04-09T02:00:58.219401Z","end":"2026-04-09T02:00:58.885043Z","steps":["trace[371130160] 'process raft request' (duration: 370.925274ms)","trace[371130160] 'compare' (duration: 294.252098ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:00:58.88521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:00:58.219329Z","time spent":"665.770644ms","remote":"127.0.0.1:47474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:00:58.898529Z","caller":"traceutil/trace.go:171","msg":"trace[1386190230] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"666.546861ms","start":"2026-04-09T02:00:58.231958Z","end":"2026-04-09T02:00:58.898505Z","steps":["trace[1386190230] 'process raft request' (duration: 666.265065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:00:58.898652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:00:58.231934Z","time spent":"666.654673ms","remote":"127.0.0.1:47474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:00:58.8988Z","caller":"traceutil/trace.go:171","msg":"trace[473037357] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"623.899901ms","start":"2026-04-09T02:00:58.274865Z","end":"2026-04-09T02:00:58.898765Z","steps":["trace[473037357] 'process raft request' (duration: 623.527993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:00:58.898928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:00:58.274832Z","time spent":"624.037433ms","remote":"127.0.0.1:47474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:00:58.899295Z","caller":"traceutil/trace.go:171","msg":"trace[435594616] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:563; }","duration":"317.905088ms","start":"2026-04-09T02:00:58.581366Z","end":"2026-04-09T02:00:58.899271Z","steps":["trace[435594616] 'read index received' (duration: 9.029554ms)","trace[435594616] 'applied index is now lower than readState.Index' (duration: 308.874274ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:00:58.899441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.059341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:00:58.899513Z","caller":"traceutil/trace.go:171","msg":"trace[320051196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:544; }","duration":"318.183074ms","start":"2026-04-09T02:00:58.581321Z","end":"2026-04-09T02:00:58.899504Z","steps":["trace[320051196] 'agreement among raft nodes before linearized reading' (duration: 318.053491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:00:58.899554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:00:58.581304Z","time spent":"318.236945ms","remote":"127.0.0.1:47422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T02:00:58.899832Z","caller":"traceutil/trace.go:171","msg":"trace[1217962449] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"620.918666ms","start":"2026-04-09T02:00:58.278902Z","end":"2026-04-09T02:00:58.899821Z","steps":["trace[1217962449] 'process raft request' (duration: 619.575797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:00:58.899977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:00:58.278869Z","time spent":"621.031598ms","remote":"127.0.0.1:47474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:01:04.43268Z","caller":"traceutil/trace.go:171","msg":"trace[1362485762] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"132.943918ms","start":"2026-04-09T02:01:04.299709Z","end":"2026-04-09T02:01:04.432653Z","steps":["trace[1362485762] 'process raft request' (duration: 132.464609ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:01:05.125176Z","caller":"traceutil/trace.go:171","msg":"trace[1736961616] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"208.729837ms","start":"2026-04-09T02:01:04.916424Z","end":"2026-04-09T02:01:05.125153Z","steps":["trace[1736961616] 'process raft request' (duration: 208.250437ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:01:05.12563Z","caller":"traceutil/trace.go:171","msg":"trace[1447366988] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:639; }","duration":"106.880266ms","start":"2026-04-09T02:01:05.018724Z","end":"2026-04-09T02:01:05.125604Z","steps":["trace[1447366988] 'read index received' (duration: 106.875216ms)","trace[1447366988] 'applied index is now lower than readState.Index' (duration: 4.02µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:01:05.125852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.121701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qb7xs\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-09T02:01:05.125892Z","caller":"traceutil/trace.go:171","msg":"trace[1119150582] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qb7xs; range_end:; response_count:1; response_revision:615; }","duration":"107.215373ms","start":"2026-04-09T02:01:05.018662Z","end":"2026-04-09T02:01:05.125878Z","steps":["trace[1119150582] 'agreement among raft nodes before linearized reading' (duration: 107.004859ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:01:05.954048Z","caller":"traceutil/trace.go:171","msg":"trace[1309156257] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"795.652677ms","start":"2026-04-09T02:01:05.158354Z","end":"2026-04-09T02:01:05.954007Z","steps":["trace[1309156257] 'process raft request' (duration: 795.404672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:05.954202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:05.15833Z","time spent":"795.79711ms","remote":"127.0.0.1:47622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:01:05.961117Z","caller":"traceutil/trace.go:171","msg":"trace[507228028] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:639; }","duration":"379.998862ms","start":"2026-04-09T02:01:05.581095Z","end":"2026-04-09T02:01:05.961094Z","steps":["trace[507228028] 'read index received' (duration: 372.752785ms)","trace[507228028] 'applied index is now lower than readState.Index' (duration: 7.245207ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:01:05.961143Z","caller":"traceutil/trace.go:171","msg":"trace[1699343311] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"779.05371ms","start":"2026-04-09T02:01:05.182039Z","end":"2026-04-09T02:01:05.961092Z","steps":["trace[1699343311] 'process raft request' (duration: 778.835846ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:01:05.961294Z","caller":"traceutil/trace.go:171","msg":"trace[1484533384] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"255.402556ms","start":"2026-04-09T02:01:05.705867Z","end":"2026-04-09T02:01:05.96127Z","steps":["trace[1484533384] 'process raft request' (duration: 255.187802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:05.961361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.238567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:01:05.961419Z","caller":"traceutil/trace.go:171","msg":"trace[1205144309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"380.36468ms","start":"2026-04-09T02:01:05.581041Z","end":"2026-04-09T02:01:05.961406Z","steps":["trace[1205144309] 'agreement among raft nodes before linearized reading' (duration: 380.235917ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:01:05.961408Z","caller":"traceutil/trace.go:171","msg":"trace[2052192506] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"346.718478ms","start":"2026-04-09T02:01:05.614645Z","end":"2026-04-09T02:01:05.961363Z","steps":["trace[2052192506] 'process raft request' (duration: 346.3548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:05.961483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:05.581021Z","time spent":"380.453211ms","remote":"127.0.0.1:47422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T02:01:05.961605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:05.614602Z","time spent":"346.899451ms","remote":"127.0.0.1:47730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:01:05.962092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:05.182013Z","time spent":"779.306366ms","remote":"127.0.0.1:48000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:01:07.497205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.792771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:01:07.49736Z","caller":"traceutil/trace.go:171","msg":"trace[441095052] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"348.811108ms","start":"2026-04-09T02:01:07.148521Z","end":"2026-04-09T02:01:07.497332Z","steps":["trace[441095052] 'read index received' (duration: 158.842814ms)","trace[441095052] 'applied index is now lower than readState.Index' (duration: 189.966324ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:01:07.497468Z","caller":"traceutil/trace.go:171","msg":"trace[1066028035] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"350.768127ms","start":"2026-04-09T02:01:07.14668Z","end":"2026-04-09T02:01:07.497448Z","steps":["trace[1066028035] 'process raft request' (duration: 160.588239ms)","trace[1066028035] 'compare' (duration: 189.490895ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:01:07.497565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:07.14667Z","time spent":"350.847599ms","remote":"127.0.0.1:47730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:01:07.497725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.178655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:01:07.497804Z","caller":"traceutil/trace.go:171","msg":"trace[1801348327] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:643; }","duration":"349.292558ms","start":"2026-04-09T02:01:07.148492Z","end":"2026-04-09T02:01:07.497784Z","steps":["trace[1801348327] 'agreement among raft nodes before linearized reading' (duration: 348.967092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:07.497845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:07.148479Z","time spent":"349.357849ms","remote":"127.0.0.1:47474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"info","ts":"2026-04-09T02:01:07.502579Z","caller":"traceutil/trace.go:171","msg":"trace[1205894908] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"352.36597ms","start":"2026-04-09T02:01:07.15019Z","end":"2026-04-09T02:01:07.502556Z","steps":["trace[1205894908] 'process raft request' (duration: 352.322089ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:01:07.502655Z","caller":"traceutil/trace.go:171","msg":"trace[1744831046] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"352.863269ms","start":"2026-04-09T02:01:07.149739Z","end":"2026-04-09T02:01:07.502602Z","steps":["trace[1744831046] 'process raft request' (duration: 352.663725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:07.502874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:07.149704Z","time spent":"353.074134ms","remote":"127.0.0.1:47474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:01:07.502714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:07.150181Z","time spent":"352.465972ms","remote":"127.0.0.1:47474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:01:07.505621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.996492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:01:07.505684Z","caller":"traceutil/trace.go:171","msg":"trace[1978923846] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:646; }","duration":"357.089853ms","start":"2026-04-09T02:01:07.148579Z","end":"2026-04-09T02:01:07.505669Z","steps":["trace[1978923846] 'agreement among raft nodes before linearized reading' (duration: 356.978511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:07.50575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:07.148573Z","time spent":"357.168245ms","remote":"127.0.0.1:47474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-04-09T02:01:07.505958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.928461ms","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":"warn","ts":"2026-04-09T02:01:07.505944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.135874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:01:07.505986Z","caller":"traceutil/trace.go:171","msg":"trace[457386097] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:646; }","duration":"355.954691ms","start":"2026-04-09T02:01:07.150023Z","end":"2026-04-09T02:01:07.505978Z","steps":["trace[457386097] 'agreement among raft nodes before linearized reading' (duration: 355.90728ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:01:07.506026Z","caller":"traceutil/trace.go:171","msg":"trace[222975042] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:646; }","duration":"355.260497ms","start":"2026-04-09T02:01:07.150745Z","end":"2026-04-09T02:01:07.506006Z","steps":["trace[222975042] 'agreement among raft nodes before linearized reading' (duration: 355.082703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:07.506046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:07.149997Z","time spent":"356.042922ms","remote":"127.0.0.1:47474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-04-09T02:01:07.506073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:07.15072Z","time spent":"355.343838ms","remote":"127.0.0.1:47474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-04-09T02:01:07.506131Z","caller":"traceutil/trace.go:171","msg":"trace[682560814] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"159.413715ms","start":"2026-04-09T02:01:07.346696Z","end":"2026-04-09T02:01:07.50611Z","steps":["trace[682560814] 'process raft request' (duration: 158.766992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:07.506239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.598714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:01:07.506311Z","caller":"traceutil/trace.go:171","msg":"trace[451396974] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:646; }","duration":"356.755286ms","start":"2026-04-09T02:01:07.149539Z","end":"2026-04-09T02:01:07.506294Z","steps":["trace[451396974] 'agreement among raft nodes before linearized reading' (duration: 356.602533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:07.507109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:07.149531Z","time spent":"357.566543ms","remote":"127.0.0.1:47474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-04-09T02:01:20.736511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853969692481376392,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T02:01:21.109974Z","caller":"traceutil/trace.go:171","msg":"trace[421454024] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:804; }","duration":"874.401269ms","start":"2026-04-09T02:01:20.235545Z","end":"2026-04-09T02:01:21.109947Z","steps":["trace[421454024] 'read index received' (duration: 874.283157ms)","trace[421454024] 'applied index is now lower than readState.Index' (duration: 117.232µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:01:21.110444Z","caller":"traceutil/trace.go:171","msg":"trace[516025582] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"889.54588ms","start":"2026-04-09T02:01:20.220867Z","end":"2026-04-09T02:01:21.110412Z","steps":["trace[516025582] 'process raft request' (duration: 888.922249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:21.110483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.922648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-09T02:01:21.110531Z","caller":"traceutil/trace.go:171","msg":"trace[1932371240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:777; }","duration":"875.005269ms","start":"2026-04-09T02:01:20.235513Z","end":"2026-04-09T02:01:21.110518Z","steps":["trace[1932371240] 'agreement among raft nodes before linearized reading' (duration: 874.836326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:21.110564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:20.235502Z","time spent":"875.05489ms","remote":"127.0.0.1:47730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T02:01:21.110649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:20.220839Z","time spent":"889.689682ms","remote":"127.0.0.1:47730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:01:21.270702Z","caller":"traceutil/trace.go:171","msg":"trace[1393394683] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:805; }","duration":"160.595754ms","start":"2026-04-09T02:01:21.110083Z","end":"2026-04-09T02:01:21.270679Z","steps":["trace[1393394683] 'read index received' (duration: 160.449852ms)","trace[1393394683] 'applied index is now lower than readState.Index' (duration: 144.652µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:01:21.270879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028838111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-09T02:01:21.270855Z","caller":"traceutil/trace.go:171","msg":"trace[412010654] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"395.858984ms","start":"2026-04-09T02:01:20.874951Z","end":"2026-04-09T02:01:21.27081Z","steps":["trace[412010654] 'process raft request' (duration: 395.590969ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:01:21.271107Z","caller":"traceutil/trace.go:171","msg":"trace[1439356114] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:778; }","duration":"1.028934503s","start":"2026-04-09T02:01:20.241975Z","end":"2026-04-09T02:01:21.27091Z","steps":["trace[1439356114] 'agreement among raft nodes before linearized reading' (duration: 1.028788091s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:21.271142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:20.241953Z","time spent":"1.029180937s","remote":"127.0.0.1:47730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T02:01:21.271129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.354371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-09T02:01:21.271241Z","caller":"traceutil/trace.go:171","msg":"trace[557411956] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:778; }","duration":"254.703768ms","start":"2026-04-09T02:01:21.016511Z","end":"2026-04-09T02:01:21.271215Z","steps":["trace[557411956] 'agreement among raft nodes before linearized reading' (duration: 254.218749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:21.271265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:20.874917Z","time spent":"396.251901ms","remote":"127.0.0.1:47730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:01:21.271799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027257313s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-09T02:01:21.271843Z","caller":"traceutil/trace.go:171","msg":"trace[1729996165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:778; }","duration":"1.027321613s","start":"2026-04-09T02:01:20.244509Z","end":"2026-04-09T02:01:21.27183Z","steps":["trace[1729996165] 'agreement among raft nodes before linearized reading' (duration: 1.027179511s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:21.271874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:20.244498Z","time spent":"1.027368515s","remote":"127.0.0.1:47730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-09T02:01:21.277777Z","caller":"traceutil/trace.go:171","msg":"trace[1808542134] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"163.18598ms","start":"2026-04-09T02:01:21.114573Z","end":"2026-04-09T02:01:21.277759Z","steps":["trace[1808542134] 'process raft request' (duration: 162.995487ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:01:21.277729Z","caller":"traceutil/trace.go:171","msg":"trace[580309476] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"149.237223ms","start":"2026-04-09T02:01:21.128476Z","end":"2026-04-09T02:01:21.277713Z","steps":["trace[580309476] 'process raft request' (duration: 149.193062ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:01:25.27913Z","caller":"traceutil/trace.go:171","msg":"trace[188972182] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"125.464273ms","start":"2026-04-09T02:01:25.153639Z","end":"2026-04-09T02:01:25.279103Z","steps":["trace[188972182] 'process raft request' (duration: 125.220059ms)"],"step_count":1}