{"level":"warn","ts":"2026-07-09T03:46:28.809015Z","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-09T03:46:28.809162Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.181:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.181:2380","--initial-cluster=instance=https://199.19.213.181:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.181:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.181: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-09T03:46:28.809246Z","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-09T03:46:28.809263Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.181:2380"]} {"level":"info","ts":"2026-07-09T03:46:28.809289Z","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-09T03:46:28.810158Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"]} {"level":"info","ts":"2026-07-09T03:46:28.811837Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.181:2380"],"listen-peer-urls":["https://199.19.213.181:2380"],"advertise-client-urls":["https://199.19.213.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.181: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-09T03:46:28.818556Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.418538ms"} {"level":"info","ts":"2026-07-09T03:46:28.828104Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1a532052a1358225","cluster-id":"b34324311008a1d3"} {"level":"info","ts":"2026-07-09T03:46:28.82818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T03:46:28.828206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became follower at term 0"} {"level":"info","ts":"2026-07-09T03:46:28.828219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1a532052a1358225 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T03:46:28.828231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became follower at term 1"} {"level":"info","ts":"2026-07-09T03:46:28.828261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=(1896895407317156389)"} {"level":"warn","ts":"2026-07-09T03:46:28.835888Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T03:46:28.83731Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T03:46:28.838379Z","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-09T03:46:28.841049Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1a532052a1358225","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T03:46:28.841277Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1a532052a1358225","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T03:46:28.843944Z","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-09T03:46:28.84492Z","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-09T03:46:28.845071Z","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-09T03:46:28.845439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=(1896895407317156389)"} {"level":"info","ts":"2026-07-09T03:46:28.84572Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b34324311008a1d3","local-member-id":"1a532052a1358225","added-peer-id":"1a532052a1358225","added-peer-peer-urls":["https://199.19.213.181:2380"]} {"level":"info","ts":"2026-07-09T03:46:28.848852Z","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-09T03:46:28.848962Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.181:2380"} {"level":"info","ts":"2026-07-09T03:46:28.849028Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.181:2380"} {"level":"info","ts":"2026-07-09T03:46:28.849222Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1a532052a1358225","initial-advertise-peer-urls":["https://199.19.213.181:2380"],"listen-peer-urls":["https://199.19.213.181:2380"],"advertise-client-urls":["https://199.19.213.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T03:46:28.849261Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T03:46:28.929006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T03:46:28.929115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T03:46:28.929163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 received MsgPreVoteResp from 1a532052a1358225 at term 1"} {"level":"info","ts":"2026-07-09T03:46:28.929227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became candidate at term 2"} {"level":"info","ts":"2026-07-09T03:46:28.929258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 received MsgVoteResp from 1a532052a1358225 at term 2"} {"level":"info","ts":"2026-07-09T03:46:28.929293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became leader at term 2"} {"level":"info","ts":"2026-07-09T03:46:28.929321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1a532052a1358225 elected leader 1a532052a1358225 at term 2"} {"level":"info","ts":"2026-07-09T03:46:28.930214Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T03:46:28.931202Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T03:46:28.931253Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T03:46:28.931203Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1a532052a1358225","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.181:2379]}","request-path":"/0/members/1a532052a1358225/attributes","cluster-id":"b34324311008a1d3","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T03:46:28.931457Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T03:46:28.931589Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T03:46:28.932016Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b34324311008a1d3","local-member-id":"1a532052a1358225","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T03:46:28.932486Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T03:46:28.932532Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T03:46:28.935136Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T03:46:28.938089Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.181:2379"} {"level":"info","ts":"2026-07-09T03:46:32.280046Z","caller":"traceutil/trace.go:171","msg":"trace[1439006322] transaction","detail":"{read_only:false; response_revision:180; number_of_response:1; }","duration":"308.427783ms","start":"2026-07-09T03:46:31.971587Z","end":"2026-07-09T03:46:32.280015Z","steps":["trace[1439006322] 'process raft request' (duration: 269.996947ms)","trace[1439006322] 'compare' (duration: 38.295193ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:46:32.280951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:46:31.971572Z","time spent":"308.894595ms","remote":"127.0.0.1:46826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":610,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T03:46:32.844323Z","caller":"traceutil/trace.go:171","msg":"trace[563584471] linearizableReadLoop","detail":"{readStateIndex:184; appliedIndex:183; }","duration":"221.792556ms","start":"2026-07-09T03:46:32.622486Z","end":"2026-07-09T03:46:32.844278Z","steps":["trace[563584471] 'read index received' (duration: 208.661163ms)","trace[563584471] 'applied index is now lower than readState.Index' (duration: 13.130483ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:46:32.844475Z","caller":"traceutil/trace.go:171","msg":"trace[1684291009] transaction","detail":"{read_only:false; response_revision:181; number_of_response:1; }","duration":"556.048705ms","start":"2026-07-09T03:46:32.288395Z","end":"2026-07-09T03:46:32.844444Z","steps":["trace[1684291009] 'process raft request' (duration: 542.811239ms)","trace[1684291009] 'compare' (duration: 12.952638ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:46:32.84446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.950969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T03:46:32.84456Z","caller":"traceutil/trace.go:171","msg":"trace[1119229923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:181; }","duration":"222.102124ms","start":"2026-07-09T03:46:32.622445Z","end":"2026-07-09T03:46:32.844547Z","steps":["trace[1119229923] 'agreement among raft nodes before linearized reading' (duration: 221.947089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:46:32.844585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:46:32.288377Z","time spent":"556.158688ms","remote":"127.0.0.1:46826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":655,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T03:46:33.052843Z","caller":"traceutil/trace.go:171","msg":"trace[526346990] transaction","detail":"{read_only:false; response_revision:183; number_of_response:1; }","duration":"109.63569ms","start":"2026-07-09T03:46:32.943177Z","end":"2026-07-09T03:46:33.052813Z","steps":["trace[526346990] 'process raft request' (duration: 100.744846ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:46:36.195464Z","caller":"traceutil/trace.go:171","msg":"trace[1511400163] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:284; }","duration":"107.085384ms","start":"2026-07-09T03:46:36.08836Z","end":"2026-07-09T03:46:36.195445Z","steps":["trace[1511400163] 'read index received' (duration: 73.086003ms)","trace[1511400163] 'applied index is now lower than readState.Index' (duration: 33.998621ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:46:36.195615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.235797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-09T03:46:36.195577Z","caller":"traceutil/trace.go:171","msg":"trace[713512203] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"111.485858ms","start":"2026-07-09T03:46:36.084045Z","end":"2026-07-09T03:46:36.19553Z","steps":["trace[713512203] 'process raft request' (duration: 77.441596ms)","trace[713512203] 'compare' (duration: 33.855837ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:46:36.195642Z","caller":"traceutil/trace.go:171","msg":"trace[1887223211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:277; }","duration":"107.300498ms","start":"2026-07-09T03:46:36.088332Z","end":"2026-07-09T03:46:36.195633Z","steps":["trace[1887223211] 'agreement among raft nodes before linearized reading' (duration: 107.231097ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:08.129322Z","caller":"traceutil/trace.go:171","msg":"trace[2127393148] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"100.599557ms","start":"2026-07-09T03:47:08.028699Z","end":"2026-07-09T03:47:08.129299Z","steps":["trace[2127393148] 'process raft request' (duration: 100.430732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:08.533257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.008878ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-09T03:47:11.558063Z","caller":"traceutil/trace.go:171","msg":"trace[802490521] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"101.757297ms","start":"2026-07-09T03:47:11.456254Z","end":"2026-07-09T03:47:11.558012Z","steps":["trace[802490521] 'process raft request' (duration: 40.572335ms)","trace[802490521] 'compare' (duration: 60.674428ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:47:13.008256Z","caller":"traceutil/trace.go:171","msg":"trace[1377514340] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"127.420821ms","start":"2026-07-09T03:47:12.880816Z","end":"2026-07-09T03:47:13.008237Z","steps":["trace[1377514340] 'process raft request' (duration: 127.310907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:13.603177Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378076917702156413,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T03:47:14.103386Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378076917702156413,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T03:47:14.604642Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378076917702156413,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T03:47:14.748343Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.65371456s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T03:47:14.748702Z","caller":"traceutil/trace.go:171","msg":"trace[978497155] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.654166711s","start":"2026-07-09T03:47:13.094516Z","end":"2026-07-09T03:47:14.748683Z","steps":["trace[978497155] 'process raft request' (duration: 1.654076618s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:14.74879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:13.094497Z","time spent":"1.654240374s","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T03:47:15.22635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00039862s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-09T03:47:15.226413Z","caller":"traceutil/trace.go:171","msg":"trace[1948721966] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000507603s","start":"2026-07-09T03:47:13.22589Z","end":"2026-07-09T03:47:15.226398Z","steps":["trace[1948721966] 'agreement among raft nodes before linearized reading' (duration: 2.000429191s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:15.226447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:13.225866Z","time spent":"2.000575005s","remote":"127.0.0.1:46490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/09 03:47:15 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-07-09T03:47:15.265934Z","caller":"traceutil/trace.go:171","msg":"trace[295322669] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"2.170704093s","start":"2026-07-09T03:47:13.095202Z","end":"2026-07-09T03:47:15.265906Z","steps":["trace[295322669] 'process raft request' (duration: 2.170497148s)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:15.266003Z","caller":"traceutil/trace.go:171","msg":"trace[64993712] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"2.160481853s","start":"2026-07-09T03:47:13.105494Z","end":"2026-07-09T03:47:15.265976Z","steps":["trace[64993712] 'process raft request' (duration: 2.16038135s)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:15.266012Z","caller":"traceutil/trace.go:171","msg":"trace[1787145322] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"2.17054186s","start":"2026-07-09T03:47:13.095424Z","end":"2026-07-09T03:47:15.265965Z","steps":["trace[1787145322] 'process raft request' (duration: 2.170417407s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:15.266065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:13.09518Z","time spent":"2.170827988s","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T03:47:15.266114Z","caller":"traceutil/trace.go:171","msg":"trace[1651957889] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"2.160510174s","start":"2026-07-09T03:47:13.105566Z","end":"2026-07-09T03:47:15.266076Z","steps":["trace[1651957889] 'process raft request' (duration: 2.160374189s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:15.266146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:13.105474Z","time spent":"2.160584866s","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T03:47:15.266102Z","caller":"traceutil/trace.go:171","msg":"trace[452986478] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"2.160545425s","start":"2026-07-09T03:47:13.105496Z","end":"2026-07-09T03:47:15.266041Z","steps":["trace[452986478] 'process raft request' (duration: 2.16041031s)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:15.266189Z","caller":"traceutil/trace.go:171","msg":"trace[1818292149] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"2.159519098s","start":"2026-07-09T03:47:13.106664Z","end":"2026-07-09T03:47:15.266183Z","steps":["trace[1818292149] 'process raft request' (duration: 2.159323734s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:15.26619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:13.095415Z","time spent":"2.170689124s","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T03:47:15.266217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:13.105561Z","time spent":"2.160606096s","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T03:47:15.266248Z","caller":"traceutil/trace.go:171","msg":"trace[860217178] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"2.159531228s","start":"2026-07-09T03:47:13.10671Z","end":"2026-07-09T03:47:15.266241Z","steps":["trace[860217178] 'process raft request' (duration: 2.159336073s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:15.266278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:13.106706Z","time spent":"2.159559699s","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T03:47:15.266296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:13.106654Z","time spent":"2.159557539s","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T03:47:15.266323Z","caller":"traceutil/trace.go:171","msg":"trace[1703147713] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"2.150977101s","start":"2026-07-09T03:47:13.115339Z","end":"2026-07-09T03:47:15.266316Z","steps":["trace[1703147713] 'process raft request' (duration: 2.150757695s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:15.266312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:13.105483Z","time spent":"2.160734099s","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T03:47:15.266344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:13.115327Z","time spent":"2.151006112s","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T03:47:15.266371Z","caller":"traceutil/trace.go:171","msg":"trace[631661016] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"2.140763601s","start":"2026-07-09T03:47:13.125591Z","end":"2026-07-09T03:47:15.266354Z","steps":["trace[631661016] 'process raft request' (duration: 2.140555456s)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:15.26642Z","caller":"traceutil/trace.go:171","msg":"trace[555619419] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:520; }","duration":"2.164253383s","start":"2026-07-09T03:47:13.102156Z","end":"2026-07-09T03:47:15.266409Z","steps":["trace[555619419] 'read index received' (duration: 1.646913549s)","trace[555619419] 'applied index is now lower than readState.Index' (duration: 517.339344ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:47:15.26642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:13.125583Z","time spent":"2.140815322s","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T03:47:15.266467Z","caller":"traceutil/trace.go:171","msg":"trace[1637959468] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"2.129287545s","start":"2026-07-09T03:47:13.137174Z","end":"2026-07-09T03:47:15.266461Z","steps":["trace[1637959468] 'process raft request' (duration: 2.129106971s)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:15.266482Z","caller":"traceutil/trace.go:171","msg":"trace[1894612804] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"2.008746021s","start":"2026-07-09T03:47:13.257723Z","end":"2026-07-09T03:47:15.266469Z","steps":["trace[1894612804] 'process raft request' (duration: 2.008612227s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:15.266505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:13.137152Z","time spent":"2.129328427s","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T03:47:15.266525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.164365846s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-09T03:47:15.266546Z","caller":"traceutil/trace.go:171","msg":"trace[1200920600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:515; }","duration":"2.164411256s","start":"2026-07-09T03:47:13.102129Z","end":"2026-07-09T03:47:15.26654Z","steps":["trace[1200920600] 'agreement among raft nodes before linearized reading' (duration: 2.164337615s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:15.266532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:13.257693Z","time spent":"2.008815343s","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T03:47:15.266563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:13.102118Z","time spent":"2.164441678s","remote":"127.0.0.1:46768","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-09T03:47:15.558711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.102027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T03:47:15.55898Z","caller":"traceutil/trace.go:171","msg":"trace[818160662] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:531; }","duration":"292.519075ms","start":"2026-07-09T03:47:15.266444Z","end":"2026-07-09T03:47:15.558963Z","steps":["trace[818160662] 'read index received' (duration: 98.274916ms)","trace[818160662] 'applied index is now lower than readState.Index' (duration: 194.243219ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:47:15.558964Z","caller":"traceutil/trace.go:171","msg":"trace[550020439] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"803.587113ms","start":"2026-07-09T03:47:14.755334Z","end":"2026-07-09T03:47:15.558921Z","steps":["trace[550020439] 'process raft request' (duration: 609.209699ms)","trace[550020439] 'compare' (duration: 193.940982ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:47:15.559133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.434380704s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.181\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-09T03:47:15.559135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:14.755313Z","time spent":"803.751446ms","remote":"127.0.0.1:47052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T03:47:15.559168Z","caller":"traceutil/trace.go:171","msg":"trace[1745127598] range","detail":"{range_begin:/registry/masterleases/199.19.213.181; range_end:; response_count:1; response_revision:517; }","duration":"2.434439366s","start":"2026-07-09T03:47:13.124719Z","end":"2026-07-09T03:47:15.559158Z","steps":["trace[1745127598] 'agreement among raft nodes before linearized reading' (duration: 2.434301832s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:15.559216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:13.124709Z","time spent":"2.434498327s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.181\" "} {"level":"info","ts":"2026-07-09T03:47:15.55923Z","caller":"traceutil/trace.go:171","msg":"trace[1640035082] transaction","detail":"{read_only:false; number_of_response:1; response_revision:517; }","duration":"801.151958ms","start":"2026-07-09T03:47:14.758046Z","end":"2026-07-09T03:47:15.559198Z","steps":["trace[1640035082] 'process raft request' (duration: 800.879691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:15.56068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:14.758015Z","time spent":"802.386571ms","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:15.559282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.489834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T03:47:15.560769Z","caller":"traceutil/trace.go:171","msg":"trace[911745762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"334.046366ms","start":"2026-07-09T03:47:15.226692Z","end":"2026-07-09T03:47:15.560738Z","steps":["trace[911745762] 'agreement among raft nodes before linearized reading' (duration: 332.408222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:15.560877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.226675Z","time spent":"334.157109ms","remote":"127.0.0.1:46484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T03:47:15.559911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154802132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T03:47:15.560961Z","caller":"traceutil/trace.go:171","msg":"trace[995788359] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"1.155876971s","start":"2026-07-09T03:47:14.405058Z","end":"2026-07-09T03:47:15.560935Z","steps":["trace[995788359] 'agreement among raft nodes before linearized reading' (duration: 1.154141225s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:15.561003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:14.405041Z","time spent":"1.155952544s","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T03:47:15.560189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.284322ms","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-09T03:47:15.561374Z","caller":"traceutil/trace.go:171","msg":"trace[1054848821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"547.498864ms","start":"2026-07-09T03:47:15.013857Z","end":"2026-07-09T03:47:15.561356Z","steps":["trace[1054848821] 'agreement among raft nodes before linearized reading' (duration: 546.276272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:15.561412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.013836Z","time spent":"547.565126ms","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-09T03:47:15.559143Z","caller":"traceutil/trace.go:171","msg":"trace[631580991] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"801.085116ms","start":"2026-07-09T03:47:14.758046Z","end":"2026-07-09T03:47:15.559131Z","steps":["trace[631580991] 'process raft request' (duration: 800.764098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:15.561899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:14.758015Z","time spent":"803.837269ms","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:15.561818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412068753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-09T03:47:15.566179Z","caller":"traceutil/trace.go:171","msg":"trace[398811367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"1.417817465s","start":"2026-07-09T03:47:14.148319Z","end":"2026-07-09T03:47:15.566137Z","steps":["trace[398811367] 'agreement among raft nodes before linearized reading' (duration: 1.410933063s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:15.56637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:14.1483Z","time spent":"1.417981629s","remote":"127.0.0.1:46768","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":"info","ts":"2026-07-09T03:47:15.584545Z","caller":"traceutil/trace.go:171","msg":"trace[1269018624] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"313.09308ms","start":"2026-07-09T03:47:15.271427Z","end":"2026-07-09T03:47:15.58452Z","steps":["trace[1269018624] 'process raft request' (duration: 312.827373ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:15.584758Z","caller":"traceutil/trace.go:171","msg":"trace[1071766352] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"312.538556ms","start":"2026-07-09T03:47:15.272183Z","end":"2026-07-09T03:47:15.584722Z","steps":["trace[1071766352] 'process raft request' (duration: 312.248077ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:15.584832Z","caller":"traceutil/trace.go:171","msg":"trace[436301295] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"307.205844ms","start":"2026-07-09T03:47:15.277592Z","end":"2026-07-09T03:47:15.584797Z","steps":["trace[436301295] 'process raft request' (duration: 307.056621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:15.58494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.272172Z","time spent":"312.669829ms","remote":"127.0.0.1:47052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T03:47:15.584961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.27758Z","time spent":"307.307957ms","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T03:47:15.585102Z","caller":"traceutil/trace.go:171","msg":"trace[1223223931] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"287.216804ms","start":"2026-07-09T03:47:15.297865Z","end":"2026-07-09T03:47:15.585082Z","steps":["trace[1223223931] 'process raft request' (duration: 287.112981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:15.585145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.271406Z","time spent":"313.308716ms","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T03:47:15.585304Z","caller":"traceutil/trace.go:171","msg":"trace[96317304] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"300.534398ms","start":"2026-07-09T03:47:15.284753Z","end":"2026-07-09T03:47:15.585288Z","steps":["trace[96317304] 'process raft request' (duration: 300.025094ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:15.585323Z","caller":"traceutil/trace.go:171","msg":"trace[878263994] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"297.281061ms","start":"2026-07-09T03:47:15.288025Z","end":"2026-07-09T03:47:15.585306Z","steps":["trace[878263994] 'process raft request' (duration: 296.810828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:15.585412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.284731Z","time spent":"300.623929ms","remote":"127.0.0.1:47052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T03:47:15.585446Z","caller":"traceutil/trace.go:171","msg":"trace[99112612] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"289.385212ms","start":"2026-07-09T03:47:15.296048Z","end":"2026-07-09T03:47:15.585434Z","steps":["trace[99112612] 'process raft request' (duration: 288.866408ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:15.58611Z","caller":"traceutil/trace.go:171","msg":"trace[1047040347] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"254.592209ms","start":"2026-07-09T03:47:15.331501Z","end":"2026-07-09T03:47:15.586093Z","steps":["trace[1047040347] 'process raft request' (duration: 254.503147ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:15.586155Z","caller":"traceutil/trace.go:171","msg":"trace[494800516] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"279.14667ms","start":"2026-07-09T03:47:15.306988Z","end":"2026-07-09T03:47:15.586135Z","steps":["trace[494800516] 'process raft request' (duration: 278.03526ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:15.586603Z","caller":"traceutil/trace.go:171","msg":"trace[1367051649] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"307.47083ms","start":"2026-07-09T03:47:15.277999Z","end":"2026-07-09T03:47:15.585469Z","steps":["trace[1367051649] 'process raft request' (duration: 306.740681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:15.586757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.277984Z","time spent":"308.684953ms","remote":"127.0.0.1:47052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T03:47:17.570732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000379779s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-09T03:47:17.570896Z","caller":"traceutil/trace.go:171","msg":"trace[566099605] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000574213s","start":"2026-07-09T03:47:15.570301Z","end":"2026-07-09T03:47:17.570875Z","steps":["trace[566099605] 'agreement among raft nodes before linearized reading' (duration: 2.000395199s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:17.571045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.570293Z","time spent":"2.000738207s","remote":"127.0.0.1:46490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/09 03:47:17 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-09T03:47:17.847483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.646318072s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T03:47:17.847641Z","caller":"traceutil/trace.go:171","msg":"trace[738766302] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:527; }","duration":"1.646498067s","start":"2026-07-09T03:47:16.20112Z","end":"2026-07-09T03:47:17.847618Z","steps":["trace[738766302] 'range keys from in-memory index tree' (duration: 1.646300632s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:17.847996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.676656356s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-09T03:47:17.848448Z","caller":"traceutil/trace.go:171","msg":"trace[904799778] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:534; }","duration":"2.27810666s","start":"2026-07-09T03:47:15.570324Z","end":"2026-07-09T03:47:17.848431Z","steps":["trace[904799778] 'read index received' (duration: 14.027902ms)","trace[904799778] 'applied index is now lower than readState.Index' (duration: 2.264077988s)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:47:17.848512Z","caller":"traceutil/trace.go:171","msg":"trace[1775260362] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"2.279742793s","start":"2026-07-09T03:47:15.568727Z","end":"2026-07-09T03:47:17.84847Z","steps":["trace[1775260362] 'process raft request' (duration: 2.279500387s)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:17.848526Z","caller":"traceutil/trace.go:171","msg":"trace[1156972451] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"2.278636193s","start":"2026-07-09T03:47:15.569872Z","end":"2026-07-09T03:47:17.848508Z","steps":["trace[1156972451] 'process raft request' (duration: 2.278387697s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:17.848599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.568274Z","time spent":"2.280271797s","remote":"127.0.0.1:46564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T03:47:17.848645Z","caller":"traceutil/trace.go:171","msg":"trace[1103504963] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"2.27353854s","start":"2026-07-09T03:47:15.575098Z","end":"2026-07-09T03:47:17.848636Z","steps":["trace[1103504963] 'process raft request' (duration: 2.273243731s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:17.848698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.575085Z","time spent":"2.2735871s","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T03:47:17.848803Z","caller":"traceutil/trace.go:171","msg":"trace[583144059] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"2.281543841s","start":"2026-07-09T03:47:15.56725Z","end":"2026-07-09T03:47:17.848794Z","steps":["trace[583144059] 'process raft request' (duration: 2.280841403s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:17.848609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.56986Z","time spent":"2.278695774s","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:17.848685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.561884Z","time spent":"2.286796131s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-09T03:47:17.848903Z","caller":"traceutil/trace.go:171","msg":"trace[27205981] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"2.280904794s","start":"2026-07-09T03:47:15.567989Z","end":"2026-07-09T03:47:17.848894Z","steps":["trace[27205981] 'process raft request' (duration: 2.280205665s)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:17.848634Z","caller":"traceutil/trace.go:171","msg":"trace[885259733] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"2.276900728s","start":"2026-07-09T03:47:15.571718Z","end":"2026-07-09T03:47:17.848619Z","steps":["trace[885259733] 'process raft request' (duration: 2.276571319s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:17.84895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.567961Z","time spent":"2.280966416s","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:17.848857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.567232Z","time spent":"2.281600052s","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:17.848978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.571708Z","time spent":"2.277230297s","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:18.349217Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378076917702156456,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T03:47:18.388561Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.704980487s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T03:47:18.389147Z","caller":"traceutil/trace.go:171","msg":"trace[1380089101] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"2.765713636s","start":"2026-07-09T03:47:15.623399Z","end":"2026-07-09T03:47:18.389113Z","steps":["trace[1380089101] 'process raft request' (duration: 2.765376277s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:18.389287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.623382Z","time spent":"2.765825289s","remote":"127.0.0.1:47052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T03:47:18.389446Z","caller":"traceutil/trace.go:171","msg":"trace[1180799263] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"2.752130046s","start":"2026-07-09T03:47:15.637284Z","end":"2026-07-09T03:47:18.389414Z","steps":["trace[1180799263] 'process raft request' (duration: 2.751779586s)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:18.389579Z","caller":"traceutil/trace.go:171","msg":"trace[1677254283] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"2.65083879s","start":"2026-07-09T03:47:15.738705Z","end":"2026-07-09T03:47:18.389543Z","steps":["trace[1677254283] 'process raft request' (duration: 2.650788229s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:18.389632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.637259Z","time spent":"2.752258209s","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T03:47:18.389657Z","caller":"traceutil/trace.go:171","msg":"trace[1990443269] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"2.722826779s","start":"2026-07-09T03:47:15.666803Z","end":"2026-07-09T03:47:18.38963Z","steps":["trace[1990443269] 'process raft request' (duration: 2.722517191s)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:18.389675Z","caller":"traceutil/trace.go:171","msg":"trace[1904823219] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"2.720730084s","start":"2026-07-09T03:47:15.668922Z","end":"2026-07-09T03:47:18.389652Z","steps":["trace[1904823219] 'process raft request' (duration: 2.720517458s)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:18.389687Z","caller":"traceutil/trace.go:171","msg":"trace[1744648324] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"2.751698254s","start":"2026-07-09T03:47:15.637972Z","end":"2026-07-09T03:47:18.38967Z","steps":["trace[1744648324] 'process raft request' (duration: 2.751242233s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:18.389718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.73868Z","time spent":"2.650962813s","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:18.389768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.666776Z","time spent":"2.722934652s","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:18.389765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.668878Z","time spent":"2.720842286s","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T03:47:18.389786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:15.637947Z","time spent":"2.751777365s","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:18.694958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.497843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T03:47:18.695934Z","caller":"traceutil/trace.go:171","msg":"trace[1938098038] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"845.35979ms","start":"2026-07-09T03:47:17.850515Z","end":"2026-07-09T03:47:18.695875Z","steps":["trace[1938098038] 'process raft request' (duration: 651.846709ms)","trace[1938098038] 'compare' (duration: 192.339839ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:47:18.696032Z","caller":"traceutil/trace.go:171","msg":"trace[1063518127] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"839.252238ms","start":"2026-07-09T03:47:17.856752Z","end":"2026-07-09T03:47:18.696004Z","steps":["trace[1063518127] 'process raft request' (duration: 838.325534ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:18.696068Z","caller":"traceutil/trace.go:171","msg":"trace[956040650] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"838.274561ms","start":"2026-07-09T03:47:17.857774Z","end":"2026-07-09T03:47:18.696049Z","steps":["trace[956040650] 'process raft request' (duration: 837.374408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:18.696136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:17.856735Z","time spent":"839.35827ms","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:18.696168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:17.857767Z","time spent":"838.352063ms","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:18.696239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:17.850502Z","time spent":"845.570736ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:18.983778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.557619ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-09T03:47:18.984212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.393574613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-09T03:47:18.985099Z","caller":"traceutil/trace.go:171","msg":"trace[1091518714] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:542; }","duration":"1.394454326s","start":"2026-07-09T03:47:17.590616Z","end":"2026-07-09T03:47:18.98507Z","steps":["trace[1091518714] 'agreement among raft nodes before linearized reading' (duration: 1.393539861s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:18.985177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:17.590564Z","time spent":"1.394589249s","remote":"127.0.0.1:46768","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-09T03:47:18.984423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413233714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T03:47:18.984001Z","caller":"traceutil/trace.go:171","msg":"trace[1688819834] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:551; }","duration":"1.135499011s","start":"2026-07-09T03:47:17.848483Z","end":"2026-07-09T03:47:18.983982Z","steps":["trace[1688819834] 'read index received' (duration: 540.364034ms)","trace[1688819834] 'applied index is now lower than readState.Index' (duration: 595.133257ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:47:18.985797Z","caller":"traceutil/trace.go:171","msg":"trace[1643780399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"1.414639081s","start":"2026-07-09T03:47:17.571138Z","end":"2026-07-09T03:47:18.985777Z","steps":["trace[1643780399] 'agreement among raft nodes before linearized reading' (duration: 1.413263324s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:18.985865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:17.571115Z","time spent":"1.414734414s","remote":"127.0.0.1:46484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T03:47:18.987263Z","caller":"traceutil/trace.go:171","msg":"trace[1875698893] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"523.050326ms","start":"2026-07-09T03:47:18.463971Z","end":"2026-07-09T03:47:18.987022Z","steps":["trace[1875698893] 'process raft request' (duration: 520.094857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:18.987361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:18.463936Z","time spent":"523.378684ms","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T03:47:18.987516Z","caller":"traceutil/trace.go:171","msg":"trace[652444034] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"277.792224ms","start":"2026-07-09T03:47:18.709703Z","end":"2026-07-09T03:47:18.987496Z","steps":["trace[652444034] 'process raft request' (duration: 276.8617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:18.987906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.297499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-09T03:47:18.988028Z","caller":"traceutil/trace.go:171","msg":"trace[2072961624] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:544; }","duration":"289.482014ms","start":"2026-07-09T03:47:18.69851Z","end":"2026-07-09T03:47:18.987992Z","steps":["trace[2072961624] 'agreement among raft nodes before linearized reading' (duration: 288.341444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:19.490399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.234563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T03:47:19.490492Z","caller":"traceutil/trace.go:171","msg":"trace[1546348878] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"335.066762ms","start":"2026-07-09T03:47:19.15541Z","end":"2026-07-09T03:47:19.490477Z","steps":["trace[1546348878] 'process raft request' (duration: 78.699476ms)","trace[1546348878] 'compare' (duration: 256.052297ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:47:19.490532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:19.155374Z","time spent":"335.142894ms","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T03:47:19.493216Z","caller":"traceutil/trace.go:171","msg":"trace[1902984216] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"313.600173ms","start":"2026-07-09T03:47:19.179589Z","end":"2026-07-09T03:47:19.493189Z","steps":["trace[1902984216] 'process raft request' (duration: 313.49897ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:19.493241Z","caller":"traceutil/trace.go:171","msg":"trace[1107544429] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"316.249064ms","start":"2026-07-09T03:47:19.176967Z","end":"2026-07-09T03:47:19.493216Z","steps":["trace[1107544429] 'process raft request' (duration: 316.019567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:19.493328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:19.17954Z","time spent":"313.729006ms","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:19.493392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:19.17695Z","time spent":"316.355676ms","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:19.785345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.750698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T03:47:19.785434Z","caller":"traceutil/trace.go:171","msg":"trace[857628276] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"275.903844ms","start":"2026-07-09T03:47:19.509518Z","end":"2026-07-09T03:47:19.785421Z","steps":["trace[857628276] 'process raft request' (duration: 134.014612ms)","trace[857628276] 'compare' (duration: 141.660806ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:47:19.792588Z","caller":"traceutil/trace.go:171","msg":"trace[575536807] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"281.281527ms","start":"2026-07-09T03:47:19.511291Z","end":"2026-07-09T03:47:19.792573Z","steps":["trace[575536807] 'process raft request' (duration: 281.151764ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:19.904271Z","caller":"traceutil/trace.go:171","msg":"trace[184740127] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"123.973706ms","start":"2026-07-09T03:47:19.780277Z","end":"2026-07-09T03:47:19.904251Z","steps":["trace[184740127] 'process raft request' (duration: 123.466072ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:20.106551Z","caller":"traceutil/trace.go:171","msg":"trace[2144106198] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:575; }","duration":"254.151108ms","start":"2026-07-09T03:47:19.852358Z","end":"2026-07-09T03:47:20.106509Z","steps":["trace[2144106198] 'read index received' (duration: 51.152056ms)","trace[2144106198] 'applied index is now lower than readState.Index' (duration: 202.998062ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:47:20.106903Z","caller":"traceutil/trace.go:171","msg":"trace[1293320772] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"303.731702ms","start":"2026-07-09T03:47:19.803158Z","end":"2026-07-09T03:47:20.106889Z","steps":["trace[1293320772] 'process raft request' (duration: 267.931723ms)","trace[1293320772] 'compare' (duration: 35.261955ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:47:20.10708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:19.803138Z","time spent":"303.863785ms","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:20.107218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.830805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-09T03:47:20.107257Z","caller":"traceutil/trace.go:171","msg":"trace[531230261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:560; }","duration":"254.903087ms","start":"2026-07-09T03:47:19.852345Z","end":"2026-07-09T03:47:20.107248Z","steps":["trace[531230261] 'agreement among raft nodes before linearized reading' (duration: 254.783194ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:20.10728Z","caller":"traceutil/trace.go:171","msg":"trace[395503102] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"302.87993ms","start":"2026-07-09T03:47:19.804388Z","end":"2026-07-09T03:47:20.107268Z","steps":["trace[395503102] 'process raft request' (duration: 302.070398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:20.107345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:19.804364Z","time spent":"302.948491ms","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:20.107448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.756877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T03:47:20.107474Z","caller":"traceutil/trace.go:171","msg":"trace[1601481021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:560; }","duration":"253.803989ms","start":"2026-07-09T03:47:19.853664Z","end":"2026-07-09T03:47:20.107468Z","steps":["trace[1601481021] 'agreement among raft nodes before linearized reading' (duration: 253.744037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:20.107529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.167984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-09T03:47:20.107568Z","caller":"traceutil/trace.go:171","msg":"trace[917804444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"255.238736ms","start":"2026-07-09T03:47:19.852319Z","end":"2026-07-09T03:47:20.107557Z","steps":["trace[917804444] 'agreement among raft nodes before linearized reading' (duration: 255.136533ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:20.292746Z","caller":"traceutil/trace.go:171","msg":"trace[109493724] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"137.67659ms","start":"2026-07-09T03:47:20.155041Z","end":"2026-07-09T03:47:20.292718Z","steps":["trace[109493724] 'process raft request' (duration: 126.316118ms)","trace[109493724] 'compare' (duration: 11.047023ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:47:20.471782Z","caller":"traceutil/trace.go:171","msg":"trace[154160856] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"123.320409ms","start":"2026-07-09T03:47:20.348447Z","end":"2026-07-09T03:47:20.471767Z","steps":["trace[154160856] 'process raft request' (duration: 121.069409ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:20.471782Z","caller":"traceutil/trace.go:171","msg":"trace[1717007835] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"118.676305ms","start":"2026-07-09T03:47:20.353076Z","end":"2026-07-09T03:47:20.471752Z","steps":["trace[1717007835] 'read index received' (duration: 116.308913ms)","trace[1717007835] 'applied index is now lower than readState.Index' (duration: 2.365962ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:47:20.471964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.870591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-07-09T03:47:20.472008Z","caller":"traceutil/trace.go:171","msg":"trace[1853359268] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:569; }","duration":"118.960324ms","start":"2026-07-09T03:47:20.353036Z","end":"2026-07-09T03:47:20.471996Z","steps":["trace[1853359268] 'agreement among raft nodes before linearized reading' (duration: 118.807769ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:20.715474Z","caller":"traceutil/trace.go:171","msg":"trace[915914475] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"138.267124ms","start":"2026-07-09T03:47:20.577186Z","end":"2026-07-09T03:47:20.715453Z","steps":["trace[915914475] 'process raft request' (duration: 138.051159ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:20.844828Z","caller":"traceutil/trace.go:171","msg":"trace[1150037836] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:590; }","duration":"224.725868ms","start":"2026-07-09T03:47:20.620077Z","end":"2026-07-09T03:47:20.844803Z","steps":["trace[1150037836] 'read index received' (duration: 95.243115ms)","trace[1150037836] 'applied index is now lower than readState.Index' (duration: 129.481793ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:47:20.844976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.880951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-07-09T03:47:20.845018Z","caller":"traceutil/trace.go:171","msg":"trace[1037287709] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:573; }","duration":"224.980804ms","start":"2026-07-09T03:47:20.620023Z","end":"2026-07-09T03:47:20.845004Z","steps":["trace[1037287709] 'agreement among raft nodes before linearized reading' (duration: 224.871311ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:20.845031Z","caller":"traceutil/trace.go:171","msg":"trace[1103724127] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"240.359532ms","start":"2026-07-09T03:47:20.604643Z","end":"2026-07-09T03:47:20.845003Z","steps":["trace[1103724127] 'process raft request' (duration: 239.87854ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:20.896063Z","caller":"traceutil/trace.go:171","msg":"trace[64217936] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"173.290843ms","start":"2026-07-09T03:47:20.72275Z","end":"2026-07-09T03:47:20.896041Z","steps":["trace[64217936] 'process raft request' (duration: 173.131439ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:25.458192Z","caller":"traceutil/trace.go:171","msg":"trace[2100573026] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"484.963327ms","start":"2026-07-09T03:47:24.973203Z","end":"2026-07-09T03:47:25.458166Z","steps":["trace[2100573026] 'read index received' (duration: 484.753102ms)","trace[2100573026] 'applied index is now lower than readState.Index' (duration: 209.165µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:47:25.458276Z","caller":"traceutil/trace.go:171","msg":"trace[1471349588] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"778.024965ms","start":"2026-07-09T03:47:24.680221Z","end":"2026-07-09T03:47:25.458246Z","steps":["trace[1471349588] 'process raft request' (duration: 777.8175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:25.458423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.202623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2lsm6\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-09T03:47:25.458431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:24.680209Z","time spent":"778.146968ms","remote":"127.0.0.1:46620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":397,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T03:47:25.458454Z","caller":"traceutil/trace.go:171","msg":"trace[1446460657] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2lsm6; range_end:; response_count:1; response_revision:604; }","duration":"485.281585ms","start":"2026-07-09T03:47:24.973164Z","end":"2026-07-09T03:47:25.458445Z","steps":["trace[1446460657] 'agreement among raft nodes before linearized reading' (duration: 485.122901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:25.458483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:24.973149Z","time spent":"485.326986ms","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-2lsm6\" "} {"level":"warn","ts":"2026-07-09T03:47:25.458637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.829072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-09T03:47:25.458719Z","caller":"traceutil/trace.go:171","msg":"trace[250730929] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"375.957335ms","start":"2026-07-09T03:47:25.082741Z","end":"2026-07-09T03:47:25.458699Z","steps":["trace[250730929] 'agreement among raft nodes before linearized reading' (duration: 375.717228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:25.458765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:25.082721Z","time spent":"376.035608ms","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-09T03:47:26.00321Z","caller":"traceutil/trace.go:171","msg":"trace[1913351222] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"537.377894ms","start":"2026-07-09T03:47:25.465807Z","end":"2026-07-09T03:47:26.003185Z","steps":["trace[1913351222] 'process raft request' (duration: 537.23154ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:26.003369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:25.465717Z","time spent":"537.58706ms","remote":"127.0.0.1:46606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1749,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T03:47:26.525283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.932147ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T03:47:26.525441Z","caller":"traceutil/trace.go:171","msg":"trace[69775531] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:605; }","duration":"324.119772ms","start":"2026-07-09T03:47:26.201291Z","end":"2026-07-09T03:47:26.525411Z","steps":["trace[69775531] 'range keys from in-memory index tree' (duration: 323.895536ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:26.525484Z","caller":"traceutil/trace.go:171","msg":"trace[1367682586] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"1.059591788s","start":"2026-07-09T03:47:25.465869Z","end":"2026-07-09T03:47:26.525461Z","steps":["trace[1367682586] 'process raft request' (duration: 1.059202268s)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:26.526047Z","caller":"traceutil/trace.go:171","msg":"trace[339626683] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"1.045015073s","start":"2026-07-09T03:47:25.480998Z","end":"2026-07-09T03:47:26.526013Z","steps":["trace[339626683] 'process raft request' (duration: 1.044464628s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:26.527305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:25.480972Z","time spent":"1.046213694s","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T03:47:26.528149Z","caller":"traceutil/trace.go:171","msg":"trace[1315392841] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"1.058839368s","start":"2026-07-09T03:47:25.466717Z","end":"2026-07-09T03:47:26.525556Z","steps":["trace[1315392841] 'process raft request' (duration: 1.058667334s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:26.52834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:25.466709Z","time spent":"1.061503629s","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:26.528662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:25.465846Z","time spent":"1.059710861s","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T03:47:26.533621Z","caller":"traceutil/trace.go:171","msg":"trace[982391165] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:625; }","duration":"394.835027ms","start":"2026-07-09T03:47:26.138715Z","end":"2026-07-09T03:47:26.53355Z","steps":["trace[982391165] 'read index received' (duration: 386.303701ms)","trace[982391165] 'applied index is now lower than readState.Index' (duration: 8.530266ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:47:26.534224Z","caller":"traceutil/trace.go:171","msg":"trace[36320238] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"633.817903ms","start":"2026-07-09T03:47:25.900365Z","end":"2026-07-09T03:47:26.534183Z","steps":["trace[36320238] 'process raft request' (duration: 628.075341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:26.534389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.071517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-09T03:47:26.534432Z","caller":"traceutil/trace.go:171","msg":"trace[1457948034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"391.16927ms","start":"2026-07-09T03:47:26.143251Z","end":"2026-07-09T03:47:26.534421Z","steps":["trace[1457948034] 'agreement among raft nodes before linearized reading' (duration: 390.930704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:26.534465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:26.143222Z","time spent":"391.235982ms","remote":"127.0.0.1:46768","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-09T03:47:26.534472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.72929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-09T03:47:26.534595Z","caller":"traceutil/trace.go:171","msg":"trace[1336997044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"395.923615ms","start":"2026-07-09T03:47:26.138645Z","end":"2026-07-09T03:47:26.534569Z","steps":["trace[1336997044] 'agreement among raft nodes before linearized reading' (duration: 395.446133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:26.534894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.807919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-09T03:47:26.534961Z","caller":"traceutil/trace.go:171","msg":"trace[1686308546] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:609; }","duration":"390.891052ms","start":"2026-07-09T03:47:26.144055Z","end":"2026-07-09T03:47:26.534946Z","steps":["trace[1686308546] 'agreement among raft nodes before linearized reading' (duration: 390.709277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:26.534986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:26.14397Z","time spent":"391.011126ms","remote":"127.0.0.1:46768","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-07-09T03:47:26.535207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.615361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T03:47:26.535237Z","caller":"traceutil/trace.go:171","msg":"trace[1025417397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"308.684613ms","start":"2026-07-09T03:47:26.226546Z","end":"2026-07-09T03:47:26.535231Z","steps":["trace[1025417397] 'agreement among raft nodes before linearized reading' (duration: 308.634512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:26.535337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:25.900341Z","time spent":"633.947256ms","remote":"127.0.0.1:46590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T03:47:26.535259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:26.226525Z","time spent":"308.729224ms","remote":"127.0.0.1:46490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T03:47:26.534659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:26.138616Z","time spent":"396.027967ms","remote":"127.0.0.1:46768","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-09T03:47:27.120684Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378076917702156611,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T03:47:29.227411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999877637s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-09T03:47:29.227505Z","caller":"traceutil/trace.go:171","msg":"trace[417752509] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000031761s","start":"2026-07-09T03:47:27.227452Z","end":"2026-07-09T03:47:29.227483Z","steps":["trace[417752509] 'agreement among raft nodes before linearized reading' (duration: 1.999923258s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:29.227564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:27.227423Z","time spent":"2.000128724s","remote":"127.0.0.1:46490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/09 03:47:29 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-09T03:47:29.488692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.22664736s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T03:47:29.488828Z","caller":"traceutil/trace.go:171","msg":"trace[478860470] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"2.946974274s","start":"2026-07-09T03:47:26.541827Z","end":"2026-07-09T03:47:29.488801Z","steps":["trace[478860470] 'process raft request' (duration: 719.367339ms)","trace[478860470] 'compare' (duration: 2.226260989s)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:47:29.48891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:26.541803Z","time spent":"2.947065236s","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:29.526991Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.265652664s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T03:47:29.527628Z","caller":"traceutil/trace.go:171","msg":"trace[1718907750] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"2.983920664s","start":"2026-07-09T03:47:26.543606Z","end":"2026-07-09T03:47:29.527527Z","steps":["trace[1718907750] 'process raft request' (duration: 2.983659867s)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:29.527739Z","caller":"traceutil/trace.go:171","msg":"trace[892873860] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:629; }","duration":"2.907677203s","start":"2026-07-09T03:47:26.620044Z","end":"2026-07-09T03:47:29.527721Z","steps":["trace[892873860] 'read index received' (duration: 641.226948ms)","trace[892873860] 'applied index is now lower than readState.Index' (duration: 2.266449314s)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:47:29.52778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:26.543583Z","time spent":"2.984117389s","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T03:47:29.527867Z","caller":"traceutil/trace.go:171","msg":"trace[1500281745] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"2.984130989s","start":"2026-07-09T03:47:26.5437Z","end":"2026-07-09T03:47:29.527831Z","steps":["trace[1500281745] 'process raft request' (duration: 2.9837821s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:29.527934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.907890928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T03:47:29.527991Z","caller":"traceutil/trace.go:171","msg":"trace[841766492] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:612; }","duration":"2.907994051s","start":"2026-07-09T03:47:26.619983Z","end":"2026-07-09T03:47:29.527977Z","steps":["trace[841766492] 'agreement among raft nodes before linearized reading' (duration: 2.907869978s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:29.528028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:26.619952Z","time spent":"2.908068533s","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-09T03:47:29.528175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:26.543693Z","time spent":"2.984330025s","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:29.534707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.519771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-09T03:47:29.534767Z","caller":"traceutil/trace.go:171","msg":"trace[1282509231] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"998.616514ms","start":"2026-07-09T03:47:28.536135Z","end":"2026-07-09T03:47:29.534752Z","steps":["trace[1282509231] 'agreement among raft nodes before linearized reading' (duration: 998.394837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:29.534802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:28.536112Z","time spent":"998.682536ms","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-09T03:47:29.53507Z","caller":"traceutil/trace.go:171","msg":"trace[2047790916] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"1.018639295s","start":"2026-07-09T03:47:28.516419Z","end":"2026-07-09T03:47:29.535058Z","steps":["trace[2047790916] 'process raft request' (duration: 1.018016978s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:29.536026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:28.516389Z","time spent":"1.018702855s","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:29.536124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.580721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T03:47:29.536177Z","caller":"traceutil/trace.go:171","msg":"trace[801204184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"308.667223ms","start":"2026-07-09T03:47:29.227497Z","end":"2026-07-09T03:47:29.536164Z","steps":["trace[801204184] 'agreement among raft nodes before linearized reading' (duration: 308.594222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:29.536208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:29.227478Z","time spent":"308.722584ms","remote":"127.0.0.1:46484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T03:47:29.757509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.180377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T03:47:29.757994Z","caller":"traceutil/trace.go:171","msg":"trace[2118131394] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"211.522648ms","start":"2026-07-09T03:47:29.546443Z","end":"2026-07-09T03:47:29.757966Z","steps":["trace[2118131394] 'process raft request' (duration: 44.798037ms)","trace[2118131394] 'compare' (duration: 166.059294ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:47:30.213608Z","caller":"traceutil/trace.go:171","msg":"trace[925546387] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"216.439978ms","start":"2026-07-09T03:47:29.997147Z","end":"2026-07-09T03:47:30.213587Z","steps":["trace[925546387] 'read index received' (duration: 213.938872ms)","trace[925546387] 'applied index is now lower than readState.Index' (duration: 2.500186ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:47:30.213729Z","caller":"traceutil/trace.go:171","msg":"trace[92822309] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"224.111871ms","start":"2026-07-09T03:47:29.989564Z","end":"2026-07-09T03:47:30.213675Z","steps":["trace[92822309] 'process raft request' (duration: 222.328375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:30.213806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.648734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2lsm6\" ","response":"range_response_count:1 size:17149"} {"level":"info","ts":"2026-07-09T03:47:30.213845Z","caller":"traceutil/trace.go:171","msg":"trace[2095141978] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2lsm6; range_end:; response_count:1; response_revision:616; }","duration":"216.752376ms","start":"2026-07-09T03:47:29.997083Z","end":"2026-07-09T03:47:30.213835Z","steps":["trace[2095141978] 'agreement among raft nodes before linearized reading' (duration: 216.591531ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:30.214287Z","caller":"traceutil/trace.go:171","msg":"trace[1966311899] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"215.552734ms","start":"2026-07-09T03:47:29.998726Z","end":"2026-07-09T03:47:30.214279Z","steps":["trace[1966311899] 'process raft request' (duration: 215.507433ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:31.336207Z","caller":"traceutil/trace.go:171","msg":"trace[2146876470] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"100.355161ms","start":"2026-07-09T03:47:31.235826Z","end":"2026-07-09T03:47:31.336181Z","steps":["trace[2146876470] 'process raft request' (duration: 99.820337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:44.726804Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378076917702157027,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T03:47:45.227172Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378076917702157027,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T03:47:45.359148Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.264782942s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T03:47:45.359636Z","caller":"traceutil/trace.go:171","msg":"trace[769275828] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:807; }","duration":"1.133431049s","start":"2026-07-09T03:47:44.226177Z","end":"2026-07-09T03:47:45.359608Z","steps":["trace[769275828] 'read index received' (duration: 1.13311487s)","trace[769275828] 'applied index is now lower than readState.Index' (duration: 314.699µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:47:45.359857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133663415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T03:47:45.359912Z","caller":"traceutil/trace.go:171","msg":"trace[1983971140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"1.133759538s","start":"2026-07-09T03:47:44.226138Z","end":"2026-07-09T03:47:45.359898Z","steps":["trace[1983971140] 'agreement among raft nodes before linearized reading' (duration: 1.133636355s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:45.359954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:44.226119Z","time spent":"1.133826019s","remote":"127.0.0.1:46490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T03:47:45.359841Z","caller":"traceutil/trace.go:171","msg":"trace[1990906295] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"1.26545524s","start":"2026-07-09T03:47:44.094241Z","end":"2026-07-09T03:47:45.359696Z","steps":["trace[1990906295] 'process raft request' (duration: 1.265181202s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:45.360399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:47:44.094218Z","time spent":"1.266083146s","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:47:45.36006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.732169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T03:47:45.360559Z","caller":"traceutil/trace.go:171","msg":"trace[1264503578] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:782; }","duration":"222.415787ms","start":"2026-07-09T03:47:45.138128Z","end":"2026-07-09T03:47:45.360544Z","steps":["trace[1264503578] 'agreement among raft nodes before linearized reading' (duration: 221.724809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:23.612421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.115347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T03:48:23.612556Z","caller":"traceutil/trace.go:171","msg":"trace[1945788761] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1097; }","duration":"385.148684ms","start":"2026-07-09T03:48:23.227385Z","end":"2026-07-09T03:48:23.612534Z","steps":["trace[1945788761] 'read index received' (duration: 85.79986ms)","trace[1945788761] 'applied index is now lower than readState.Index' (duration: 299.347054ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:48:23.612606Z","caller":"traceutil/trace.go:171","msg":"trace[1919828301] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"386.524561ms","start":"2026-07-09T03:48:23.226042Z","end":"2026-07-09T03:48:23.612567Z","steps":["trace[1919828301] 'process raft request' (duration: 87.159237ms)","trace[1919828301] 'compare' (duration: 298.764798ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:48:23.612721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.332648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T03:48:23.612767Z","caller":"traceutil/trace.go:171","msg":"trace[172045325] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1062; }","duration":"385.411141ms","start":"2026-07-09T03:48:23.227339Z","end":"2026-07-09T03:48:23.61275Z","steps":["trace[172045325] 'agreement among raft nodes before linearized reading' (duration: 385.306718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:23.612792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:23.226024Z","time spent":"386.663084ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:48:23.612809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:23.227325Z","time spent":"385.474433ms","remote":"127.0.0.1:46490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T03:48:23.613131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.739478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T03:48:23.61318Z","caller":"traceutil/trace.go:171","msg":"trace[1620368942] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1062; }","duration":"161.818899ms","start":"2026-07-09T03:48:23.451348Z","end":"2026-07-09T03:48:23.613166Z","steps":["trace[1620368942] 'agreement among raft nodes before linearized reading' (duration: 161.711976ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:48:24.9521Z","caller":"traceutil/trace.go:171","msg":"trace[206624654] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"267.118338ms","start":"2026-07-09T03:48:24.684956Z","end":"2026-07-09T03:48:24.952074Z","steps":["trace[206624654] 'process raft request' (duration: 266.963043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:25.725247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.931251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T03:48:25.725372Z","caller":"traceutil/trace.go:171","msg":"trace[994225418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1068; }","duration":"501.094325ms","start":"2026-07-09T03:48:25.224253Z","end":"2026-07-09T03:48:25.725348Z","steps":["trace[994225418] 'range keys from in-memory index tree' (duration: 500.87241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:25.725423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:25.224237Z","time spent":"501.171947ms","remote":"127.0.0.1:46490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T03:48:39.347313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.128001ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-09T03:48:39.347399Z","caller":"traceutil/trace.go:171","msg":"trace[774437541] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1150; }","duration":"405.492365ms","start":"2026-07-09T03:48:38.941892Z","end":"2026-07-09T03:48:39.347384Z","steps":["trace[774437541] 'read index received' (duration: 142.649401ms)","trace[774437541] 'applied index is now lower than readState.Index' (duration: 262.841804ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:48:39.347573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.67193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T03:48:39.347654Z","caller":"traceutil/trace.go:171","msg":"trace[2092928509] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1112; }","duration":"405.835003ms","start":"2026-07-09T03:48:38.9418Z","end":"2026-07-09T03:48:39.347635Z","steps":["trace[2092928509] 'agreement among raft nodes before linearized reading' (duration: 405.69795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:39.347698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:38.94176Z","time spent":"405.927556ms","remote":"127.0.0.1:47024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-09T03:48:39.497271Z","caller":"traceutil/trace.go:171","msg":"trace[1638100343] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1151; }","duration":"149.751939ms","start":"2026-07-09T03:48:39.347497Z","end":"2026-07-09T03:48:39.497249Z","steps":["trace[1638100343] 'read index received' (duration: 149.551273ms)","trace[1638100343] 'applied index is now lower than readState.Index' (duration: 199.806µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:48:39.497431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.200687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T03:48:39.49747Z","caller":"traceutil/trace.go:171","msg":"trace[1373891776] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1113; }","duration":"186.3155ms","start":"2026-07-09T03:48:39.311145Z","end":"2026-07-09T03:48:39.497461Z","steps":["trace[1373891776] 'agreement among raft nodes before linearized reading' (duration: 186.199557ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:48:39.497651Z","caller":"traceutil/trace.go:171","msg":"trace[851406975] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"401.386355ms","start":"2026-07-09T03:48:39.096212Z","end":"2026-07-09T03:48:39.497598Z","steps":["trace[851406975] 'process raft request' (duration: 400.907122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:39.498106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:39.096182Z","time spent":"401.671992ms","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:48:39.942381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.713797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T03:48:39.942476Z","caller":"traceutil/trace.go:171","msg":"trace[871748117] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1113; }","duration":"228.866382ms","start":"2026-07-09T03:48:39.713585Z","end":"2026-07-09T03:48:39.942451Z","steps":["trace[871748117] 'count revisions from in-memory index tree' (duration: 228.621385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:47.317559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.605896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T03:48:47.317596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339968545s","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-09T03:48:47.317696Z","caller":"traceutil/trace.go:171","msg":"trace[1088937915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1161; }","duration":"1.340088678s","start":"2026-07-09T03:48:45.977559Z","end":"2026-07-09T03:48:47.317648Z","steps":["trace[1088937915] 'range keys from in-memory index tree' (duration: 1.339670548s)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:48:47.317658Z","caller":"traceutil/trace.go:171","msg":"trace[741259553] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1161; }","duration":"881.766699ms","start":"2026-07-09T03:48:46.435866Z","end":"2026-07-09T03:48:47.317633Z","steps":["trace[741259553] 'count revisions from in-memory index tree' (duration: 881.539384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:47.317741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:45.977537Z","time spent":"1.340192381s","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T03:48:47.317757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:46.435847Z","time spent":"881.891763ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-07-09T03:48:47.317596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339817581s","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-09T03:48:47.317904Z","caller":"traceutil/trace.go:171","msg":"trace[969849805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1161; }","duration":"1.34014351s","start":"2026-07-09T03:48:45.977747Z","end":"2026-07-09T03:48:47.31789Z","steps":["trace[969849805] 'range keys from in-memory index tree' (duration: 1.339730838s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:47.317567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116768714s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T03:48:47.318015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:45.977742Z","time spent":"1.340205632s","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T03:48:47.3181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340402097s","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-09T03:48:47.318008Z","caller":"traceutil/trace.go:171","msg":"trace[1315848106] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1161; }","duration":"1.117203346s","start":"2026-07-09T03:48:46.200789Z","end":"2026-07-09T03:48:47.317993Z","steps":["trace[1315848106] 'range keys from in-memory index tree' (duration: 1.116761494s)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:48:47.318165Z","caller":"traceutil/trace.go:171","msg":"trace[471830775] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1161; }","duration":"1.340482428s","start":"2026-07-09T03:48:45.977668Z","end":"2026-07-09T03:48:47.31815Z","steps":["trace[471830775] 'range keys from in-memory index tree' (duration: 1.340274913s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:47.318219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.336243206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-09T03:48:47.318213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:45.977662Z","time spent":"1.3405398s","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T03:48:47.318221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.336921594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T03:48:47.318238Z","caller":"traceutil/trace.go:171","msg":"trace[1566349107] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1161; }","duration":"1.336275677s","start":"2026-07-09T03:48:45.981957Z","end":"2026-07-09T03:48:47.318232Z","steps":["trace[1566349107] 'range keys from in-memory index tree' (duration: 1.336192225s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:47.318263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:45.98195Z","time spent":"1.336308988s","remote":"127.0.0.1:46768","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-09T03:48:47.318257Z","caller":"traceutil/trace.go:171","msg":"trace[1177080785] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1161; }","duration":"1.336970506s","start":"2026-07-09T03:48:45.981276Z","end":"2026-07-09T03:48:47.318246Z","steps":["trace[1177080785] 'range keys from in-memory index tree' (duration: 1.336842202s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:47.318291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:45.981269Z","time spent":"1.337015117s","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-09T03:48:47.864342Z","caller":"traceutil/trace.go:171","msg":"trace[241233894] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"543.215114ms","start":"2026-07-09T03:48:47.321085Z","end":"2026-07-09T03:48:47.8643Z","steps":["trace[241233894] 'process raft request' (duration: 542.938257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:47.86477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:47.321074Z","time spent":"543.392228ms","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T03:48:48.630186Z","caller":"traceutil/trace.go:171","msg":"trace[1712613488] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"1.005000374s","start":"2026-07-09T03:48:47.625166Z","end":"2026-07-09T03:48:48.630167Z","steps":["trace[1712613488] 'process raft request' (duration: 1.004966093s)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:48:48.630215Z","caller":"traceutil/trace.go:171","msg":"trace[1519716534] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"1.309080824s","start":"2026-07-09T03:48:47.32109Z","end":"2026-07-09T03:48:48.630171Z","steps":["trace[1519716534] 'process raft request' (duration: 1.308804096s)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:48:48.63027Z","caller":"traceutil/trace.go:171","msg":"trace[1719791490] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"1.307212534s","start":"2026-07-09T03:48:47.323033Z","end":"2026-07-09T03:48:48.630246Z","steps":["trace[1719791490] 'process raft request' (duration: 1.307069461s)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:48:48.630283Z","caller":"traceutil/trace.go:171","msg":"trace[1983924230] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"1.308071537s","start":"2026-07-09T03:48:47.322169Z","end":"2026-07-09T03:48:48.630241Z","steps":["trace[1983924230] 'process raft request' (duration: 1.307871352s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:48.630322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:47.625148Z","time spent":"1.005103667s","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T03:48:48.630212Z","caller":"traceutil/trace.go:171","msg":"trace[282799346] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"1.307935244s","start":"2026-07-09T03:48:47.322246Z","end":"2026-07-09T03:48:48.630181Z","steps":["trace[282799346] 'process raft request' (duration: 1.307831061s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:48.630414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:47.323026Z","time spent":"1.307305216s","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:48:48.630439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:47.322161Z","time spent":"1.30819106s","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:48:48.63045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:47.321081Z","time spent":"1.309227168s","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:48:48.630497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:47.322228Z","time spent":"1.30819437s","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T03:48:48.674847Z","caller":"traceutil/trace.go:171","msg":"trace[472930008] linearizableReadLoop","detail":"{readStateIndex:1209; appliedIndex:1205; }","duration":"140.302959ms","start":"2026-07-09T03:48:48.534514Z","end":"2026-07-09T03:48:48.674817Z","steps":["trace[472930008] 'read index received' (duration: 95.55469ms)","trace[472930008] 'applied index is now lower than readState.Index' (duration: 44.746589ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:48:48.674997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.450522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T03:48:48.675039Z","caller":"traceutil/trace.go:171","msg":"trace[360056282] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1167; }","duration":"140.564345ms","start":"2026-07-09T03:48:48.534464Z","end":"2026-07-09T03:48:48.675028Z","steps":["trace[360056282] 'agreement among raft nodes before linearized reading' (duration: 140.455862ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:48:56.490715Z","caller":"traceutil/trace.go:171","msg":"trace[1552472806] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"120.077931ms","start":"2026-07-09T03:48:56.370604Z","end":"2026-07-09T03:48:56.490682Z","steps":["trace[1552472806] 'read index received' (duration: 119.811533ms)","trace[1552472806] 'applied index is now lower than readState.Index' (duration: 265.328µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:48:56.491164Z","caller":"traceutil/trace.go:171","msg":"trace[207581909] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"227.680059ms","start":"2026-07-09T03:48:56.263445Z","end":"2026-07-09T03:48:56.491125Z","steps":["trace[207581909] 'process raft request' (duration: 227.057072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:56.491951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.327753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-6wd67\" ","response":"range_response_count:1 size:12642"} {"level":"info","ts":"2026-07-09T03:48:56.492029Z","caller":"traceutil/trace.go:171","msg":"trace[875995393] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-6wd67; range_end:; response_count:1; response_revision:1220; }","duration":"121.445847ms","start":"2026-07-09T03:48:56.370563Z","end":"2026-07-09T03:48:56.492009Z","steps":["trace[875995393] 'agreement among raft nodes before linearized reading' (duration: 120.240545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:57.162591Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378076917702157950,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T03:48:57.647847Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.143754441s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T03:48:57.648321Z","caller":"traceutil/trace.go:171","msg":"trace[1931180051] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"1.144365497s","start":"2026-07-09T03:48:56.503933Z","end":"2026-07-09T03:48:57.648299Z","steps":["trace[1931180051] 'process raft request' (duration: 1.144199293s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:57.648448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:56.503901Z","time spent":"1.14447756s","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13706,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T03:48:57.650381Z","caller":"traceutil/trace.go:171","msg":"trace[644773556] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1264; }","duration":"988.365612ms","start":"2026-07-09T03:48:56.662Z","end":"2026-07-09T03:48:57.650365Z","steps":["trace[644773556] 'read index received' (duration: 986.554754ms)","trace[644773556] 'applied index is now lower than readState.Index' (duration: 1.810108ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:48:57.650488Z","caller":"traceutil/trace.go:171","msg":"trace[2020151463] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"993.283503ms","start":"2026-07-09T03:48:56.657175Z","end":"2026-07-09T03:48:57.650459Z","steps":["trace[2020151463] 'process raft request' (duration: 993.112869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:57.650575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.563448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-09T03:48:57.650595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:56.657148Z","time spent":"993.396225ms","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T03:48:57.650637Z","caller":"traceutil/trace.go:171","msg":"trace[1699572252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1222; }","duration":"988.658629ms","start":"2026-07-09T03:48:56.661965Z","end":"2026-07-09T03:48:57.650624Z","steps":["trace[1699572252] 'agreement among raft nodes before linearized reading' (duration: 988.486275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:57.650694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:56.661946Z","time spent":"988.730703ms","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T03:48:57.650781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.334747ms","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-09T03:48:57.650843Z","caller":"traceutil/trace.go:171","msg":"trace[1284885558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1222; }","duration":"972.431949ms","start":"2026-07-09T03:48:56.678394Z","end":"2026-07-09T03:48:57.650826Z","steps":["trace[1284885558] 'agreement among raft nodes before linearized reading' (duration: 972.284736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:57.650862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.879994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T03:48:57.650888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:56.678373Z","time spent":"972.502251ms","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T03:48:57.650898Z","caller":"traceutil/trace.go:171","msg":"trace[2078740152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1222; }","duration":"423.955036ms","start":"2026-07-09T03:48:57.22693Z","end":"2026-07-09T03:48:57.650885Z","steps":["trace[2078740152] 'agreement among raft nodes before linearized reading' (duration: 423.899864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:57.650935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:57.226881Z","time spent":"424.037958ms","remote":"127.0.0.1:46490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T03:48:57.651005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.082594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T03:48:57.651054Z","caller":"traceutil/trace.go:171","msg":"trace[166320518] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1222; }","duration":"580.133185ms","start":"2026-07-09T03:48:57.070908Z","end":"2026-07-09T03:48:57.651041Z","steps":["trace[166320518] 'agreement among raft nodes before linearized reading' (duration: 580.061093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:57.651095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:57.070865Z","time spent":"580.216527ms","remote":"127.0.0.1:46680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-07-09T03:48:57.651174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.659586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T03:48:57.651214Z","caller":"traceutil/trace.go:171","msg":"trace[598115513] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1222; }","duration":"196.746938ms","start":"2026-07-09T03:48:57.454457Z","end":"2026-07-09T03:48:57.651204Z","steps":["trace[598115513] 'agreement among raft nodes before linearized reading' (duration: 196.695197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:57.651389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.124271ms","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-09T03:48:57.651436Z","caller":"traceutil/trace.go:171","msg":"trace[827969124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1222; }","duration":"972.171452ms","start":"2026-07-09T03:48:56.679254Z","end":"2026-07-09T03:48:57.651425Z","steps":["trace[827969124] 'agreement among raft nodes before linearized reading' (duration: 972.064729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:57.651466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:56.679218Z","time spent":"972.240704ms","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T03:48:57.651386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.010328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T03:48:57.651556Z","caller":"traceutil/trace.go:171","msg":"trace[1469177671] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1222; }","duration":"972.181252ms","start":"2026-07-09T03:48:56.679362Z","end":"2026-07-09T03:48:57.651543Z","steps":["trace[1469177671] 'agreement among raft nodes before linearized reading' (duration: 971.933735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:57.651606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:48:56.679286Z","time spent":"972.304746ms","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-09T03:51:14.652991Z","caller":"traceutil/trace.go:171","msg":"trace[650484746] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"186.679625ms","start":"2026-07-09T03:51:14.466287Z","end":"2026-07-09T03:51:14.652967Z","steps":["trace[650484746] 'process raft request' (duration: 92.09401ms)","trace[650484746] 'compare' (duration: 94.44829ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:51:14.65332Z","caller":"traceutil/trace.go:171","msg":"trace[1815294352] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"186.976772ms","start":"2026-07-09T03:51:14.466332Z","end":"2026-07-09T03:51:14.653309Z","steps":["trace[1815294352] 'process raft request' (duration: 186.605033ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:52:51.358161Z","caller":"traceutil/trace.go:171","msg":"trace[860684369] transaction","detail":"{read_only:false; response_revision:2326; number_of_response:1; }","duration":"127.662045ms","start":"2026-07-09T03:52:51.230473Z","end":"2026-07-09T03:52:51.358135Z","steps":["trace[860684369] 'process raft request' (duration: 36.618208ms)","trace[860684369] 'compare' (duration: 90.914074ms)"],"step_count":2}