{"level":"warn","ts":"2026-07-30T22:55:37.040054Z","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-30T22:55:37.040203Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-30T22:55:37.040339Z","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-30T22:55:37.040367Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-07-30T22:55:37.04044Z","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-30T22:55:37.041628Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-07-30T22:55:37.044593Z","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.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-30T22:55:37.055459Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.470721ms"} {"level":"info","ts":"2026-07-30T22:55:37.357291Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-07-30T22:55:37.357492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T22:55:37.357652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-07-30T22:55:37.357681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T22:55:37.357723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-07-30T22:55:37.357809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-07-30T22:55:37.361607Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T22:55:37.365318Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T22:55:37.366924Z","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-30T22:55:37.369399Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T22:55:37.371528Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T22:55:37.371612Z","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-30T22:55:37.37168Z","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-30T22:55:37.371691Z","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-30T22:55:37.372352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-07-30T22:55:37.372443Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-07-30T22:55:37.373333Z","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-30T22:55:37.373518Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-07-30T22:55:37.373532Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-07-30T22:55:37.374949Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T22:55:37.374985Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T22:55:38.059347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T22:55:38.059426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T22:55:38.059458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-07-30T22:55:38.059501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-07-30T22:55:38.059515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-07-30T22:55:38.059533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-07-30T22:55:38.059548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-07-30T22:55:38.06055Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T22:55:38.060949Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T22:55:38.060966Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T22:55:38.060987Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T22:55:38.06169Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T22:55:38.061901Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T22:55:38.061958Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T22:55:38.061906Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T22:55:38.062024Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T22:55:38.063504Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T22:55:38.064862Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"warn","ts":"2026-07-30T22:55:41.964626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.426131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T22:55:41.964747Z","caller":"traceutil/trace.go:171","msg":"trace[510094278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:209; }","duration":"199.618696ms","start":"2026-07-30T22:55:41.765107Z","end":"2026-07-30T22:55:41.964725Z","steps":["trace[510094278] 'range keys from in-memory index tree' (duration: 199.271027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:55:42.65938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.448927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T22:55:42.659484Z","caller":"traceutil/trace.go:171","msg":"trace[945285124] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"247.745099ms","start":"2026-07-30T22:55:42.411726Z","end":"2026-07-30T22:55:42.659471Z","steps":["trace[945285124] 'process raft request' (duration: 20.812309ms)","trace[945285124] 'compare' (duration: 226.340824ms)"],"step_count":2} 2026/07/30 22:55:42 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-30T22:55:43.875326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.094149491s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T22:55:43.875425Z","caller":"traceutil/trace.go:171","msg":"trace[1051163452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:248; }","duration":"1.094325786s","start":"2026-07-30T22:55:42.781074Z","end":"2026-07-30T22:55:43.8754Z","steps":["trace[1051163452] 'range keys from in-memory index tree' (duration: 1.094072899s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:55:43.875466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:55:42.781055Z","time spent":"1.094401618s","remote":"127.0.0.1:34338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T22:55:43.87551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.480973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-07-30T22:55:43.875598Z","caller":"traceutil/trace.go:171","msg":"trace[1648946257] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:248; }","duration":"491.620917ms","start":"2026-07-30T22:55:43.38395Z","end":"2026-07-30T22:55:43.875571Z","steps":["trace[1648946257] 'range keys from in-memory index tree' (duration: 491.37096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:55:43.875636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:55:43.383934Z","time spent":"491.694899ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-07-30T22:55:43.875745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100702835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T22:55:43.875861Z","caller":"traceutil/trace.go:171","msg":"trace[1189139153] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:248; }","duration":"1.100852248s","start":"2026-07-30T22:55:42.774977Z","end":"2026-07-30T22:55:43.87583Z","steps":["trace[1189139153] 'range keys from in-memory index tree' (duration: 1.100635022s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:55:43.875906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:55:42.774958Z","time spent":"1.100935771s","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" "} {"level":"warn","ts":"2026-07-30T22:55:43.891244Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221536138006593,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T22:55:44.099867Z","caller":"traceutil/trace.go:171","msg":"trace[2129449433] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"715.646123ms","start":"2026-07-30T22:55:43.384193Z","end":"2026-07-30T22:55:44.099839Z","steps":["trace[2129449433] 'process raft request' (duration: 715.50685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:55:44.100093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:55:43.384183Z","time spent":"715.847459ms","remote":"127.0.0.1:34142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T22:55:45.005027Z","caller":"traceutil/trace.go:171","msg":"trace[1958359945] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"1.498682634s","start":"2026-07-30T22:55:43.505985Z","end":"2026-07-30T22:55:45.004667Z","steps":["trace[1958359945] 'process raft request' (duration: 1.498589681s)"],"step_count":1} {"level":"info","ts":"2026-07-30T22:55:45.005074Z","caller":"traceutil/trace.go:171","msg":"trace[1625142295] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"1.498825908s","start":"2026-07-30T22:55:43.506084Z","end":"2026-07-30T22:55:45.00491Z","steps":["trace[1625142295] 'process raft request' (duration: 1.49851779s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:55:45.00523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:55:43.505979Z","time spent":"1.499131145s","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T22:55:45.005536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:55:43.506077Z","time spent":"1.499091135s","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T22:55:45.005572Z","caller":"traceutil/trace.go:171","msg":"trace[944462281] linearizableReadLoop","detail":"{readStateIndex:262; appliedIndex:254; }","duration":"1.61480318s","start":"2026-07-30T22:55:43.390707Z","end":"2026-07-30T22:55:45.00551Z","steps":["trace[944462281] 'read index received' (duration: 709.093159ms)","trace[944462281] 'applied index is now lower than readState.Index' (duration: 905.453504ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T22:55:45.005676Z","caller":"traceutil/trace.go:171","msg":"trace[2115023635] transaction","detail":"{read_only:false; number_of_response:0; response_revision:252; }","duration":"1.499095076s","start":"2026-07-30T22:55:43.506535Z","end":"2026-07-30T22:55:45.00563Z","steps":["trace[2115023635] 'process raft request' (duration: 1.49850808s)"],"step_count":1} {"level":"info","ts":"2026-07-30T22:55:45.005919Z","caller":"traceutil/trace.go:171","msg":"trace[1586867716] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"1.125665959s","start":"2026-07-30T22:55:43.880065Z","end":"2026-07-30T22:55:45.005731Z","steps":["trace[1586867716] 'process raft request' (duration: 1.125044192s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:55:45.005991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:55:43.50653Z","time spent":"1.4992834s","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T22:55:45.006019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:55:43.880042Z","time spent":"1.125909856s","remote":"127.0.0.1:34338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T22:55:45.006622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.61589972s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T22:55:45.006689Z","caller":"traceutil/trace.go:171","msg":"trace[135075107] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:253; }","duration":"1.615971001s","start":"2026-07-30T22:55:43.390692Z","end":"2026-07-30T22:55:45.006663Z","steps":["trace[135075107] 'agreement among raft nodes before linearized reading' (duration: 1.615283913s)"],"step_count":1} {"level":"info","ts":"2026-07-30T22:55:45.006257Z","caller":"traceutil/trace.go:171","msg":"trace[169892487] transaction","detail":"{read_only:false; number_of_response:0; response_revision:252; }","duration":"1.499854006s","start":"2026-07-30T22:55:43.506384Z","end":"2026-07-30T22:55:45.006238Z","steps":["trace[169892487] 'process raft request' (duration: 1.498624043s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:55:45.00673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:55:43.390685Z","time spent":"1.616037233s","remote":"127.0.0.1:34338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-07-30T22:55:45.006805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:55:43.506377Z","time spent":"1.5003824s","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T22:55:45.006213Z","caller":"traceutil/trace.go:171","msg":"trace[1723217777] transaction","detail":"{read_only:false; number_of_response:0; response_revision:249; }","duration":"1.510113219s","start":"2026-07-30T22:55:43.494463Z","end":"2026-07-30T22:55:45.004576Z","steps":["trace[1723217777] 'process raft request' (duration: 1.509922574s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:55:45.006975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:55:43.494438Z","time spent":"1.512514373s","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T22:55:45.004666Z","caller":"traceutil/trace.go:171","msg":"trace[187229529] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"1.498796237s","start":"2026-07-30T22:55:43.505858Z","end":"2026-07-30T22:55:45.004654Z","steps":["trace[187229529] 'process raft request' (duration: 1.498607702s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:55:45.007242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:55:43.505846Z","time spent":"1.501344696s","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T22:55:45.095169Z","caller":"traceutil/trace.go:171","msg":"trace[1616342875] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"989.775366ms","start":"2026-07-30T22:55:44.105363Z","end":"2026-07-30T22:55:45.095139Z","steps":["trace[1616342875] 'process raft request' (duration: 983.512956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:55:45.09536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108331373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T22:55:45.095423Z","caller":"traceutil/trace.go:171","msg":"trace[583263868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:254; }","duration":"1.108451676s","start":"2026-07-30T22:55:43.986955Z","end":"2026-07-30T22:55:45.095407Z","steps":["trace[583263868] 'agreement among raft nodes before linearized reading' (duration: 1.108297011s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:55:45.095458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:55:43.986934Z","time spent":"1.108515258s","remote":"127.0.0.1:34338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T22:55:45.095523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216871867s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-07-30T22:55:45.095595Z","caller":"traceutil/trace.go:171","msg":"trace[1377760163] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:254; }","duration":"1.216959369s","start":"2026-07-30T22:55:43.87861Z","end":"2026-07-30T22:55:45.09557Z","steps":["trace[1377760163] 'agreement among raft nodes before linearized reading' (duration: 1.216848406s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:55:45.095559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413899859s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T22:55:45.095648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:55:43.878596Z","time spent":"1.217043411s","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-07-30T22:55:45.095683Z","caller":"traceutil/trace.go:171","msg":"trace[343585688] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:254; }","duration":"1.414079144s","start":"2026-07-30T22:55:43.68157Z","end":"2026-07-30T22:55:45.095649Z","steps":["trace[343585688] 'agreement among raft nodes before linearized reading' (duration: 1.413862308s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:55:45.095762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:55:43.681553Z","time spent":"1.414195227s","remote":"127.0.0.1:34338","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-07-30T22:55:45.095864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:55:44.105343Z","time spent":"989.903039ms","remote":"127.0.0.1:34142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T22:55:45.095395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209265783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T22:55:45.096701Z","caller":"traceutil/trace.go:171","msg":"trace[1905791248] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:254; }","duration":"1.21057757s","start":"2026-07-30T22:55:43.886089Z","end":"2026-07-30T22:55:45.096666Z","steps":["trace[1905791248] 'agreement among raft nodes before linearized reading' (duration: 1.209204593s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:55:45.096752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:55:43.886054Z","time spent":"1.210685982s","remote":"127.0.0.1:34162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/kube-system/coredns\" "} {"level":"warn","ts":"2026-07-30T22:55:45.095768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.764864ms","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-30T22:55:45.099957Z","caller":"traceutil/trace.go:171","msg":"trace[1889297631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:254; }","duration":"722.085892ms","start":"2026-07-30T22:55:44.377846Z","end":"2026-07-30T22:55:45.099932Z","steps":["trace[1889297631] 'agreement among raft nodes before linearized reading' (duration: 717.525048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:55:45.100031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:55:44.377827Z","time spent":"722.182715ms","remote":"127.0.0.1:34338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T22:55:49.228234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.925503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-07-30T22:55:49.228328Z","caller":"traceutil/trace.go:171","msg":"trace[1111689212] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:326; }","duration":"116.085808ms","start":"2026-07-30T22:55:49.112222Z","end":"2026-07-30T22:55:49.228308Z","steps":["trace[1111689212] 'range keys from in-memory index tree' (duration: 115.791799ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T22:55:50.292678Z","caller":"traceutil/trace.go:171","msg":"trace[1447026913] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:356; }","duration":"130.414932ms","start":"2026-07-30T22:55:50.162235Z","end":"2026-07-30T22:55:50.29265Z","steps":["trace[1447026913] 'read index received' (duration: 76.125841ms)","trace[1447026913] 'applied index is now lower than readState.Index' (duration: 54.287921ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T22:55:50.292861Z","caller":"traceutil/trace.go:171","msg":"trace[1223946767] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"166.673141ms","start":"2026-07-30T22:55:50.126139Z","end":"2026-07-30T22:55:50.292812Z","steps":["trace[1223946767] 'process raft request' (duration: 112.309178ms)","trace[1223946767] 'compare' (duration: 54.077045ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T22:55:50.293212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.889055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-07-30T22:55:50.293424Z","caller":"traceutil/trace.go:171","msg":"trace[1160639503] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:348; }","duration":"131.228715ms","start":"2026-07-30T22:55:50.162178Z","end":"2026-07-30T22:55:50.293407Z","steps":["trace[1160639503] 'agreement among raft nodes before linearized reading' (duration: 130.640459ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T22:56:18.279583Z","caller":"traceutil/trace.go:171","msg":"trace[2053971624] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"602.309717ms","start":"2026-07-30T22:56:17.67725Z","end":"2026-07-30T22:56:18.27956Z","steps":["trace[2053971624] 'process raft request' (duration: 602.157102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:18.280797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:17.677213Z","time spent":"603.522197ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T22:56:18.453254Z","caller":"traceutil/trace.go:171","msg":"trace[975221974] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"769.076051ms","start":"2026-07-30T22:56:17.684129Z","end":"2026-07-30T22:56:18.453205Z","steps":["trace[975221974] 'process raft request' (duration: 768.779311ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T22:56:18.453284Z","caller":"traceutil/trace.go:171","msg":"trace[1350331533] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"757.798694ms","start":"2026-07-30T22:56:17.695459Z","end":"2026-07-30T22:56:18.453257Z","steps":["trace[1350331533] 'process raft request' (duration: 757.6894ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T22:56:18.453325Z","caller":"traceutil/trace.go:171","msg":"trace[1324221594] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"755.103133ms","start":"2026-07-30T22:56:17.698213Z","end":"2026-07-30T22:56:18.453316Z","steps":["trace[1324221594] 'process raft request' (duration: 754.975099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:18.453392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:17.684097Z","time spent":"769.234796ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T22:56:18.4534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:17.695435Z","time spent":"757.898476ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T22:56:18.453266Z","caller":"traceutil/trace.go:171","msg":"trace[1134724196] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"765.380288ms","start":"2026-07-30T22:56:17.687845Z","end":"2026-07-30T22:56:18.453225Z","steps":["trace[1134724196] 'process raft request' (duration: 765.237673ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T22:56:18.453522Z","caller":"traceutil/trace.go:171","msg":"trace[1933869189] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"751.201853ms","start":"2026-07-30T22:56:17.702313Z","end":"2026-07-30T22:56:18.453515Z","steps":["trace[1933869189] 'process raft request' (duration: 750.996306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:18.453556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:17.702306Z","time spent":"751.232123ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T22:56:18.453399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:17.698205Z","time spent":"755.144164ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T22:56:18.453598Z","caller":"traceutil/trace.go:171","msg":"trace[89448207] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"748.74032ms","start":"2026-07-30T22:56:17.704835Z","end":"2026-07-30T22:56:18.453575Z","steps":["trace[89448207] 'process raft request' (duration: 748.513742ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T22:56:18.453608Z","caller":"traceutil/trace.go:171","msg":"trace[191009838] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"736.747529ms","start":"2026-07-30T22:56:17.716855Z","end":"2026-07-30T22:56:18.453603Z","steps":["trace[191009838] 'process raft request' (duration: 736.583973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:18.453635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:17.716841Z","time spent":"736.782909ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T22:56:18.453648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:17.70483Z","time spent":"748.793551ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T22:56:18.453771Z","caller":"traceutil/trace.go:171","msg":"trace[1855951252] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"733.739707ms","start":"2026-07-30T22:56:17.720018Z","end":"2026-07-30T22:56:18.453758Z","steps":["trace[1855951252] 'process raft request' (duration: 733.52613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:18.453823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:17.719993Z","time spent":"733.809169ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T22:56:18.453917Z","caller":"traceutil/trace.go:171","msg":"trace[786459770] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"529.977189ms","start":"2026-07-30T22:56:17.923929Z","end":"2026-07-30T22:56:18.453907Z","steps":["trace[786459770] 'process raft request' (duration: 529.859735ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T22:56:18.453897Z","caller":"traceutil/trace.go:171","msg":"trace[1558410803] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:531; }","duration":"435.628464ms","start":"2026-07-30T22:56:18.018238Z","end":"2026-07-30T22:56:18.453867Z","steps":["trace[1558410803] 'read index received' (duration: 261.510453ms)","trace[1558410803] 'applied index is now lower than readState.Index' (duration: 174.117191ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T22:56:18.453907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:17.687835Z","time spent":"765.682788ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T22:56:18.453971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:17.923875Z","time spent":"530.077071ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T22:56:18.454066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.81194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T22:56:18.454111Z","caller":"traceutil/trace.go:171","msg":"trace[144322220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"435.909964ms","start":"2026-07-30T22:56:18.018188Z","end":"2026-07-30T22:56:18.454098Z","steps":["trace[144322220] 'agreement among raft nodes before linearized reading' (duration: 435.77966ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T22:56:18.453993Z","caller":"traceutil/trace.go:171","msg":"trace[1453078126] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"681.776686ms","start":"2026-07-30T22:56:17.77213Z","end":"2026-07-30T22:56:18.453907Z","steps":["trace[1453078126] 'process raft request' (duration: 681.576699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:18.454147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:18.01817Z","time spent":"435.969836ms","remote":"127.0.0.1:34048","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-07-30T22:56:18.454309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:17.7721Z","time spent":"682.118817ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T22:56:18.453858Z","caller":"traceutil/trace.go:171","msg":"trace[702660895] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"752.126303ms","start":"2026-07-30T22:56:17.701308Z","end":"2026-07-30T22:56:18.453435Z","steps":["trace[702660895] 'process raft request' (duration: 751.920216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:18.454766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:17.701284Z","time spent":"753.422917ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T22:56:19.231108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:18.80159Z","time spent":"429.511163ms","remote":"127.0.0.1:43668","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-30T22:56:19.231802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.485867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T22:56:19.232083Z","caller":"traceutil/trace.go:171","msg":"trace[212494626] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"942.507534ms","start":"2026-07-30T22:56:18.289558Z","end":"2026-07-30T22:56:19.232066Z","steps":["trace[212494626] 'process raft request' (duration: 942.335238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:19.232173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:18.289545Z","time spent":"942.581476ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T22:56:19.232287Z","caller":"traceutil/trace.go:171","msg":"trace[1530836741] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:542; }","duration":"770.198363ms","start":"2026-07-30T22:56:18.46208Z","end":"2026-07-30T22:56:19.232278Z","steps":["trace[1530836741] 'read index received' (duration: 417.972741ms)","trace[1530836741] 'applied index is now lower than readState.Index' (duration: 352.224712ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T22:56:19.232428Z","caller":"traceutil/trace.go:171","msg":"trace[2036793604] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"945.240856ms","start":"2026-07-30T22:56:18.287177Z","end":"2026-07-30T22:56:19.232418Z","steps":["trace[2036793604] 'process raft request' (duration: 592.941712ms)","trace[2036793604] 'compare' (duration: 351.28826ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T22:56:19.23248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:18.287156Z","time spent":"945.304408ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T22:56:19.232517Z","caller":"traceutil/trace.go:171","msg":"trace[406533315] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"764.15035ms","start":"2026-07-30T22:56:18.468353Z","end":"2026-07-30T22:56:19.232504Z","steps":["trace[406533315] 'process raft request' (duration: 763.626032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:19.232614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:18.468336Z","time spent":"764.230722ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T22:56:19.232699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.617577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T22:56:19.232738Z","caller":"traceutil/trace.go:171","msg":"trace[475478282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"770.70137ms","start":"2026-07-30T22:56:18.462027Z","end":"2026-07-30T22:56:19.232729Z","steps":["trace[475478282] 'agreement among raft nodes before linearized reading' (duration: 770.287786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:19.232765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:18.462004Z","time spent":"770.755661ms","remote":"127.0.0.1:34038","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-07-30T22:56:19.233054Z","caller":"traceutil/trace.go:171","msg":"trace[435249919] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"761.667396ms","start":"2026-07-30T22:56:18.471378Z","end":"2026-07-30T22:56:19.233045Z","steps":["trace[435249919] 'process raft request' (duration: 760.962982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:19.23311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:18.471355Z","time spent":"761.732817ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T22:56:19.233356Z","caller":"traceutil/trace.go:171","msg":"trace[269517568] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"760.119063ms","start":"2026-07-30T22:56:18.473224Z","end":"2026-07-30T22:56:19.233343Z","steps":["trace[269517568] 'process raft request' (duration: 759.622846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:19.233423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:18.473217Z","time spent":"760.173446ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T22:56:19.233731Z","caller":"traceutil/trace.go:171","msg":"trace[581025056] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"759.826574ms","start":"2026-07-30T22:56:18.473891Z","end":"2026-07-30T22:56:19.233718Z","steps":["trace[581025056] 'process raft request' (duration: 759.023147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:19.233789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:18.473884Z","time spent":"759.881405ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T22:56:19.240035Z","caller":"traceutil/trace.go:171","msg":"trace[1760521838] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"763.516139ms","start":"2026-07-30T22:56:18.476477Z","end":"2026-07-30T22:56:19.239993Z","steps":["trace[1760521838] 'process raft request' (duration: 758.080265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:19.240197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:18.476464Z","time spent":"763.644654ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T22:56:19.241083Z","caller":"traceutil/trace.go:171","msg":"trace[1770799498] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"766.81555ms","start":"2026-07-30T22:56:18.474123Z","end":"2026-07-30T22:56:19.240939Z","steps":["trace[1770799498] 'process raft request' (duration: 758.82299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:19.241267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:18.474118Z","time spent":"766.995116ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T22:56:19.241524Z","caller":"traceutil/trace.go:171","msg":"trace[2140524034] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"764.021387ms","start":"2026-07-30T22:56:18.477476Z","end":"2026-07-30T22:56:19.241497Z","steps":["trace[2140524034] 'process raft request' (duration: 757.151934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:19.241712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:18.477444Z","time spent":"764.149661ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T22:56:19.242571Z","caller":"traceutil/trace.go:171","msg":"trace[1621233500] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"758.410607ms","start":"2026-07-30T22:56:18.484143Z","end":"2026-07-30T22:56:19.242554Z","steps":["trace[1621233500] 'process raft request' (duration: 750.570022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:19.242661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:18.484122Z","time spent":"758.492541ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T22:56:19.242735Z","caller":"traceutil/trace.go:171","msg":"trace[733502396] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"697.59425ms","start":"2026-07-30T22:56:18.545134Z","end":"2026-07-30T22:56:19.242728Z","steps":["trace[733502396] 'process raft request' (duration: 696.99739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:19.242816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:18.545105Z","time spent":"697.687934ms","remote":"127.0.0.1:34112","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-07-30T22:56:19.243097Z","caller":"traceutil/trace.go:171","msg":"trace[515819384] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"764.267005ms","start":"2026-07-30T22:56:18.478813Z","end":"2026-07-30T22:56:19.24308Z","steps":["trace[515819384] 'process raft request' (duration: 755.864721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:19.243228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:18.478801Z","time spent":"764.341887ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T22:56:25.623521Z","caller":"traceutil/trace.go:171","msg":"trace[1409867700] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"108.48254ms","start":"2026-07-30T22:56:25.515013Z","end":"2026-07-30T22:56:25.623495Z","steps":["trace[1409867700] 'read index received' (duration: 107.725654ms)","trace[1409867700] 'applied index is now lower than readState.Index' (duration: 755.926µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T22:56:25.623599Z","caller":"traceutil/trace.go:171","msg":"trace[1714684289] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"148.035341ms","start":"2026-07-30T22:56:25.475534Z","end":"2026-07-30T22:56:25.623569Z","steps":["trace[1714684289] 'process raft request' (duration: 147.411049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:25.623825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.7669ms","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-30T22:56:25.623896Z","caller":"traceutil/trace.go:171","msg":"trace[1258291525] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"108.877584ms","start":"2026-07-30T22:56:25.514981Z","end":"2026-07-30T22:56:25.623859Z","steps":["trace[1258291525] 'agreement among raft nodes before linearized reading' (duration: 108.648656ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T22:56:25.775997Z","caller":"traceutil/trace.go:171","msg":"trace[1492212123] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"143.39371ms","start":"2026-07-30T22:56:25.632547Z","end":"2026-07-30T22:56:25.775941Z","steps":["trace[1492212123] 'process raft request' (duration: 110.091066ms)","trace[1492212123] 'compare' (duration: 33.210021ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T22:56:25.867864Z","caller":"traceutil/trace.go:171","msg":"trace[369562897] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"183.161809ms","start":"2026-07-30T22:56:25.684684Z","end":"2026-07-30T22:56:25.867846Z","steps":["trace[369562897] 'process raft request' (duration: 182.91826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:26.378321Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221536138007374,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T22:56:26.448762Z","caller":"traceutil/trace.go:171","msg":"trace[21937812] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"577.06176ms","start":"2026-07-30T22:56:25.871668Z","end":"2026-07-30T22:56:26.44873Z","steps":["trace[21937812] 'process raft request' (duration: 521.812078ms)","trace[21937812] 'compare' (duration: 55.035565ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T22:56:26.448909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:25.871658Z","time spent":"577.178364ms","remote":"127.0.0.1:34338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T22:56:26.459834Z","caller":"traceutil/trace.go:171","msg":"trace[46211563] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"587.921608ms","start":"2026-07-30T22:56:25.871893Z","end":"2026-07-30T22:56:26.459815Z","steps":["trace[46211563] 'process raft request' (duration: 587.787643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:26.459957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:25.871887Z","time spent":"588.005141ms","remote":"127.0.0.1:34338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T22:56:26.460076Z","caller":"traceutil/trace.go:171","msg":"trace[1775517822] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:646; }","duration":"582.20312ms","start":"2026-07-30T22:56:25.877858Z","end":"2026-07-30T22:56:26.460061Z","steps":["trace[1775517822] 'read index received' (duration: 515.639194ms)","trace[1775517822] 'applied index is now lower than readState.Index' (duration: 66.562986ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T22:56:26.460181Z","caller":"traceutil/trace.go:171","msg":"trace[395904481] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"579.103802ms","start":"2026-07-30T22:56:25.88107Z","end":"2026-07-30T22:56:26.460173Z","steps":["trace[395904481] 'process raft request' (duration: 578.888315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:26.460216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:25.881061Z","time spent":"579.136594ms","remote":"127.0.0.1:34338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T22:56:26.460288Z","caller":"traceutil/trace.go:171","msg":"trace[1179879104] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"579.829618ms","start":"2026-07-30T22:56:25.880453Z","end":"2026-07-30T22:56:26.460283Z","steps":["trace[1179879104] 'process raft request' (duration: 579.31501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:26.460298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.431218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T22:56:26.460316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:25.880439Z","time spent":"579.862219ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T22:56:26.460379Z","caller":"traceutil/trace.go:171","msg":"trace[838245043] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:628; }","duration":"582.494181ms","start":"2026-07-30T22:56:25.87783Z","end":"2026-07-30T22:56:26.460325Z","steps":["trace[838245043] 'agreement among raft nodes before linearized reading' (duration: 582.436839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:26.46042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:25.877815Z","time spent":"582.595314ms","remote":"127.0.0.1:34302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-30T22:56:26.503505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.684429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T22:56:26.503655Z","caller":"traceutil/trace.go:171","msg":"trace[1998578042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"485.894776ms","start":"2026-07-30T22:56:26.017737Z","end":"2026-07-30T22:56:26.503632Z","steps":["trace[1998578042] 'agreement among raft nodes before linearized reading' (duration: 485.685769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:26.503755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:26.017719Z","time spent":"486.02025ms","remote":"127.0.0.1:34048","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-07-30T22:56:27.518885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221536138007382,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T22:56:28.019304Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221536138007382,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T22:56:28.21451Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.336232048s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T22:56:28.215599Z","caller":"traceutil/trace.go:171","msg":"trace[856249225] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"1.19692601s","start":"2026-07-30T22:56:27.018651Z","end":"2026-07-30T22:56:28.215577Z","steps":["trace[856249225] 'read index received' (duration: 1.196018488s)","trace[856249225] 'applied index is now lower than readState.Index' (duration: 906.591µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T22:56:28.215729Z","caller":"traceutil/trace.go:171","msg":"trace[1639193768] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.342677893s","start":"2026-07-30T22:56:26.873043Z","end":"2026-07-30T22:56:28.21572Z","steps":["trace[1639193768] 'process raft request' (duration: 1.341915536s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:28.215827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:26.873019Z","time spent":"1.342736905s","remote":"127.0.0.1:34160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T22:56:28.216067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197396906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T22:56:28.216144Z","caller":"traceutil/trace.go:171","msg":"trace[379707742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"1.19751582s","start":"2026-07-30T22:56:27.018607Z","end":"2026-07-30T22:56:28.216123Z","steps":["trace[379707742] 'agreement among raft nodes before linearized reading' (duration: 1.197365374s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:28.216163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.410489ms","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-30T22:56:28.216185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:27.01857Z","time spent":"1.197608364s","remote":"127.0.0.1:34048","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-07-30T22:56:28.216233Z","caller":"traceutil/trace.go:171","msg":"trace[1198332564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"436.532874ms","start":"2026-07-30T22:56:27.779682Z","end":"2026-07-30T22:56:28.216215Z","steps":["trace[1198332564] 'agreement among raft nodes before linearized reading' (duration: 436.316796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:28.216228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.751064ms","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-30T22:56:28.216366Z","caller":"traceutil/trace.go:171","msg":"trace[1662879585] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"526.984363ms","start":"2026-07-30T22:56:27.689335Z","end":"2026-07-30T22:56:28.216319Z","steps":["trace[1662879585] 'agreement among raft nodes before linearized reading' (duration: 526.570588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:28.216261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:27.779663Z","time spent":"436.592705ms","remote":"127.0.0.1:34338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T22:56:28.216809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:27.689317Z","time spent":"527.082465ms","remote":"127.0.0.1:34338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-30T22:56:30.238774Z","caller":"traceutil/trace.go:171","msg":"trace[582236238] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"145.364872ms","start":"2026-07-30T22:56:30.093389Z","end":"2026-07-30T22:56:30.238754Z","steps":["trace[582236238] 'process raft request' (duration: 145.228608ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T22:56:44.368399Z","caller":"traceutil/trace.go:171","msg":"trace[519352485] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"581.315129ms","start":"2026-07-30T22:56:43.787063Z","end":"2026-07-30T22:56:44.368378Z","steps":["trace[519352485] 'process raft request' (duration: 581.156893ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:44.368542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:43.787048Z","time spent":"581.424263ms","remote":"127.0.0.1:34338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T22:56:44.601739Z","caller":"traceutil/trace.go:171","msg":"trace[890804187] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:815; }","duration":"331.386147ms","start":"2026-07-30T22:56:44.270337Z","end":"2026-07-30T22:56:44.601723Z","steps":["trace[890804187] 'read index received' (duration: 97.89888ms)","trace[890804187] 'applied index is now lower than readState.Index' (duration: 233.486057ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T22:56:44.601941Z","caller":"traceutil/trace.go:171","msg":"trace[1776390028] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"612.318403ms","start":"2026-07-30T22:56:43.989555Z","end":"2026-07-30T22:56:44.601873Z","steps":["trace[1776390028] 'process raft request' (duration: 612.058743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:44.60205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:43.989534Z","time spent":"612.435756ms","remote":"127.0.0.1:34302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T22:56:44.60226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.908356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T22:56:44.602662Z","caller":"traceutil/trace.go:171","msg":"trace[502133300] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:789; }","duration":"332.28845ms","start":"2026-07-30T22:56:44.27029Z","end":"2026-07-30T22:56:44.602578Z","steps":["trace[502133300] 'agreement among raft nodes before linearized reading' (duration: 331.878035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:44.603313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:56:44.270273Z","time spent":"333.021077ms","remote":"127.0.0.1:34416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":67,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-07-30T22:56:44.602325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.304092ms","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-30T22:56:44.603535Z","caller":"traceutil/trace.go:171","msg":"trace[26112933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:789; }","duration":"288.549218ms","start":"2026-07-30T22:56:44.314969Z","end":"2026-07-30T22:56:44.603518Z","steps":["trace[26112933] 'agreement among raft nodes before linearized reading' (duration: 287.27023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:56:44.603738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.249341ms","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-30T22:56:44.603777Z","caller":"traceutil/trace.go:171","msg":"trace[909643966] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:789; }","duration":"296.311374ms","start":"2026-07-30T22:56:44.307457Z","end":"2026-07-30T22:56:44.603768Z","steps":["trace[909643966] 'agreement among raft nodes before linearized reading' (duration: 294.700794ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T22:57:42.888816Z","caller":"traceutil/trace.go:171","msg":"trace[1058740980] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"218.991433ms","start":"2026-07-30T22:57:42.669729Z","end":"2026-07-30T22:57:42.88872Z","steps":["trace[1058740980] 'process raft request' (duration: 218.720672ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T22:57:43.186674Z","caller":"traceutil/trace.go:171","msg":"trace[972920852] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"156.949747ms","start":"2026-07-30T22:57:43.0297Z","end":"2026-07-30T22:57:43.186649Z","steps":["trace[972920852] 'process raft request' (duration: 81.790419ms)","trace[972920852] 'compare' (duration: 75.050184ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T22:58:42.910565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.680098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T22:58:42.910712Z","caller":"traceutil/trace.go:171","msg":"trace[1478827394] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1351; }","duration":"258.895127ms","start":"2026-07-30T22:58:42.651785Z","end":"2026-07-30T22:58:42.910681Z","steps":["trace[1478827394] 'count revisions from in-memory index tree' (duration: 258.551633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:58:42.910604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.78683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T22:58:42.910806Z","caller":"traceutil/trace.go:171","msg":"trace[1858291042] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1351; }","duration":"145.056482ms","start":"2026-07-30T22:58:42.765732Z","end":"2026-07-30T22:58:42.910789Z","steps":["trace[1858291042] 'count revisions from in-memory index tree' (duration: 144.684697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:58:43.924369Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221536138009079,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T22:58:44.01352Z","caller":"traceutil/trace.go:171","msg":"trace[7408028] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"593.31309ms","start":"2026-07-30T22:58:43.420164Z","end":"2026-07-30T22:58:44.013477Z","steps":["trace[7408028] 'process raft request' (duration: 588.607121ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T22:58:44.013554Z","caller":"traceutil/trace.go:171","msg":"trace[1167045796] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1409; }","duration":"590.809414ms","start":"2026-07-30T22:58:43.422705Z","end":"2026-07-30T22:58:44.013515Z","steps":["trace[1167045796] 'read index received' (duration: 585.923667ms)","trace[1167045796] 'applied index is now lower than readState.Index' (duration: 4.884457ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T22:58:44.013716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:58:43.420134Z","time spent":"593.488458ms","remote":"127.0.0.1:34338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T22:58:44.013796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.200314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T22:58:44.013842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.400591ms","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-30T22:58:44.01386Z","caller":"traceutil/trace.go:171","msg":"trace[1732060769] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1355; }","duration":"565.320689ms","start":"2026-07-30T22:58:43.448523Z","end":"2026-07-30T22:58:44.013844Z","steps":["trace[1732060769] 'agreement among raft nodes before linearized reading' (duration: 565.214674ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T22:58:44.013751Z","caller":"traceutil/trace.go:171","msg":"trace[268273732] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"592.893472ms","start":"2026-07-30T22:58:43.420698Z","end":"2026-07-30T22:58:44.013592Z","steps":["trace[268273732] 'process raft request' (duration: 592.667323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:58:44.013896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.512972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T22:58:44.013982Z","caller":"traceutil/trace.go:171","msg":"trace[1432175075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1355; }","duration":"437.659758ms","start":"2026-07-30T22:58:43.576306Z","end":"2026-07-30T22:58:44.013966Z","steps":["trace[1432175075] 'agreement among raft nodes before linearized reading' (duration: 437.439579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:58:44.014021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:58:43.576278Z","time spent":"437.735692ms","remote":"127.0.0.1:34338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T22:58:44.01392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:58:43.448482Z","time spent":"565.422903ms","remote":"127.0.0.1:34198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-07-30T22:58:44.013908Z","caller":"traceutil/trace.go:171","msg":"trace[586710504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1355; }","duration":"592.499566ms","start":"2026-07-30T22:58:43.421391Z","end":"2026-07-30T22:58:44.013891Z","steps":["trace[586710504] 'agreement among raft nodes before linearized reading' (duration: 592.293827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T22:58:44.014096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:58:43.421379Z","time spent":"592.701684ms","remote":"127.0.0.1:34338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T22:58:44.014265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T22:58:43.420673Z","time spent":"593.31865ms","remote":"127.0.0.1:34338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T22:58:44.201539Z","caller":"traceutil/trace.go:171","msg":"trace[1665915888] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"178.99542ms","start":"2026-07-30T22:58:44.022509Z","end":"2026-07-30T22:58:44.201505Z","steps":["trace[1665915888] 'process raft request' (duration: 98.911137ms)","trace[1665915888] 'compare' (duration: 79.932087ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T22:58:44.53589Z","caller":"traceutil/trace.go:171","msg":"trace[728047900] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"213.696036ms","start":"2026-07-30T22:58:44.322165Z","end":"2026-07-30T22:58:44.535861Z","steps":["trace[728047900] 'process raft request' (duration: 153.19853ms)","trace[728047900] 'compare' (duration: 59.862219ms)"],"step_count":2}