{"level":"warn","ts":"2026-04-13T20:33:49.050964Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T20:33:49.05139Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-13T20:33:49.051593Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T20:33:49.051651Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-04-13T20:33:49.051724Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-13T20:33:49.052686Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-04-13T20:33:49.052847Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-13T20:33:49.718576Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"665.399701ms"} {"level":"info","ts":"2026-04-13T20:33:49.741307Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-04-13T20:33:49.741424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T20:33:49.741563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-04-13T20:33:49.741583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T20:33:49.741608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-04-13T20:33:49.741681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-04-13T20:33:49.74939Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T20:33:49.752005Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T20:33:49.753682Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-13T20:33:49.756282Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T20:33:49.756704Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T20:33:49.756799Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T20:33:49.756817Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T20:33:49.756453Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T20:33:49.760414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-04-13T20:33:49.761291Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-04-13T20:33:49.762359Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-13T20:33:49.762815Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T20:33:49.762505Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-04-13T20:33:49.763847Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-04-13T20:33:49.763035Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T20:33:50.742322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T20:33:50.742381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T20:33:50.742406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-04-13T20:33:50.742427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-04-13T20:33:50.742439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-04-13T20:33:50.742456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-04-13T20:33:50.742476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-04-13T20:33:50.743468Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T20:33:50.743515Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T20:33:50.743501Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T20:33:50.743573Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T20:33:50.743772Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T20:33:50.743786Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T20:33:50.744463Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T20:33:50.744551Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T20:33:50.744584Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T20:33:50.746732Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-04-13T20:33:50.747235Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T20:33:54.477424Z","caller":"traceutil/trace.go:171","msg":"trace[263347418] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"107.681321ms","start":"2026-04-13T20:33:54.36972Z","end":"2026-04-13T20:33:54.477401Z","steps":["trace[263347418] 'process raft request' (duration: 102.985161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:33:58.662775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.281659ms","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-04-13T20:33:58.662943Z","caller":"traceutil/trace.go:171","msg":"trace[1868287360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:326; }","duration":"140.514618ms","start":"2026-04-13T20:33:58.522397Z","end":"2026-04-13T20:33:58.662911Z","steps":["trace[1868287360] 'range keys from in-memory index tree' (duration: 140.06202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:33:58.663415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.730264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:33:58.663461Z","caller":"traceutil/trace.go:171","msg":"trace[1707547500] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:0; response_revision:326; }","duration":"147.784086ms","start":"2026-04-13T20:33:58.515665Z","end":"2026-04-13T20:33:58.663449Z","steps":["trace[1707547500] 'range keys from in-memory index tree' (duration: 147.621911ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:34:00.032019Z","caller":"traceutil/trace.go:171","msg":"trace[1215696868] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"415.421492ms","start":"2026-04-13T20:33:59.616561Z","end":"2026-04-13T20:34:00.031982Z","steps":["trace[1215696868] 'process raft request' (duration: 415.264817ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:34:00.032528Z","caller":"traceutil/trace.go:171","msg":"trace[832093104] linearizableReadLoop","detail":"{readStateIndex:347; appliedIndex:346; }","duration":"245.601259ms","start":"2026-04-13T20:33:59.786901Z","end":"2026-04-13T20:34:00.032502Z","steps":["trace[832093104] 'read index received' (duration: 244.879403ms)","trace[832093104] 'applied index is now lower than readState.Index' (duration: 719.686µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:34:00.032721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.801406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-13T20:34:00.032693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:33:59.616537Z","time spent":"415.577258ms","remote":"127.0.0.1:37960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T20:34:00.032763Z","caller":"traceutil/trace.go:171","msg":"trace[1470106253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:336; }","duration":"245.90408ms","start":"2026-04-13T20:33:59.786848Z","end":"2026-04-13T20:34:00.032752Z","steps":["trace[1470106253] 'agreement among raft nodes before linearized reading' (duration: 245.772755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:00.877872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.392337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T20:34:00.878003Z","caller":"traceutil/trace.go:171","msg":"trace[1312220154] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:347; }","duration":"836.219502ms","start":"2026-04-13T20:34:00.041766Z","end":"2026-04-13T20:34:00.877986Z","steps":["trace[1312220154] 'read index received' (duration: 219.120679ms)","trace[1312220154] 'applied index is now lower than readState.Index' (duration: 617.097443ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:34:00.87809Z","caller":"traceutil/trace.go:171","msg":"trace[791560470] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"839.840523ms","start":"2026-04-13T20:34:00.038205Z","end":"2026-04-13T20:34:00.878045Z","steps":["trace[791560470] 'process raft request' (duration: 222.7369ms)","trace[791560470] 'compare' (duration: 616.236032ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:34:00.878125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.338996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-04-13T20:34:00.87814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.14607ms","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-04-13T20:34:00.878258Z","caller":"traceutil/trace.go:171","msg":"trace[604190961] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:337; }","duration":"836.492181ms","start":"2026-04-13T20:34:00.041735Z","end":"2026-04-13T20:34:00.878227Z","steps":["trace[604190961] 'agreement among raft nodes before linearized reading' (duration: 836.316535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:00.878302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:00.038189Z","time spent":"840.006349ms","remote":"127.0.0.1:38012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:34:00.878331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:00.041723Z","time spent":"836.596575ms","remote":"127.0.0.1:37862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-13T20:34:00.878328Z","caller":"traceutil/trace.go:171","msg":"trace[1758542794] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:337; }","duration":"201.340907ms","start":"2026-04-13T20:34:00.676952Z","end":"2026-04-13T20:34:00.878293Z","steps":["trace[1758542794] 'agreement among raft nodes before linearized reading' (duration: 201.140319ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:34:01.312206Z","caller":"traceutil/trace.go:171","msg":"trace[1695753030] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"429.851167ms","start":"2026-04-13T20:34:00.882332Z","end":"2026-04-13T20:34:01.312183Z","steps":["trace[1695753030] 'process raft request' (duration: 336.53236ms)","trace[1695753030] 'compare' (duration: 92.473147ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:34:01.312206Z","caller":"traceutil/trace.go:171","msg":"trace[809923578] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"428.321372ms","start":"2026-04-13T20:34:00.883858Z","end":"2026-04-13T20:34:01.312179Z","steps":["trace[809923578] 'process raft request' (duration: 427.909837ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:34:01.312206Z","caller":"traceutil/trace.go:171","msg":"trace[1867793575] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"428.804029ms","start":"2026-04-13T20:34:00.883359Z","end":"2026-04-13T20:34:01.312163Z","steps":["trace[1867793575] 'process raft request' (duration: 428.153426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:01.312324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:00.882307Z","time spent":"429.963231ms","remote":"127.0.0.1:37960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T20:34:01.312358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:00.883847Z","time spent":"428.430826ms","remote":"127.0.0.1:38012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:34:01.312828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:00.88333Z","time spent":"428.969215ms","remote":"127.0.0.1:38012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:34:08.660546Z","caller":"traceutil/trace.go:171","msg":"trace[609944923] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"101.189855ms","start":"2026-04-13T20:34:08.559334Z","end":"2026-04-13T20:34:08.660523Z","steps":["trace[609944923] 'process raft request' (duration: 101.042531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:23.555458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898555347579213,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T20:34:23.647635Z","caller":"traceutil/trace.go:171","msg":"trace[1034080172] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"593.537253ms","start":"2026-04-13T20:34:23.054072Z","end":"2026-04-13T20:34:23.647609Z","steps":["trace[1034080172] 'process raft request' (duration: 570.967734ms)","trace[1034080172] 'compare' (duration: 22.356503ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:34:23.647775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:23.054051Z","time spent":"593.650986ms","remote":"127.0.0.1:37796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:34:23.649679Z","caller":"traceutil/trace.go:171","msg":"trace[1448483054] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:574; }","duration":"595.035017ms","start":"2026-04-13T20:34:23.054628Z","end":"2026-04-13T20:34:23.649663Z","steps":["trace[1448483054] 'read index received' (duration: 570.43153ms)","trace[1448483054] 'applied index is now lower than readState.Index' (duration: 24.602647ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:34:23.650158Z","caller":"traceutil/trace.go:171","msg":"trace[302211513] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"591.946197ms","start":"2026-04-13T20:34:23.0582Z","end":"2026-04-13T20:34:23.650146Z","steps":["trace[302211513] 'process raft request' (duration: 590.607178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:23.650233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:23.058186Z","time spent":"591.998028ms","remote":"127.0.0.1:37796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:34:23.650356Z","caller":"traceutil/trace.go:171","msg":"trace[1266274026] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"576.712299ms","start":"2026-04-13T20:34:23.073635Z","end":"2026-04-13T20:34:23.650347Z","steps":["trace[1266274026] 'process raft request' (duration: 575.365641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:23.650406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:23.073616Z","time spent":"576.76184ms","remote":"127.0.0.1:37796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:34:23.650528Z","caller":"traceutil/trace.go:171","msg":"trace[286760203] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"530.260133ms","start":"2026-04-13T20:34:23.120257Z","end":"2026-04-13T20:34:23.650517Z","steps":["trace[286760203] 'process raft request' (duration: 529.307486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:23.650572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:23.120236Z","time spent":"530.313675ms","remote":"127.0.0.1:37796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T20:34:23.650715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.082027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-13T20:34:23.650748Z","caller":"traceutil/trace.go:171","msg":"trace[635680462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:557; }","duration":"596.139978ms","start":"2026-04-13T20:34:23.054599Z","end":"2026-04-13T20:34:23.650739Z","steps":["trace[635680462] 'agreement among raft nodes before linearized reading' (duration: 596.076506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:23.650774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:23.054588Z","time spent":"596.178699ms","remote":"127.0.0.1:38012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T20:34:23.650893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.854835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:34:23.65091Z","caller":"traceutil/trace.go:171","msg":"trace[1433186946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"487.932386ms","start":"2026-04-13T20:34:23.162972Z","end":"2026-04-13T20:34:23.650905Z","steps":["trace[1433186946] 'agreement among raft nodes before linearized reading' (duration: 487.905476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:23.65093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:23.162951Z","time spent":"487.973948ms","remote":"127.0.0.1:60674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T20:34:23.651288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.476301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-13T20:34:23.651311Z","caller":"traceutil/trace.go:171","msg":"trace[384024090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:557; }","duration":"218.541463ms","start":"2026-04-13T20:34:23.432763Z","end":"2026-04-13T20:34:23.651305Z","steps":["trace[384024090] 'agreement among raft nodes before linearized reading' (duration: 218.44235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:23.651585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.242678ms","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-04-13T20:34:23.651601Z","caller":"traceutil/trace.go:171","msg":"trace[72445543] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:557; }","duration":"222.315861ms","start":"2026-04-13T20:34:23.429281Z","end":"2026-04-13T20:34:23.651596Z","steps":["trace[72445543] 'agreement among raft nodes before linearized reading' (duration: 222.27182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:23.651614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.173556ms","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-04-13T20:34:23.651665Z","caller":"traceutil/trace.go:171","msg":"trace[121816212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:557; }","duration":"222.283609ms","start":"2026-04-13T20:34:23.429368Z","end":"2026-04-13T20:34:23.651652Z","steps":["trace[121816212] 'agreement among raft nodes before linearized reading' (duration: 222.040563ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:34:24.75838Z","caller":"traceutil/trace.go:171","msg":"trace[1972454608] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"611.621491ms","start":"2026-04-13T20:34:24.146737Z","end":"2026-04-13T20:34:24.758358Z","steps":["trace[1972454608] 'process raft request' (duration: 583.346705ms)","trace[1972454608] 'compare' (duration: 28.08558ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:34:24.75851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:24.146707Z","time spent":"611.738175ms","remote":"127.0.0.1:37796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:34:24.75906Z","caller":"traceutil/trace.go:171","msg":"trace[947469624] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"590.836088ms","start":"2026-04-13T20:34:24.168199Z","end":"2026-04-13T20:34:24.759036Z","steps":["trace[947469624] 'process raft request' (duration: 590.379785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:24.759237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:24.168174Z","time spent":"590.961892ms","remote":"127.0.0.1:37796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:34:24.760406Z","caller":"traceutil/trace.go:171","msg":"trace[1055400306] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"573.548275ms","start":"2026-04-13T20:34:24.186839Z","end":"2026-04-13T20:34:24.760387Z","steps":["trace[1055400306] 'process raft request' (duration: 573.116973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:24.760514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:24.186811Z","time spent":"573.650679ms","remote":"127.0.0.1:37796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T20:34:30.345642Z","caller":"traceutil/trace.go:171","msg":"trace[24047907] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"112.153686ms","start":"2026-04-13T20:34:30.233462Z","end":"2026-04-13T20:34:30.345616Z","steps":["trace[24047907] 'process raft request' (duration: 111.92375ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:34:30.742238Z","caller":"traceutil/trace.go:171","msg":"trace[786677818] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"255.528315ms","start":"2026-04-13T20:34:30.486685Z","end":"2026-04-13T20:34:30.742213Z","steps":["trace[786677818] 'process raft request' (duration: 242.843922ms)","trace[786677818] 'compare' (duration: 12.306392ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:34:31.66326Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898555347579400,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T20:34:31.726382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.054333ms","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-04-13T20:34:31.726508Z","caller":"traceutil/trace.go:171","msg":"trace[2075731392] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:627; }","duration":"187.197987ms","start":"2026-04-13T20:34:31.539289Z","end":"2026-04-13T20:34:31.726487Z","steps":["trace[2075731392] 'range keys from in-memory index tree' (duration: 187.030543ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:34:31.726723Z","caller":"traceutil/trace.go:171","msg":"trace[1370059895] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"564.095406ms","start":"2026-04-13T20:34:31.162615Z","end":"2026-04-13T20:34:31.726711Z","steps":["trace[1370059895] 'read index received' (duration: 559.77452ms)","trace[1370059895] 'applied index is now lower than readState.Index' (duration: 4.319666ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:34:31.726806Z","caller":"traceutil/trace.go:171","msg":"trace[2046673184] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"929.026706ms","start":"2026-04-13T20:34:30.797768Z","end":"2026-04-13T20:34:31.726795Z","steps":["trace[2046673184] 'process raft request' (duration: 924.635598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:31.726896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:30.797744Z","time spent":"929.084008ms","remote":"127.0.0.1:37988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T20:34:31.727383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.760744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:34:31.727468Z","caller":"traceutil/trace.go:171","msg":"trace[2107878202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"564.887707ms","start":"2026-04-13T20:34:31.162558Z","end":"2026-04-13T20:34:31.727445Z","steps":["trace[2107878202] 'agreement among raft nodes before linearized reading' (duration: 564.765574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:31.729376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:31.162538Z","time spent":"566.802538ms","remote":"127.0.0.1:60674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T20:34:54.271521Z","caller":"traceutil/trace.go:171","msg":"trace[1833470992] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:835; }","duration":"432.478011ms","start":"2026-04-13T20:34:53.839011Z","end":"2026-04-13T20:34:54.271489Z","steps":["trace[1833470992] 'read index received' (duration: 432.367028ms)","trace[1833470992] 'applied index is now lower than readState.Index' (duration: 109.573µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:34:54.271587Z","caller":"traceutil/trace.go:171","msg":"trace[1862236834] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"432.932131ms","start":"2026-04-13T20:34:53.838621Z","end":"2026-04-13T20:34:54.271553Z","steps":["trace[1862236834] 'process raft request' (duration: 432.722507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:54.27178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.748826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-13T20:34:54.271825Z","caller":"traceutil/trace.go:171","msg":"trace[1540044364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:808; }","duration":"432.849019ms","start":"2026-04-13T20:34:53.838965Z","end":"2026-04-13T20:34:54.271814Z","steps":["trace[1540044364] 'agreement among raft nodes before linearized reading' (duration: 432.677835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:54.271861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:53.838944Z","time spent":"432.908791ms","remote":"127.0.0.1:38012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T20:34:54.271838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:53.838599Z","time spent":"433.075724ms","remote":"127.0.0.1:38012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:34:54.772137Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898555347579824,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T20:34:55.000394Z","caller":"traceutil/trace.go:171","msg":"trace[1966016601] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"735.279918ms","start":"2026-04-13T20:34:54.26509Z","end":"2026-04-13T20:34:55.00037Z","steps":["trace[1966016601] 'process raft request' (duration: 735.164806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:55.000505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:54.265065Z","time spent":"735.383411ms","remote":"127.0.0.1:37988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:34:55.00348Z","caller":"traceutil/trace.go:171","msg":"trace[109449097] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:836; }","duration":"731.836051ms","start":"2026-04-13T20:34:54.271611Z","end":"2026-04-13T20:34:55.003447Z","steps":["trace[109449097] 'read index received' (duration: 728.709852ms)","trace[109449097] 'applied index is now lower than readState.Index' (duration: 3.125349ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:34:55.003604Z","caller":"traceutil/trace.go:171","msg":"trace[221819676] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"726.805669ms","start":"2026-04-13T20:34:54.276772Z","end":"2026-04-13T20:34:55.003577Z","steps":["trace[221819676] 'process raft request' (duration: 726.537643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:55.003843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164423334s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-13T20:34:55.003804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16291668s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-13T20:34:55.003616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.927912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:34:55.003945Z","caller":"traceutil/trace.go:171","msg":"trace[500881853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:810; }","duration":"1.163084234s","start":"2026-04-13T20:34:53.840846Z","end":"2026-04-13T20:34:55.00393Z","steps":["trace[500881853] 'agreement among raft nodes before linearized reading' (duration: 1.162804878s)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:34:55.003962Z","caller":"traceutil/trace.go:171","msg":"trace[1359468481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:810; }","duration":"1.164524217s","start":"2026-04-13T20:34:53.839393Z","end":"2026-04-13T20:34:55.003918Z","steps":["trace[1359468481] 'agreement among raft nodes before linearized reading' (duration: 1.164276561s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:55.004147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:53.839386Z","time spent":"1.164749131s","remote":"127.0.0.1:38012","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-04-13T20:34:55.004085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:53.840835Z","time spent":"1.163232547s","remote":"127.0.0.1:38012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T20:34:55.004027Z","caller":"traceutil/trace.go:171","msg":"trace[1222894724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:810; }","duration":"840.393122ms","start":"2026-04-13T20:34:54.163607Z","end":"2026-04-13T20:34:55.004001Z","steps":["trace[1222894724] 'agreement among raft nodes before linearized reading' (duration: 839.85736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:55.004173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:54.276743Z","time spent":"727.135187ms","remote":"127.0.0.1:38012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:34:55.003752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007657998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-13T20:34:55.004693Z","caller":"traceutil/trace.go:171","msg":"trace[931250118] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:810; }","duration":"1.008617928s","start":"2026-04-13T20:34:53.996065Z","end":"2026-04-13T20:34:55.004683Z","steps":["trace[931250118] 'agreement among raft nodes before linearized reading' (duration: 1.007552295s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:34:55.004754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:53.995994Z","time spent":"1.008749791s","remote":"127.0.0.1:37772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.53\" "} {"level":"warn","ts":"2026-04-13T20:34:55.004458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:54.163583Z","time spent":"840.852042ms","remote":"127.0.0.1:60674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T20:34:59.517757Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898555347579908,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T20:35:00.018909Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898555347579908,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T20:35:00.519301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898555347579908,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T20:35:01.495768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898555347579908,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T20:35:01.540134Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.783044929s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T20:35:01.54235Z","caller":"traceutil/trace.go:171","msg":"trace[286748429] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"2.787902743s","start":"2026-04-13T20:34:58.754422Z","end":"2026-04-13T20:35:01.542324Z","steps":["trace[286748429] 'process raft request' (duration: 2.786581226s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:35:01.542492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:58.754391Z","time spent":"2.788022166s","remote":"127.0.0.1:37838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036286,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:35:01.564934Z","caller":"traceutil/trace.go:171","msg":"trace[444121213] linearizableReadLoop","detail":"{readStateIndex:887; appliedIndex:885; }","duration":"2.547752914s","start":"2026-04-13T20:34:59.017157Z","end":"2026-04-13T20:35:01.56491Z","steps":["trace[444121213] 'read index received' (duration: 2.523198595s)","trace[444121213] 'applied index is now lower than readState.Index' (duration: 24.553069ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:35:01.565157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.5479806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-13T20:35:01.565191Z","caller":"traceutil/trace.go:171","msg":"trace[1598583707] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:856; }","duration":"2.548062611s","start":"2026-04-13T20:34:59.017118Z","end":"2026-04-13T20:35:01.565181Z","steps":["trace[1598583707] 'agreement among raft nodes before linearized reading' (duration: 2.547920218s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:35:01.565217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:59.017101Z","time spent":"2.548109682s","remote":"127.0.0.1:38012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T20:35:01.565373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.534031531s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-13T20:35:01.565409Z","caller":"traceutil/trace.go:171","msg":"trace[520211571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:856; }","duration":"2.534080401s","start":"2026-04-13T20:34:59.031319Z","end":"2026-04-13T20:35:01.5654Z","steps":["trace[520211571] 'agreement among raft nodes before linearized reading' (duration: 2.53399756s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:35:01.565405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.397916392s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T20:35:01.565429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:59.031313Z","time spent":"2.534111162s","remote":"127.0.0.1:38012","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-04-13T20:35:01.565438Z","caller":"traceutil/trace.go:171","msg":"trace[177874350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:856; }","duration":"1.397975473s","start":"2026-04-13T20:35:00.167454Z","end":"2026-04-13T20:35:01.565429Z","steps":["trace[177874350] 'agreement among raft nodes before linearized reading' (duration: 1.397922962s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:35:01.565465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:35:00.167441Z","time spent":"1.398019405s","remote":"127.0.0.1:60674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T20:35:01.565529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267327114s","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-04-13T20:35:01.565556Z","caller":"traceutil/trace.go:171","msg":"trace[1761313317] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:856; }","duration":"1.267377715s","start":"2026-04-13T20:35:00.298171Z","end":"2026-04-13T20:35:01.565549Z","steps":["trace[1761313317] 'agreement among raft nodes before linearized reading' (duration: 1.267303794s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:35:01.565575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:35:00.298156Z","time spent":"1.267414046s","remote":"127.0.0.1:38012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T20:35:01.565575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.469323815s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-13T20:35:01.565604Z","caller":"traceutil/trace.go:171","msg":"trace[1567214168] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:856; }","duration":"2.469372015s","start":"2026-04-13T20:34:59.096225Z","end":"2026-04-13T20:35:01.565597Z","steps":["trace[1567214168] 'agreement among raft nodes before linearized reading' (duration: 2.469301974s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:35:01.565628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:59.096218Z","time spent":"2.469405197s","remote":"127.0.0.1:38012","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":"warn","ts":"2026-04-13T20:35:01.565671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.534319848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-13T20:35:01.565688Z","caller":"traceutil/trace.go:171","msg":"trace[1852998050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:856; }","duration":"2.534364658s","start":"2026-04-13T20:34:59.031319Z","end":"2026-04-13T20:35:01.565683Z","steps":["trace[1852998050] 'agreement among raft nodes before linearized reading' (duration: 2.534309207s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:35:01.565709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:34:59.031306Z","time spent":"2.53439364s","remote":"127.0.0.1:38012","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-04-13T20:35:23.824336Z","caller":"traceutil/trace.go:171","msg":"trace[364628454] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"131.966528ms","start":"2026-04-13T20:35:23.692338Z","end":"2026-04-13T20:35:23.824305Z","steps":["trace[364628454] 'process raft request' (duration: 131.739874ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:36:00.690107Z","caller":"traceutil/trace.go:171","msg":"trace[1249743291] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"107.651527ms","start":"2026-04-13T20:36:00.582433Z","end":"2026-04-13T20:36:00.690084Z","steps":["trace[1249743291] 'process raft request' (duration: 107.475774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:36:08.512157Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898555347580659,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T20:36:09.012504Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898555347580659,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T20:36:09.333764Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.431270811s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T20:36:09.334063Z","caller":"traceutil/trace.go:171","msg":"trace[189592152] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"1.431647137s","start":"2026-04-13T20:36:07.902397Z","end":"2026-04-13T20:36:09.334044Z","steps":["trace[189592152] 'process raft request' (duration: 1.431537276s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:36:09.334179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:36:07.902377Z","time spent":"1.431745729s","remote":"127.0.0.1:38012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:36:09.64529Z","caller":"traceutil/trace.go:171","msg":"trace[54310595] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"1.57673088s","start":"2026-04-13T20:36:08.068521Z","end":"2026-04-13T20:36:09.645252Z","steps":["trace[54310595] 'process raft request' (duration: 1.556451755s)","trace[54310595] 'compare' (duration: 19.98099ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:36:09.645355Z","caller":"traceutil/trace.go:171","msg":"trace[604167420] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"722.481932ms","start":"2026-04-13T20:36:08.922851Z","end":"2026-04-13T20:36:09.645333Z","steps":["trace[604167420] 'process raft request' (duration: 722.317019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:36:09.645466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:36:08.922838Z","time spent":"722.567563ms","remote":"127.0.0.1:38012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:36:09.645458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:36:08.068501Z","time spent":"1.576875851s","remote":"127.0.0.1:38012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:36:10.1627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000478624s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-13T20:36:10.162795Z","caller":"traceutil/trace.go:171","msg":"trace[397510064] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000616126s","start":"2026-04-13T20:36:08.162159Z","end":"2026-04-13T20:36:10.162775Z","steps":["trace[397510064] 'agreement among raft nodes before linearized reading' (duration: 2.000503945s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:36:10.162862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:36:08.162143Z","time spent":"2.000701147s","remote":"127.0.0.1:60674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/13 20:36:10 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T20:36:11.039252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.393992398s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-13T20:36:11.03933Z","caller":"traceutil/trace.go:171","msg":"trace[1914017222] linearizableReadLoop","detail":"{readStateIndex:1221; appliedIndex:1219; }","duration":"3.027818957s","start":"2026-04-13T20:36:08.011497Z","end":"2026-04-13T20:36:11.039316Z","steps":["trace[1914017222] 'read index received' (duration: 1.322657661s)","trace[1914017222] 'applied index is now lower than readState.Index' (duration: 1.705160246s)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:36:11.040043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.738569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:36:11.040125Z","caller":"traceutil/trace.go:171","msg":"trace[715667088] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1176; }","duration":"913.867072ms","start":"2026-04-13T20:36:10.126236Z","end":"2026-04-13T20:36:11.040103Z","steps":["trace[715667088] 'agreement among raft nodes before linearized reading' (duration: 913.7464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:36:11.040129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.99409036s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-13T20:36:11.040168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:36:10.126216Z","time spent":"913.940902ms","remote":"127.0.0.1:51260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-13T20:36:11.040182Z","caller":"traceutil/trace.go:171","msg":"trace[1367093890] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1176; }","duration":"2.994169351s","start":"2026-04-13T20:36:08.045998Z","end":"2026-04-13T20:36:11.040167Z","steps":["trace[1367093890] 'agreement among raft nodes before linearized reading' (duration: 2.993972538s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:36:11.040219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:36:08.045985Z","time spent":"2.994223451s","remote":"127.0.0.1:38012","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":"warn","ts":"2026-04-13T20:36:11.040233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.297556095s","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":"warn","ts":"2026-04-13T20:36:11.040284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.979704353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-13T20:36:11.040248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.978088888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-13T20:36:11.040294Z","caller":"traceutil/trace.go:171","msg":"trace[171286236] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1176; }","duration":"2.297659946s","start":"2026-04-13T20:36:08.742619Z","end":"2026-04-13T20:36:11.040279Z","steps":["trace[171286236] 'agreement among raft nodes before linearized reading' (duration: 2.297545065s)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:36:11.040318Z","caller":"traceutil/trace.go:171","msg":"trace[1018542089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1176; }","duration":"2.979784924s","start":"2026-04-13T20:36:08.060522Z","end":"2026-04-13T20:36:11.040307Z","steps":["trace[1018542089] 'agreement among raft nodes before linearized reading' (duration: 2.979639302s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:36:11.040335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:36:08.742606Z","time spent":"2.297720537s","remote":"127.0.0.1:38036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-13T20:36:11.040337Z","caller":"traceutil/trace.go:171","msg":"trace[1910823634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1176; }","duration":"2.97819395s","start":"2026-04-13T20:36:08.062123Z","end":"2026-04-13T20:36:11.040317Z","steps":["trace[1910823634] 'agreement among raft nodes before linearized reading' (duration: 2.977942136s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:36:11.040348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:36:08.060501Z","time spent":"2.979841095s","remote":"127.0.0.1:38012","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-04-13T20:36:11.040365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.573158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T20:36:11.040354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.409514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5547"} {"level":"warn","ts":"2026-04-13T20:36:11.040383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:36:08.062117Z","time spent":"2.978253371s","remote":"127.0.0.1:38012","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-04-13T20:36:11.040397Z","caller":"traceutil/trace.go:171","msg":"trace[1069996382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1176; }","duration":"877.63135ms","start":"2026-04-13T20:36:10.162754Z","end":"2026-04-13T20:36:11.040386Z","steps":["trace[1069996382] 'agreement among raft nodes before linearized reading' (duration: 877.585749ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:36:11.040419Z","caller":"traceutil/trace.go:171","msg":"trace[740786073] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1176; }","duration":"329.516976ms","start":"2026-04-13T20:36:10.710885Z","end":"2026-04-13T20:36:11.040402Z","steps":["trace[740786073] 'agreement among raft nodes before linearized reading' (duration: 329.299003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:36:11.040423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:36:10.162738Z","time spent":"877.68002ms","remote":"127.0.0.1:60664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T20:36:11.040453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:36:10.710862Z","time spent":"329.583877ms","remote":"127.0.0.1:37932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5571,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-13T20:36:11.040679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.029179867s","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-04-13T20:36:11.040709Z","caller":"traceutil/trace.go:171","msg":"trace[1411351712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1176; }","duration":"3.029245317s","start":"2026-04-13T20:36:08.011453Z","end":"2026-04-13T20:36:11.040699Z","steps":["trace[1411351712] 'agreement among raft nodes before linearized reading' (duration: 3.029160136s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:36:11.040734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:36:08.011436Z","time spent":"3.029293069s","remote":"127.0.0.1:38012","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-04-13T20:36:12.563023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02384717s","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-04-13T20:36:12.563126Z","caller":"traceutil/trace.go:171","msg":"trace[1008768562] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1182; }","duration":"1.023961032s","start":"2026-04-13T20:36:11.539149Z","end":"2026-04-13T20:36:12.56311Z","steps":["trace[1008768562] 'range keys from in-memory index tree' (duration: 1.023814579s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:36:12.56416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.274279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T20:36:12.564301Z","caller":"traceutil/trace.go:171","msg":"trace[2027646861] linearizableReadLoop","detail":"{readStateIndex:1230; appliedIndex:1229; }","duration":"838.775782ms","start":"2026-04-13T20:36:11.725503Z","end":"2026-04-13T20:36:12.564279Z","steps":["trace[2027646861] 'read index received' (duration: 195.222629ms)","trace[2027646861] 'applied index is now lower than readState.Index' (duration: 643.551013ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:36:12.564358Z","caller":"traceutil/trace.go:171","msg":"trace[506360602] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"913.489395ms","start":"2026-04-13T20:36:11.650855Z","end":"2026-04-13T20:36:12.564344Z","steps":["trace[506360602] 'process raft request' (duration: 269.921112ms)","trace[506360602] 'compare' (duration: 642.562429ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:36:12.56455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.046287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5547"} {"level":"info","ts":"2026-04-13T20:36:12.56464Z","caller":"traceutil/trace.go:171","msg":"trace[2057966923] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1183; }","duration":"839.179688ms","start":"2026-04-13T20:36:11.725442Z","end":"2026-04-13T20:36:12.564622Z","steps":["trace[2057966923] 'agreement among raft nodes before linearized reading' (duration: 838.923735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:36:12.56471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:36:11.725418Z","time spent":"839.278601ms","remote":"127.0.0.1:37932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5571,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-13T20:36:12.564748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.387692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T20:36:12.564805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.745739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:36:12.564844Z","caller":"traceutil/trace.go:171","msg":"trace[953084755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1183; }","duration":"403.511743ms","start":"2026-04-13T20:36:12.161322Z","end":"2026-04-13T20:36:12.564834Z","steps":["trace[953084755] 'agreement among raft nodes before linearized reading' (duration: 403.390282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:36:12.564857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.285236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T20:36:12.56493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:36:12.161307Z","time spent":"403.614466ms","remote":"127.0.0.1:60674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T20:36:12.564925Z","caller":"traceutil/trace.go:171","msg":"trace[322626557] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1183; }","duration":"570.89496ms","start":"2026-04-13T20:36:11.993979Z","end":"2026-04-13T20:36:12.564874Z","steps":["trace[322626557] 'agreement among raft nodes before linearized reading' (duration: 570.724398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:36:12.56502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:36:11.993959Z","time spent":"571.046463ms","remote":"127.0.0.1:51390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-04-13T20:36:12.565114Z","caller":"traceutil/trace.go:171","msg":"trace[881383479] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1183; }","duration":"577.488648ms","start":"2026-04-13T20:36:11.987452Z","end":"2026-04-13T20:36:12.56494Z","steps":["trace[881383479] 'agreement among raft nodes before linearized reading' (duration: 577.240624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:36:12.565258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:36:11.650834Z","time spent":"913.608937ms","remote":"127.0.0.1:38012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:36:12.565335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:36:11.987434Z","time spent":"577.859864ms","remote":"127.0.0.1:51286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-13T20:36:12.926537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.851097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T20:36:12.926595Z","caller":"traceutil/trace.go:171","msg":"trace[1390347341] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1183; }","duration":"199.97173ms","start":"2026-04-13T20:36:12.72661Z","end":"2026-04-13T20:36:12.926582Z","steps":["trace[1390347341] 'count revisions from in-memory index tree' (duration: 199.746946ms)"],"step_count":1}