{"level":"warn","ts":"2026-06-12T22:58:27.683671Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-12T22:58:27.68378Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.177: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.177:2380","--initial-cluster=instance=https://199.204.45.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.177: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-06-12T22:58:27.683867Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-12T22:58:27.683923Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-06-12T22:58:27.683994Z","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-06-12T22:58:27.684937Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"]} {"level":"info","ts":"2026-06-12T22:58:27.685037Z","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.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.177: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-06-12T22:58:27.689295Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.990748ms"} {"level":"info","ts":"2026-06-12T22:58:27.696194Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7c53a18098324dc9","cluster-id":"4717e8aa221973c8"} {"level":"info","ts":"2026-06-12T22:58:27.696276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=()"} {"level":"info","ts":"2026-06-12T22:58:27.696299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 0"} {"level":"info","ts":"2026-06-12T22:58:27.696314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7c53a18098324dc9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-12T22:58:27.696324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 1"} {"level":"info","ts":"2026-06-12T22:58:27.696366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"warn","ts":"2026-06-12T22:58:27.701945Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-12T22:58:27.703947Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-12T22:58:27.70498Z","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-06-12T22:58:27.707851Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7c53a18098324dc9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-12T22:58:27.707974Z","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-06-12T22:58:27.708076Z","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-06-12T22:58:27.708088Z","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-06-12T22:58:27.70853Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7c53a18098324dc9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-12T22:58:27.711081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"info","ts":"2026-06-12T22:58:27.711246Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","added-peer-id":"7c53a18098324dc9","added-peer-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-06-12T22:58:27.713677Z","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-06-12T22:58:27.713827Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-06-12T22:58:27.713901Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-06-12T22:58:27.714037Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7c53a18098324dc9","initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-12T22:58:27.714121Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-12T22:58:27.997476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 is starting a new election at term 1"} {"level":"info","ts":"2026-06-12T22:58:27.997533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-12T22:58:27.997581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgPreVoteResp from 7c53a18098324dc9 at term 1"} {"level":"info","ts":"2026-06-12T22:58:27.997625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became candidate at term 2"} {"level":"info","ts":"2026-06-12T22:58:27.997645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgVoteResp from 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-06-12T22:58:27.99767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became leader at term 2"} {"level":"info","ts":"2026-06-12T22:58:27.997692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7c53a18098324dc9 elected leader 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-06-12T22:58:27.998765Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7c53a18098324dc9","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.177:2379]}","request-path":"/0/members/7c53a18098324dc9/attributes","cluster-id":"4717e8aa221973c8","publish-timeout":"7s"} {"level":"info","ts":"2026-06-12T22:58:27.998768Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-12T22:58:27.998824Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-12T22:58:27.999094Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-12T22:58:27.999141Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-12T22:58:27.999796Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","cluster-version":"3.5"} {"level":"info","ts":"2026-06-12T22:58:27.999904Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-12T22:58:27.999946Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-12T22:58:28.001207Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.177:2379"} {"level":"info","ts":"2026-06-12T22:58:28.001258Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-12T22:58:28.003219Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-12T22:58:41.583105Z","caller":"traceutil/trace.go:171","msg":"trace[257428969] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:415; }","duration":"455.403627ms","start":"2026-06-12T22:58:41.127681Z","end":"2026-06-12T22:58:41.583085Z","steps":["trace[257428969] 'read index received' (duration: 455.195101ms)","trace[257428969] 'applied index is now lower than readState.Index' (duration: 207.706µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:58:41.583164Z","caller":"traceutil/trace.go:171","msg":"trace[873206784] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"561.598624ms","start":"2026-06-12T22:58:41.021528Z","end":"2026-06-12T22:58:41.583126Z","steps":["trace[873206784] 'process raft request' (duration: 561.331777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:58:41.583444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.344056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-12T22:58:41.583454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.577681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-12T22:58:41.583545Z","caller":"traceutil/trace.go:171","msg":"trace[1065592620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:401; }","duration":"141.662144ms","start":"2026-06-12T22:58:41.441868Z","end":"2026-06-12T22:58:41.58353Z","steps":["trace[1065592620] 'agreement among raft nodes before linearized reading' (duration: 141.349596ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:58:41.583558Z","caller":"traceutil/trace.go:171","msg":"trace[1739386863] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:401; }","duration":"455.89097ms","start":"2026-06-12T22:58:41.12765Z","end":"2026-06-12T22:58:41.58354Z","steps":["trace[1739386863] 'agreement among raft nodes before linearized reading' (duration: 455.564781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:58:41.583604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:58:41.021512Z","time spent":"561.717147ms","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T22:58:41.583596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:58:41.127633Z","time spent":"455.951361ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.177\" "} {"level":"warn","ts":"2026-06-12T22:58:47.95987Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185750370153394,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T22:58:48.188542Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.386172252s","expected-duration":"1s"} {"level":"info","ts":"2026-06-12T22:58:48.189233Z","caller":"traceutil/trace.go:171","msg":"trace[1852703408] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"730.158096ms","start":"2026-06-12T22:58:47.45905Z","end":"2026-06-12T22:58:48.189208Z","steps":["trace[1852703408] 'read index received' (duration: 729.903639ms)","trace[1852703408] 'applied index is now lower than readState.Index' (duration: 250.857µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:58:48.189424Z","caller":"traceutil/trace.go:171","msg":"trace[1609083384] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"1.389205989s","start":"2026-06-12T22:58:46.80006Z","end":"2026-06-12T22:58:48.189266Z","steps":["trace[1609083384] 'process raft request' (duration: 1.388878431s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:58:48.18956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.622301ms","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-06-12T22:58:48.189614Z","caller":"traceutil/trace.go:171","msg":"trace[673912958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:418; }","duration":"582.694033ms","start":"2026-06-12T22:58:47.606904Z","end":"2026-06-12T22:58:48.189598Z","steps":["trace[673912958] 'agreement among raft nodes before linearized reading' (duration: 582.615471ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:58:48.189669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:58:47.60689Z","time spent":"582.766825ms","remote":"127.0.0.1:51368","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-06-12T22:58:48.189869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:58:46.800025Z","time spent":"1.389563488s","remote":"127.0.0.1:51280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T22:58:48.189991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.546647ms","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-06-12T22:58:48.190044Z","caller":"traceutil/trace.go:171","msg":"trace[2040019732] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:418; }","duration":"259.632399ms","start":"2026-06-12T22:58:47.930398Z","end":"2026-06-12T22:58:48.190031Z","steps":["trace[2040019732] 'agreement among raft nodes before linearized reading' (duration: 259.547207ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:58:48.190253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.963049ms","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-06-12T22:58:48.190299Z","caller":"traceutil/trace.go:171","msg":"trace[1012869975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:418; }","duration":"434.045331ms","start":"2026-06-12T22:58:47.756242Z","end":"2026-06-12T22:58:48.190287Z","steps":["trace[1012869975] 'agreement among raft nodes before linearized reading' (duration: 433.968469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:58:48.190334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:58:47.756221Z","time spent":"434.104753ms","remote":"127.0.0.1:51368","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-06-12T22:58:48.18948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.398892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T22:58:48.190614Z","caller":"traceutil/trace.go:171","msg":"trace[1147645722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:418; }","duration":"731.556611ms","start":"2026-06-12T22:58:47.459015Z","end":"2026-06-12T22:58:48.190572Z","steps":["trace[1147645722] 'agreement among raft nodes before linearized reading' (duration: 730.346701ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:58:48.190746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:58:47.458998Z","time spent":"731.730465ms","remote":"127.0.0.1:51086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-12T22:58:48.190525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.192367ms","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-06-12T22:58:48.191207Z","caller":"traceutil/trace.go:171","msg":"trace[1937254673] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:418; }","duration":"505.794102ms","start":"2026-06-12T22:58:47.685296Z","end":"2026-06-12T22:58:48.19109Z","steps":["trace[1937254673] 'agreement among raft nodes before linearized reading' (duration: 505.191937ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:58:48.191352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:58:47.685283Z","time spent":"506.021127ms","remote":"127.0.0.1:51368","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-06-12T22:58:49.42409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.472841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-12T22:58:49.424322Z","caller":"traceutil/trace.go:171","msg":"trace[1171810401] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"1.226285502s","start":"2026-06-12T22:58:48.198013Z","end":"2026-06-12T22:58:49.424298Z","steps":["trace[1171810401] 'process raft request' (duration: 773.861327ms)","trace[1171810401] 'compare' (duration: 451.256626ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:58:49.424399Z","caller":"traceutil/trace.go:171","msg":"trace[15440977] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"1.226289772s","start":"2026-06-12T22:58:48.198099Z","end":"2026-06-12T22:58:49.424389Z","steps":["trace[15440977] 'process raft request' (duration: 1.226133408s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:58:49.424438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:58:48.197993Z","time spent":"1.226402045s","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T22:58:49.424485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:58:48.198088Z","time spent":"1.226343213s","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:58:49.424362Z","caller":"traceutil/trace.go:171","msg":"trace[233129153] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"1.226252891s","start":"2026-06-12T22:58:48.198095Z","end":"2026-06-12T22:58:49.424347Z","steps":["trace[233129153] 'process raft request' (duration: 1.226080457s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:58:49.424678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:58:48.198086Z","time spent":"1.226488278s","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:58:49.424766Z","caller":"traceutil/trace.go:171","msg":"trace[1879216599] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"1.222633611s","start":"2026-06-12T22:58:48.202114Z","end":"2026-06-12T22:58:49.424747Z","steps":["trace[1879216599] 'process raft request' (duration: 1.22218814s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:58:49.424863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:58:48.202104Z","time spent":"1.222716513s","remote":"127.0.0.1:51280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T22:58:54.941671Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185750370153475,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T22:58:55.442467Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185750370153475,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T22:58:55.899909Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.607430415s","expected-duration":"1s"} {"level":"info","ts":"2026-06-12T22:58:55.90024Z","caller":"traceutil/trace.go:171","msg":"trace[700426088] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:483; }","duration":"1.459046427s","start":"2026-06-12T22:58:54.441167Z","end":"2026-06-12T22:58:55.900214Z","steps":["trace[700426088] 'read index received' (duration: 1.458828061s)","trace[700426088] 'applied index is now lower than readState.Index' (duration: 217.275µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:58:55.900284Z","caller":"traceutil/trace.go:171","msg":"trace[1984869951] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"1.607852676s","start":"2026-06-12T22:58:54.292404Z","end":"2026-06-12T22:58:55.900257Z","steps":["trace[1984869951] 'process raft request' (duration: 1.607678191s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:58:55.900387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:58:54.292392Z","time spent":"1.607930117s","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T22:58:55.900404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.45922862s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T22:58:55.900438Z","caller":"traceutil/trace.go:171","msg":"trace[1262790168] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:466; }","duration":"1.459282892s","start":"2026-06-12T22:58:54.441144Z","end":"2026-06-12T22:58:55.900427Z","steps":["trace[1262790168] 'agreement among raft nodes before linearized reading' (duration: 1.45920016s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:58:55.900474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:58:54.441059Z","time spent":"1.459406775s","remote":"127.0.0.1:51086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-12T22:58:55.900535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.486743ms","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-06-12T22:58:55.90056Z","caller":"traceutil/trace.go:171","msg":"trace[102363943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:466; }","duration":"409.530185ms","start":"2026-06-12T22:58:55.491022Z","end":"2026-06-12T22:58:55.900553Z","steps":["trace[102363943] 'agreement among raft nodes before linearized reading' (duration: 409.449033ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:58:55.900575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:58:55.491014Z","time spent":"409.558245ms","remote":"127.0.0.1:51368","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-06-12T22:58:55.900598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.628769ms","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-06-12T22:58:55.900698Z","caller":"traceutil/trace.go:171","msg":"trace[851788719] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:466; }","duration":"441.808404ms","start":"2026-06-12T22:58:55.458867Z","end":"2026-06-12T22:58:55.900675Z","steps":["trace[851788719] 'agreement among raft nodes before linearized reading' (duration: 441.602909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:58:55.900691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.815145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-12T22:58:55.900752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:58:55.458838Z","time spent":"441.903505ms","remote":"127.0.0.1:51368","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-06-12T22:58:55.900804Z","caller":"traceutil/trace.go:171","msg":"trace[1694251707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:466; }","duration":"410.959138ms","start":"2026-06-12T22:58:55.489816Z","end":"2026-06-12T22:58:55.900775Z","steps":["trace[1694251707] 'agreement among raft nodes before linearized reading' (duration: 410.782454ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:58:55.900853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:58:55.489805Z","time spent":"411.039161ms","remote":"127.0.0.1:51368","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-06-12T22:58:56.408829Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185750370153479,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T22:58:56.909523Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185750370153479,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T22:58:57.248654Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.344343525s","expected-duration":"1s"} {"level":"info","ts":"2026-06-12T22:58:57.256249Z","caller":"traceutil/trace.go:171","msg":"trace[321159728] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"1.352040806s","start":"2026-06-12T22:58:55.90419Z","end":"2026-06-12T22:58:57.256231Z","steps":["trace[321159728] 'process raft request' (duration: 1.344695004s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:58:57.256357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:58:55.904167Z","time spent":"1.352123907s","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:58:57.275037Z","caller":"traceutil/trace.go:171","msg":"trace[1004983216] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"1.370736525s","start":"2026-06-12T22:58:55.90427Z","end":"2026-06-12T22:58:57.275007Z","steps":["trace[1004983216] 'process raft request' (duration: 1.370443848s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:58:57.275205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:58:55.904261Z","time spent":"1.370849137s","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:58:57.275503Z","caller":"traceutil/trace.go:171","msg":"trace[1580261960] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"1.370609922s","start":"2026-06-12T22:58:55.904867Z","end":"2026-06-12T22:58:57.275476Z","steps":["trace[1580261960] 'process raft request' (duration: 1.369966047s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:58:57.275567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:58:55.904839Z","time spent":"1.370697363s","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T22:58:57.386389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.500219ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-12T22:58:57.386501Z","caller":"traceutil/trace.go:171","msg":"trace[449823286] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:484; }","duration":"1.477993124s","start":"2026-06-12T22:58:55.908475Z","end":"2026-06-12T22:58:57.386468Z","steps":["trace[449823286] 'read index received' (duration: 1.340421762s)","trace[449823286] 'applied index is now lower than readState.Index' (duration: 137.569851ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T22:58:57.386573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.478095676s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T22:58:57.386605Z","caller":"traceutil/trace.go:171","msg":"trace[857357393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:469; }","duration":"1.478175027s","start":"2026-06-12T22:58:55.90842Z","end":"2026-06-12T22:58:57.386595Z","steps":["trace[857357393] 'agreement among raft nodes before linearized reading' (duration: 1.478111306s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:58:57.386643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:58:55.908399Z","time spent":"1.478236138s","remote":"127.0.0.1:51086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-12T22:58:58.021216Z","caller":"traceutil/trace.go:171","msg":"trace[1649902088] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"113.581155ms","start":"2026-06-12T22:58:57.90759Z","end":"2026-06-12T22:58:58.021171Z","steps":["trace[1649902088] 'process raft request' (duration: 113.449042ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:06.552054Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185750370153604,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T22:59:07.052489Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185750370153604,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T22:59:07.553495Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185750370153604,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T22:59:07.894492Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.176550072s","expected-duration":"1s"} {"level":"info","ts":"2026-06-12T22:59:07.895076Z","caller":"traceutil/trace.go:171","msg":"trace[779548876] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"2.177200735s","start":"2026-06-12T22:59:05.717819Z","end":"2026-06-12T22:59:07.89502Z","steps":["trace[779548876] 'process raft request' (duration: 2.177001951s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:07.895248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:05.717794Z","time spent":"2.17736587s","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-12T22:59:07.987608Z","caller":"traceutil/trace.go:171","msg":"trace[1188351232] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"2.267513232s","start":"2026-06-12T22:59:05.720068Z","end":"2026-06-12T22:59:07.987581Z","steps":["trace[1188351232] 'process raft request' (duration: 2.265057147s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:07.988428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:05.720039Z","time spent":"2.268313379s","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-12T22:59:07.989305Z","caller":"traceutil/trace.go:171","msg":"trace[553022016] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"2.261219241s","start":"2026-06-12T22:59:05.726404Z","end":"2026-06-12T22:59:07.987623Z","steps":["trace[553022016] 'process raft request' (duration: 2.261010057s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:07.989395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:05.726392Z","time spent":"2.262948939s","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-12T22:59:07.989627Z","caller":"traceutil/trace.go:171","msg":"trace[715389642] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"2.269378073s","start":"2026-06-12T22:59:05.720096Z","end":"2026-06-12T22:59:07.989474Z","steps":["trace[715389642] 'process raft request' (duration: 2.267265287s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:07.989812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:05.720085Z","time spent":"2.269631568s","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-12T22:59:07.989869Z","caller":"traceutil/trace.go:171","msg":"trace[266744625] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"2.263206885s","start":"2026-06-12T22:59:05.726644Z","end":"2026-06-12T22:59:07.989851Z","steps":["trace[266744625] 'process raft request' (duration: 2.260809413s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:07.989934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:05.726639Z","time spent":"2.263266306s","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-12T22:59:07.989821Z","caller":"traceutil/trace.go:171","msg":"trace[856811915] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"2.263018801s","start":"2026-06-12T22:59:05.726777Z","end":"2026-06-12T22:59:07.989795Z","steps":["trace[856811915] 'process raft request' (duration: 2.260750121s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:07.990121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:05.726751Z","time spent":"2.263319347s","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-12T22:59:07.99182Z","caller":"traceutil/trace.go:171","msg":"trace[1995283613] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"2.261073667s","start":"2026-06-12T22:59:05.730409Z","end":"2026-06-12T22:59:07.991483Z","steps":["trace[1995283613] 'process raft request' (duration: 2.257705693s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:07.992159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:05.730402Z","time spent":"2.261544437s","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-12T22:59:07.992835Z","caller":"traceutil/trace.go:171","msg":"trace[1427091978] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"2.254105712s","start":"2026-06-12T22:59:05.738552Z","end":"2026-06-12T22:59:07.992658Z","steps":["trace[1427091978] 'process raft request' (duration: 2.250205756s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:07.9933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:05.738546Z","time spent":"2.254333227s","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-12T22:59:07.99474Z","caller":"traceutil/trace.go:171","msg":"trace[1999043029] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"2.254591382s","start":"2026-06-12T22:59:05.740121Z","end":"2026-06-12T22:59:07.994712Z","steps":["trace[1999043029] 'process raft request' (duration: 2.25084539s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:07.994868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:05.740115Z","time spent":"2.254703885s","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-12T22:59:07.996611Z","caller":"traceutil/trace.go:171","msg":"trace[1909704709] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"2.220569714s","start":"2026-06-12T22:59:05.774657Z","end":"2026-06-12T22:59:07.995227Z","steps":["trace[1909704709] 'process raft request' (duration: 2.220314408s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:07.996705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:05.774641Z","time spent":"2.222009486s","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-12T22:59:08.001048Z","caller":"traceutil/trace.go:171","msg":"trace[598886474] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"2.251207757s","start":"2026-06-12T22:59:05.743937Z","end":"2026-06-12T22:59:07.995145Z","steps":["trace[598886474] 'process raft request' (duration: 2.250690186s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:08.001134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:05.743928Z","time spent":"2.257164278s","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-12T22:59:08.001799Z","caller":"traceutil/trace.go:171","msg":"trace[1727239495] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"2.105477128s","start":"2026-06-12T22:59:05.896284Z","end":"2026-06-12T22:59:08.001761Z","steps":["trace[1727239495] 'process raft request' (duration: 2.104303062s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:08.001975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:05.896257Z","time spent":"2.105660353s","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-12T22:59:08.441537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000243194s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-12T22:59:08.44163Z","caller":"traceutil/trace.go:171","msg":"trace[943971223] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000379557s","start":"2026-06-12T22:59:06.441225Z","end":"2026-06-12T22:59:08.441605Z","steps":["trace[943971223] 'agreement among raft nodes before linearized reading' (duration: 2.000269985s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:08.441698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:06.44121Z","time spent":"2.000476469s","remote":"127.0.0.1:51086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/12 22:59:08 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-12T22:59:08.856207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.40812ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-12T22:59:08.856686Z","caller":"traceutil/trace.go:171","msg":"trace[1701245922] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:543; }","duration":"2.805110795s","start":"2026-06-12T22:59:06.051535Z","end":"2026-06-12T22:59:08.856646Z","steps":["trace[1701245922] 'read index received' (duration: 1.84311974s)","trace[1701245922] 'applied index is now lower than readState.Index' (duration: 961.989095ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:59:08.856767Z","caller":"traceutil/trace.go:171","msg":"trace[1072680860] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"954.900109ms","start":"2026-06-12T22:59:07.901858Z","end":"2026-06-12T22:59:08.856758Z","steps":["trace[1072680860] 'process raft request' (duration: 954.645233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:08.856827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:07.901831Z","time spent":"954.95618ms","remote":"127.0.0.1:51632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-12T22:59:08.857036Z","caller":"traceutil/trace.go:171","msg":"trace[1841093569] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"953.029017ms","start":"2026-06-12T22:59:07.903984Z","end":"2026-06-12T22:59:08.857013Z","steps":["trace[1841093569] 'process raft request' (duration: 952.68898ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:08.857081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.805533214s","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-06-12T22:59:08.857164Z","caller":"traceutil/trace.go:171","msg":"trace[1883769456] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"2.805754949s","start":"2026-06-12T22:59:06.051385Z","end":"2026-06-12T22:59:08.85714Z","steps":["trace[1883769456] 'agreement among raft nodes before linearized reading' (duration: 2.80535716s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:08.857281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:06.051361Z","time spent":"2.805909273s","remote":"127.0.0.1:51368","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":"info","ts":"2026-06-12T22:59:08.857266Z","caller":"traceutil/trace.go:171","msg":"trace[1449068440] transaction","detail":"{read_only:false; number_of_response:1; response_revision:537; }","duration":"952.893794ms","start":"2026-06-12T22:59:07.904349Z","end":"2026-06-12T22:59:08.857243Z","steps":["trace[1449068440] 'process raft request' (duration: 952.423244ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:08.857188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:07.903954Z","time spent":"953.15866ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T22:59:08.857413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:07.904312Z","time spent":"953.040498ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6936,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:59:08.857658Z","caller":"traceutil/trace.go:171","msg":"trace[1468806516] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"855.84277ms","start":"2026-06-12T22:59:08.001798Z","end":"2026-06-12T22:59:08.857641Z","steps":["trace[1468806516] 'process raft request' (duration: 855.798119ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:08.857718Z","caller":"traceutil/trace.go:171","msg":"trace[1712022545] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"856.703208ms","start":"2026-06-12T22:59:08.001003Z","end":"2026-06-12T22:59:08.857706Z","steps":["trace[1712022545] 'process raft request' (duration: 856.515194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:08.857768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:08.000986Z","time spent":"856.75789ms","remote":"127.0.0.1:51632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-12T22:59:08.857807Z","caller":"traceutil/trace.go:171","msg":"trace[846788572] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"856.423252ms","start":"2026-06-12T22:59:08.001371Z","end":"2026-06-12T22:59:08.857794Z","steps":["trace[846788572] 'process raft request' (duration: 856.185197ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:08.85776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:08.001793Z","time spent":"855.925412ms","remote":"127.0.0.1:51632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-12T22:59:08.85785Z","caller":"traceutil/trace.go:171","msg":"trace[181256640] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"857.310922ms","start":"2026-06-12T22:59:08.000525Z","end":"2026-06-12T22:59:08.857836Z","steps":["trace[181256640] 'process raft request' (duration: 856.906253ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:08.857882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:08.001363Z","time spent":"856.482903ms","remote":"127.0.0.1:51632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-12T22:59:08.857974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:08.000507Z","time spent":"857.394204ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:59:08.862772Z","caller":"traceutil/trace.go:171","msg":"trace[1857058583] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"854.172113ms","start":"2026-06-12T22:59:08.00858Z","end":"2026-06-12T22:59:08.862752Z","steps":["trace[1857058583] 'process raft request' (duration: 853.706522ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:08.862835Z","caller":"traceutil/trace.go:171","msg":"trace[457186965] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"850.953902ms","start":"2026-06-12T22:59:08.011869Z","end":"2026-06-12T22:59:08.862823Z","steps":["trace[457186965] 'process raft request' (duration: 850.663056ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:08.862914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:08.008572Z","time spent":"854.278985ms","remote":"127.0.0.1:51632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-12T22:59:08.862921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:08.011862Z","time spent":"851.006483ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:59:08.862954Z","caller":"traceutil/trace.go:171","msg":"trace[2090406834] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"851.366211ms","start":"2026-06-12T22:59:08.011579Z","end":"2026-06-12T22:59:08.862945Z","steps":["trace[2090406834] 'process raft request' (duration: 850.805458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:08.863236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:08.011549Z","time spent":"851.658638ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:59:08.863379Z","caller":"traceutil/trace.go:171","msg":"trace[234543595] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"848.373006ms","start":"2026-06-12T22:59:08.014996Z","end":"2026-06-12T22:59:08.863369Z","steps":["trace[234543595] 'process raft request' (duration: 847.64943ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:08.863438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:08.014988Z","time spent":"848.418687ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T22:59:08.863517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.555874586s","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-06-12T22:59:08.863561Z","caller":"traceutil/trace.go:171","msg":"trace[1050504035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:546; }","duration":"1.555953058s","start":"2026-06-12T22:59:07.307598Z","end":"2026-06-12T22:59:08.863551Z","steps":["trace[1050504035] 'agreement among raft nodes before linearized reading' (duration: 1.555825415s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:08.863591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:07.307575Z","time spent":"1.556009369s","remote":"127.0.0.1:51368","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-06-12T22:59:08.863594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.733009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T22:59:08.863666Z","caller":"traceutil/trace.go:171","msg":"trace[1002954629] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"805.747364ms","start":"2026-06-12T22:59:08.05791Z","end":"2026-06-12T22:59:08.863657Z","steps":["trace[1002954629] 'process raft request' (duration: 805.401737ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:08.863675Z","caller":"traceutil/trace.go:171","msg":"trace[1127918558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"421.864632ms","start":"2026-06-12T22:59:08.441791Z","end":"2026-06-12T22:59:08.863656Z","steps":["trace[1127918558] 'agreement among raft nodes before linearized reading' (duration: 421.718399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:08.863714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:08.441773Z","time spent":"421.933493ms","remote":"127.0.0.1:51090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-12T22:59:08.86371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:08.057882Z","time spent":"805.810625ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-12T22:59:08.86375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.546890998s","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-06-12T22:59:08.863771Z","caller":"traceutil/trace.go:171","msg":"trace[210921438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:546; }","duration":"1.54694319s","start":"2026-06-12T22:59:07.316821Z","end":"2026-06-12T22:59:08.863764Z","steps":["trace[210921438] 'agreement among raft nodes before linearized reading' (duration: 1.546873428s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:08.863794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:07.316802Z","time spent":"1.546988s","remote":"127.0.0.1:51368","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-06-12T22:59:08.86363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.544524087s","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-06-12T22:59:08.863858Z","caller":"traceutil/trace.go:171","msg":"trace[705691884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:546; }","duration":"1.544792543s","start":"2026-06-12T22:59:07.319058Z","end":"2026-06-12T22:59:08.863851Z","steps":["trace[705691884] 'agreement among raft nodes before linearized reading' (duration: 1.544492196s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:08.863885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:07.319044Z","time spent":"1.544834553s","remote":"127.0.0.1:51368","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":"info","ts":"2026-06-12T22:59:13.096646Z","caller":"traceutil/trace.go:171","msg":"trace[1772261266] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"152.88176ms","start":"2026-06-12T22:59:12.943735Z","end":"2026-06-12T22:59:13.096617Z","steps":["trace[1772261266] 'read index received' (duration: 151.96309ms)","trace[1772261266] 'applied index is now lower than readState.Index' (duration: 917.71µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:59:13.096668Z","caller":"traceutil/trace.go:171","msg":"trace[833777096] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"159.323257ms","start":"2026-06-12T22:59:12.937313Z","end":"2026-06-12T22:59:13.096636Z","steps":["trace[833777096] 'process raft request' (duration: 158.681963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:13.097435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.675027ms","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-06-12T22:59:13.097522Z","caller":"traceutil/trace.go:171","msg":"trace[1868388324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"153.809099ms","start":"2026-06-12T22:59:12.943692Z","end":"2026-06-12T22:59:13.097501Z","steps":["trace[1868388324] 'agreement among raft nodes before linearized reading' (duration: 153.300559ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:13.792412Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185750370153761,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T22:59:14.782984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.573787ms","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-06-12T22:59:14.783163Z","caller":"traceutil/trace.go:171","msg":"trace[581643771] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:620; }","duration":"680.781861ms","start":"2026-06-12T22:59:14.102353Z","end":"2026-06-12T22:59:14.783135Z","steps":["trace[581643771] 'range keys from in-memory index tree' (duration: 680.549887ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:14.783644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.7765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-12T22:59:14.783957Z","caller":"traceutil/trace.go:171","msg":"trace[662659240] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"1.185266876s","start":"2026-06-12T22:59:13.598668Z","end":"2026-06-12T22:59:14.783935Z","steps":["trace[662659240] 'process raft request' (duration: 1.185079012s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:14.784045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:13.598649Z","time spent":"1.185349409s","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:59:14.784064Z","caller":"traceutil/trace.go:171","msg":"trace[1336807054] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"1.611370802s","start":"2026-06-12T22:59:13.172661Z","end":"2026-06-12T22:59:14.784032Z","steps":["trace[1336807054] 'process raft request' (duration: 747.119432ms)","trace[1336807054] 'compare' (duration: 863.176688ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T22:59:14.784192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:13.172638Z","time spent":"1.611500285s","remote":"127.0.0.1:51452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-12T22:59:14.78442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.492835748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T22:59:14.785143Z","caller":"traceutil/trace.go:171","msg":"trace[284505803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"1.493601574s","start":"2026-06-12T22:59:13.291483Z","end":"2026-06-12T22:59:14.785084Z","steps":["trace[284505803] 'agreement among raft nodes before linearized reading' (duration: 1.492710026s)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:14.783873Z","caller":"traceutil/trace.go:171","msg":"trace[553307278] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:647; }","duration":"1.492304278s","start":"2026-06-12T22:59:13.29155Z","end":"2026-06-12T22:59:14.783854Z","steps":["trace[553307278] 'read index received' (duration: 628.246892ms)","trace[553307278] 'applied index is now lower than readState.Index' (duration: 864.056296ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T22:59:14.785209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:13.291463Z","time spent":"1.493730388s","remote":"127.0.0.1:51086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-12T22:59:14.785434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33164741s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T22:59:14.785509Z","caller":"traceutil/trace.go:171","msg":"trace[567767789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"1.331781843s","start":"2026-06-12T22:59:13.45371Z","end":"2026-06-12T22:59:14.785492Z","steps":["trace[567767789] 'agreement among raft nodes before linearized reading' (duration: 1.331662251s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:14.785577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:13.453682Z","time spent":"1.331886446s","remote":"127.0.0.1:51090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-12T22:59:16.490397Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185750370153781,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T22:59:16.990569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185750370153781,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T22:59:17.490768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185750370153781,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T22:59:17.992011Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185750370153781,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T22:59:18.09575Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.859351094s","expected-duration":"1s"} {"level":"info","ts":"2026-06-12T22:59:18.104631Z","caller":"traceutil/trace.go:171","msg":"trace[1290509307] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"2.869588204s","start":"2026-06-12T22:59:15.235018Z","end":"2026-06-12T22:59:18.104607Z","steps":["trace[1290509307] 'process raft request' (duration: 2.862917397s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:18.104763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:15.234979Z","time spent":"2.869719547s","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T22:59:18.441133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000156976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-12T22:59:18.441228Z","caller":"traceutil/trace.go:171","msg":"trace[764778551] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000296608s","start":"2026-06-12T22:59:16.440908Z","end":"2026-06-12T22:59:18.441205Z","steps":["trace[764778551] 'agreement among raft nodes before linearized reading' (duration: 2.000183066s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:18.441276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:16.44089Z","time spent":"2.000376061s","remote":"127.0.0.1:51086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/12 22:59:18 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-12T22:59:20.441896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000078482s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-12T22:59:20.441982Z","caller":"traceutil/trace.go:171","msg":"trace[1337986849] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000217344s","start":"2026-06-12T22:59:18.441745Z","end":"2026-06-12T22:59:20.441963Z","steps":["trace[1337986849] 'agreement among raft nodes before linearized reading' (duration: 2.000114451s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:20.442028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:18.441724Z","time spent":"2.000296146s","remote":"127.0.0.1:51090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/12 22:59:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-12T22:59:21.955092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998994239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-12T22:59:21.955181Z","caller":"traceutil/trace.go:171","msg":"trace[652246457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999151572s","start":"2026-06-12T22:59:16.95601Z","end":"2026-06-12T22:59:21.955161Z","steps":["trace[652246457] 'agreement among raft nodes before linearized reading' (duration: 4.99903904s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:21.95522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:16.955975Z","time spent":"4.999235723s","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-12T22:59:21.96274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999426263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-12T22:59:21.962823Z","caller":"traceutil/trace.go:171","msg":"trace[1050936415] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999639168s","start":"2026-06-12T22:59:16.963163Z","end":"2026-06-12T22:59:21.962802Z","steps":["trace[1050936415] 'agreement among raft nodes before linearized reading' (duration: 4.999449974s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:21.962962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:16.963153Z","time spent":"4.999799071s","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/06/12 22:59:21 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-12T22:59:22.450358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000583548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-12T22:59:22.450453Z","caller":"traceutil/trace.go:171","msg":"trace[361161474] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000733861s","start":"2026-06-12T22:59:20.449697Z","end":"2026-06-12T22:59:22.450431Z","steps":["trace[361161474] 'agreement among raft nodes before linearized reading' (duration: 2.000616758s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:22.450517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:20.44968Z","time spent":"2.000825932s","remote":"127.0.0.1:51086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-12T22:59:23.069658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.745939836s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-12T22:59:23.069968Z","caller":"traceutil/trace.go:171","msg":"trace[1373487756] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:656; }","duration":"7.080261213s","start":"2026-06-12T22:59:15.989648Z","end":"2026-06-12T22:59:23.069909Z","steps":["trace[1373487756] 'read index received' (duration: 2.106349062s)","trace[1373487756] 'applied index is now lower than readState.Index' (duration: 4.973910951s)"],"step_count":2} {"level":"warn","ts":"2026-06-12T22:59:23.070367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.080689622s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nxcl8\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-12T22:59:23.070458Z","caller":"traceutil/trace.go:171","msg":"trace[1471383160] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nxcl8; range_end:; response_count:1; response_revision:630; }","duration":"7.080821585s","start":"2026-06-12T22:59:15.989613Z","end":"2026-06-12T22:59:23.070435Z","steps":["trace[1471383160] 'agreement among raft nodes before linearized reading' (duration: 7.080552649s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:23.070498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:15.989594Z","time spent":"7.080896426s","remote":"127.0.0.1:51280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-nxcl8\" "} {"level":"info","ts":"2026-06-12T22:59:23.070469Z","caller":"traceutil/trace.go:171","msg":"trace[1204608154] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"4.907714217s","start":"2026-06-12T22:59:18.162716Z","end":"2026-06-12T22:59:23.07043Z","steps":["trace[1204608154] 'process raft request' (duration: 4.907085544s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:23.070762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:18.162671Z","time spent":"4.907997433s","remote":"127.0.0.1:51468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-12T22:59:23.570341Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185750370153787,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T22:59:24.070952Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185750370153787,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T22:59:24.450953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99997944s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-12T22:59:24.451055Z","caller":"traceutil/trace.go:171","msg":"trace[220247164] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000111163s","start":"2026-06-12T22:59:22.450907Z","end":"2026-06-12T22:59:24.451018Z","steps":["trace[220247164] 'agreement among raft nodes before linearized reading' (duration: 2.000007631s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:24.451105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:22.450883Z","time spent":"2.000212454s","remote":"127.0.0.1:51090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/12 22:59:24 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-12T22:59:24.571193Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185750370153787,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T22:59:24.61956Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.0735056s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-12T22:59:25.047387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.960775ms","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-06-12T22:59:25.047571Z","caller":"traceutil/trace.go:171","msg":"trace[52524572] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:631; }","duration":"945.153869ms","start":"2026-06-12T22:59:24.102392Z","end":"2026-06-12T22:59:25.047546Z","steps":["trace[52524572] 'range keys from in-memory index tree' (duration: 944.941155ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:25.047816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.004028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-12T22:59:25.047938Z","caller":"traceutil/trace.go:171","msg":"trace[1160520774] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"4.501974407s","start":"2026-06-12T22:59:20.545935Z","end":"2026-06-12T22:59:25.04791Z","steps":["trace[1160520774] 'process raft request' (duration: 4.073777375s)","trace[1160520774] 'compare' (duration: 427.652231ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T22:59:25.048024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:20.545912Z","time spent":"4.502067479s","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:59:25.076099Z","caller":"traceutil/trace.go:171","msg":"trace[1619663997] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:659; }","duration":"2.006074397s","start":"2026-06-12T22:59:23.069997Z","end":"2026-06-12T22:59:25.076072Z","steps":["trace[1619663997] 'read index received' (duration: 1.549726137s)","trace[1619663997] 'applied index is now lower than readState.Index' (duration: 456.34738ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:59:25.076337Z","caller":"traceutil/trace.go:171","msg":"trace[686647203] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.990484874s","start":"2026-06-12T22:59:23.085823Z","end":"2026-06-12T22:59:25.076308Z","steps":["trace[686647203] 'process raft request' (duration: 1.990105417s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:25.0765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.944920458s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-12T22:59:25.076499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.113810183s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-12T22:59:25.076371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.630841407s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18b877985acf2ccf\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-06-12T22:59:25.076585Z","caller":"traceutil/trace.go:171","msg":"trace[33920723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"8.113964296s","start":"2026-06-12T22:59:16.962599Z","end":"2026-06-12T22:59:25.076564Z","steps":["trace[33920723] 'agreement among raft nodes before linearized reading' (duration: 8.113766022s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:25.07664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:16.96258Z","time spent":"8.114050729s","remote":"127.0.0.1:51368","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-06-12T22:59:25.076722Z","caller":"traceutil/trace.go:171","msg":"trace[534730162] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18b877985acf2ccf; range_end:; response_count:1; response_revision:633; }","duration":"4.631147933s","start":"2026-06-12T22:59:20.445463Z","end":"2026-06-12T22:59:25.076611Z","steps":["trace[534730162] 'agreement among raft nodes before linearized reading' (duration: 4.630734895s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:25.076798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:20.445441Z","time spent":"4.631339337s","remote":"127.0.0.1:51146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":796,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18b877985acf2ccf\" "} {"level":"info","ts":"2026-06-12T22:59:25.076566Z","caller":"traceutil/trace.go:171","msg":"trace[1622294522] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:633; }","duration":"3.94500476s","start":"2026-06-12T22:59:21.131536Z","end":"2026-06-12T22:59:25.076541Z","steps":["trace[1622294522] 'agreement among raft nodes before linearized reading' (duration: 3.944613782s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:25.076964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:21.131521Z","time spent":"3.945424058s","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.177\" "} {"level":"warn","ts":"2026-06-12T22:59:25.07732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:23.085786Z","time spent":"1.990705599s","remote":"127.0.0.1:51280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T22:59:25.079079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.920088493s","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-06-12T22:59:25.079151Z","caller":"traceutil/trace.go:171","msg":"trace[638537528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"7.920198264s","start":"2026-06-12T22:59:17.158935Z","end":"2026-06-12T22:59:25.079134Z","steps":["trace[638537528] 'agreement among raft nodes before linearized reading' (duration: 7.920023811s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:25.079187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:17.158915Z","time spent":"7.920262776s","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-12T22:59:25.230368Z","caller":"traceutil/trace.go:171","msg":"trace[278200261] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"154.177048ms","start":"2026-06-12T22:59:25.076171Z","end":"2026-06-12T22:59:25.230348Z","steps":["trace[278200261] 'read index received' (duration: 154.106457ms)","trace[278200261] 'applied index is now lower than readState.Index' (duration: 69.531µs)"],"step_count":2} {"level":"warn","ts":"2026-06-12T22:59:25.230478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.724961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T22:59:25.230533Z","caller":"traceutil/trace.go:171","msg":"trace[536323426] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"773.825434ms","start":"2026-06-12T22:59:24.456694Z","end":"2026-06-12T22:59:25.230519Z","steps":["trace[536323426] 'agreement among raft nodes before linearized reading' (duration: 773.705581ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:25.230539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.146374967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-12T22:59:25.230565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:24.456676Z","time spent":"773.883855ms","remote":"127.0.0.1:51086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-12T22:59:25.230602Z","caller":"traceutil/trace.go:171","msg":"trace[1946680721] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:634; }","duration":"2.146478629s","start":"2026-06-12T22:59:23.084097Z","end":"2026-06-12T22:59:25.230576Z","steps":["trace[1946680721] 'agreement among raft nodes before linearized reading' (duration: 2.146379967s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:25.230642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:23.084075Z","time spent":"2.14656039s","remote":"127.0.0.1:51478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" "} {"level":"warn","ts":"2026-06-12T22:59:25.230783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.93895582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-12T22:59:25.230804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.272890478s","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-06-12T22:59:25.230829Z","caller":"traceutil/trace.go:171","msg":"trace[134307776] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"1.27295754s","start":"2026-06-12T22:59:23.957866Z","end":"2026-06-12T22:59:25.230823Z","steps":["trace[134307776] 'agreement among raft nodes before linearized reading' (duration: 1.272865388s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:25.230847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:23.957842Z","time spent":"1.273001631s","remote":"127.0.0.1:51368","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-06-12T22:59:25.230838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266680735s","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-06-12T22:59:25.230838Z","caller":"traceutil/trace.go:171","msg":"trace[523384811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"1.939033451s","start":"2026-06-12T22:59:23.291783Z","end":"2026-06-12T22:59:25.230817Z","steps":["trace[523384811] 'agreement among raft nodes before linearized reading' (duration: 1.93898369s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:25.230909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:23.291757Z","time spent":"1.939146203s","remote":"127.0.0.1:51086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-12T22:59:25.230877Z","caller":"traceutil/trace.go:171","msg":"trace[1270752472] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"321.600828ms","start":"2026-06-12T22:59:24.909238Z","end":"2026-06-12T22:59:25.230839Z","steps":["trace[1270752472] 'process raft request' (duration: 321.003786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:25.231037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:24.909212Z","time spent":"321.750452ms","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:59:25.230901Z","caller":"traceutil/trace.go:171","msg":"trace[2044467984] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"1.266754196s","start":"2026-06-12T22:59:23.96413Z","end":"2026-06-12T22:59:25.230884Z","steps":["trace[2044467984] 'agreement among raft nodes before linearized reading' (duration: 1.266641654s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:25.231107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:23.964123Z","time spent":"1.26696761s","remote":"127.0.0.1:51368","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-06-12T22:59:25.258061Z","caller":"traceutil/trace.go:171","msg":"trace[1471564555] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"174.47597ms","start":"2026-06-12T22:59:25.083563Z","end":"2026-06-12T22:59:25.258039Z","steps":["trace[1471564555] 'process raft request' (duration: 174.263336ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:25.25802Z","caller":"traceutil/trace.go:171","msg":"trace[541978081] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"176.743925ms","start":"2026-06-12T22:59:25.081244Z","end":"2026-06-12T22:59:25.257988Z","steps":["trace[541978081] 'process raft request' (duration: 176.538891ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:25.258321Z","caller":"traceutil/trace.go:171","msg":"trace[305779752] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"178.400328ms","start":"2026-06-12T22:59:25.0799Z","end":"2026-06-12T22:59:25.258301Z","steps":["trace[305779752] 'process raft request' (duration: 177.789996ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:26.378972Z","caller":"traceutil/trace.go:171","msg":"trace[1044980469] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"355.983833ms","start":"2026-06-12T22:59:26.022963Z","end":"2026-06-12T22:59:26.378947Z","steps":["trace[1044980469] 'process raft request' (duration: 355.772779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:26.379137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:26.022945Z","time spent":"356.118886ms","remote":"127.0.0.1:51146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":765,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-12T22:59:26.620301Z","caller":"traceutil/trace.go:171","msg":"trace[919157978] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"585.038003ms","start":"2026-06-12T22:59:26.03523Z","end":"2026-06-12T22:59:26.620268Z","steps":["trace[919157978] 'process raft request' (duration: 521.423488ms)","trace[919157978] 'compare' (duration: 63.254248ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T22:59:26.620451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:26.035203Z","time spent":"585.172275ms","remote":"127.0.0.1:51280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17232,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:59:26.621713Z","caller":"traceutil/trace.go:171","msg":"trace[326288451] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:673; }","duration":"181.884638ms","start":"2026-06-12T22:59:26.439813Z","end":"2026-06-12T22:59:26.621698Z","steps":["trace[326288451] 'read index received' (duration: 117.417916ms)","trace[326288451] 'applied index is now lower than readState.Index' (duration: 64.464862ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:59:26.622373Z","caller":"traceutil/trace.go:171","msg":"trace[13807409] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"405.752946ms","start":"2026-06-12T22:59:26.21593Z","end":"2026-06-12T22:59:26.621683Z","steps":["trace[13807409] 'process raft request' (duration: 404.327548ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:26.623182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:26.215907Z","time spent":"407.219144ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036269,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T22:59:27.200626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.295869ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-06-12T22:59:27.200891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.050186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T22:59:27.200994Z","caller":"traceutil/trace.go:171","msg":"trace[38208403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"761.159168ms","start":"2026-06-12T22:59:26.439807Z","end":"2026-06-12T22:59:27.200967Z","steps":["trace[38208403] 'agreement among raft nodes before linearized reading' (duration: 181.94916ms)","trace[38208403] 'range keys from in-memory index tree' (duration: 579.076285ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:59:27.201412Z","caller":"traceutil/trace.go:171","msg":"trace[1585622788] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"523.809004ms","start":"2026-06-12T22:59:26.677569Z","end":"2026-06-12T22:59:27.201378Z","steps":["trace[1585622788] 'process raft request' (duration: 523.171642ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:27.201626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:26.439764Z","time spent":"761.724548ms","remote":"127.0.0.1:51086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-12T22:59:27.201699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:26.677543Z","time spent":"524.09682ms","remote":"127.0.0.1:51490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-12T22:59:27.922244Z","caller":"traceutil/trace.go:171","msg":"trace[1900511027] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"203.001274ms","start":"2026-06-12T22:59:27.719202Z","end":"2026-06-12T22:59:27.922204Z","steps":["trace[1900511027] 'process raft request' (duration: 202.431673ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:28.62776Z","caller":"traceutil/trace.go:171","msg":"trace[547393209] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"185.665265ms","start":"2026-06-12T22:59:28.442061Z","end":"2026-06-12T22:59:28.627727Z","steps":["trace[547393209] 'read index received' (duration: 183.209767ms)","trace[547393209] 'applied index is now lower than readState.Index' (duration: 2.454178ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T22:59:28.627905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.832859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T22:59:28.627951Z","caller":"traceutil/trace.go:171","msg":"trace[2082170498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"185.92952ms","start":"2026-06-12T22:59:28.442004Z","end":"2026-06-12T22:59:28.627934Z","steps":["trace[2082170498] 'agreement among raft nodes before linearized reading' (duration: 185.843178ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:28.627818Z","caller":"traceutil/trace.go:171","msg":"trace[247377632] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"189.820446ms","start":"2026-06-12T22:59:28.437976Z","end":"2026-06-12T22:59:28.627796Z","steps":["trace[247377632] 'process raft request' (duration: 187.93542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:29.748374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.43465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-12T22:59:29.748698Z","caller":"traceutil/trace.go:171","msg":"trace[1542738033] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.010237435s","start":"2026-06-12T22:59:28.738428Z","end":"2026-06-12T22:59:29.748666Z","steps":["trace[1542738033] 'process raft request' (duration: 626.384207ms)","trace[1542738033] 'compare' (duration: 383.302467ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T22:59:29.748812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:28.73842Z","time spent":"1.010342247s","remote":"127.0.0.1:51328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:59:29.748929Z","caller":"traceutil/trace.go:171","msg":"trace[335387845] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"1.010331826s","start":"2026-06-12T22:59:28.738564Z","end":"2026-06-12T22:59:29.748896Z","steps":["trace[335387845] 'process raft request' (duration: 1.009929029s)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:29.749011Z","caller":"traceutil/trace.go:171","msg":"trace[868187902] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"1.009566302s","start":"2026-06-12T22:59:28.739417Z","end":"2026-06-12T22:59:29.748984Z","steps":["trace[868187902] 'process raft request' (duration: 1.009170784s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:29.749077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:28.738545Z","time spent":"1.010449209s","remote":"127.0.0.1:51146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-12T22:59:29.749193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:28.739395Z","time spent":"1.009709584s","remote":"127.0.0.1:51280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:59:29.749214Z","caller":"traceutil/trace.go:171","msg":"trace[1617499207] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:688; }","duration":"722.308954ms","start":"2026-06-12T22:59:29.026887Z","end":"2026-06-12T22:59:29.749196Z","steps":["trace[1617499207] 'read index received' (duration: 338.101379ms)","trace[1617499207] 'applied index is now lower than readState.Index' (duration: 384.206195ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:59:29.749243Z","caller":"traceutil/trace.go:171","msg":"trace[1413657455] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"967.226905ms","start":"2026-06-12T22:59:28.781996Z","end":"2026-06-12T22:59:29.749223Z","steps":["trace[1413657455] 'process raft request' (duration: 966.898168ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:29.749333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:28.781971Z","time spent":"967.322347ms","remote":"127.0.0.1:51146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-12T22:59:29.74959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.298711ms","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-06-12T22:59:29.749698Z","caller":"traceutil/trace.go:171","msg":"trace[1932245164] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:662; }","duration":"412.482304ms","start":"2026-06-12T22:59:29.337161Z","end":"2026-06-12T22:59:29.749643Z","steps":["trace[1932245164] 'agreement among raft nodes before linearized reading' (duration: 412.240949ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:29.801348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.820489ms","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-06-12T22:59:29.801418Z","caller":"traceutil/trace.go:171","msg":"trace[1089887863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:662; }","duration":"414.119557ms","start":"2026-06-12T22:59:29.387282Z","end":"2026-06-12T22:59:29.801402Z","steps":["trace[1089887863] 'agreement among raft nodes before linearized reading' (duration: 363.692667ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:29.801415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.82715ms","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-06-12T22:59:29.801441Z","caller":"traceutil/trace.go:171","msg":"trace[345591066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:662; }","duration":"414.572615ms","start":"2026-06-12T22:59:29.386861Z","end":"2026-06-12T22:59:29.801434Z","steps":["trace[345591066] 'agreement among raft nodes before linearized reading' (duration: 362.750298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:29.801466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:29.386842Z","time spent":"414.618176ms","remote":"127.0.0.1:51368","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-06-12T22:59:29.801443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:29.387272Z","time spent":"414.164447ms","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-12T22:59:29.801987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:29.337141Z","time spent":"412.620407ms","remote":"127.0.0.1:51368","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-06-12T22:59:29.801346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.588845ms","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-06-12T22:59:29.802792Z","caller":"traceutil/trace.go:171","msg":"trace[2137718038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:662; }","duration":"415.645835ms","start":"2026-06-12T22:59:29.387137Z","end":"2026-06-12T22:59:29.802783Z","steps":["trace[2137718038] 'agreement among raft nodes before linearized reading' (duration: 362.412331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:29.802824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:29.387121Z","time spent":"415.693497ms","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-12T22:59:29.801346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.582629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nxcl8\" ","response":"range_response_count:1 size:17247"} {"level":"info","ts":"2026-06-12T22:59:29.802931Z","caller":"traceutil/trace.go:171","msg":"trace[116016926] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nxcl8; range_end:; response_count:1; response_revision:662; }","duration":"776.039518ms","start":"2026-06-12T22:59:29.026828Z","end":"2026-06-12T22:59:29.802867Z","steps":["trace[116016926] 'agreement among raft nodes before linearized reading' (duration: 722.491668ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:29.803082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:29.026812Z","time spent":"776.211492ms","remote":"127.0.0.1:51280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17269,"request content":"key:\"/registry/pods/kube-system/cilium-nxcl8\" "} {"level":"info","ts":"2026-06-12T22:59:30.21218Z","caller":"traceutil/trace.go:171","msg":"trace[183977703] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"404.878197ms","start":"2026-06-12T22:59:29.80728Z","end":"2026-06-12T22:59:30.212158Z","steps":["trace[183977703] 'process raft request' (duration: 404.737005ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:30.212665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:29.807252Z","time spent":"405.321835ms","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:59:30.823304Z","caller":"traceutil/trace.go:171","msg":"trace[700437708] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"1.015917611s","start":"2026-06-12T22:59:29.80735Z","end":"2026-06-12T22:59:30.823267Z","steps":["trace[700437708] 'process raft request' (duration: 1.015696417s)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:30.823562Z","caller":"traceutil/trace.go:171","msg":"trace[1468186678] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:693; }","duration":"382.367262ms","start":"2026-06-12T22:59:30.441177Z","end":"2026-06-12T22:59:30.823544Z","steps":["trace[1468186678] 'read index received' (duration: 381.802301ms)","trace[1468186678] 'applied index is now lower than readState.Index' (duration: 564.381µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:59:30.823641Z","caller":"traceutil/trace.go:171","msg":"trace[1103212391] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"1.007753742s","start":"2026-06-12T22:59:29.815875Z","end":"2026-06-12T22:59:30.823629Z","steps":["trace[1103212391] 'process raft request' (duration: 1.007579868s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:30.823658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.468505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T22:59:30.823704Z","caller":"traceutil/trace.go:171","msg":"trace[960233636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"382.578286ms","start":"2026-06-12T22:59:30.441116Z","end":"2026-06-12T22:59:30.823694Z","steps":["trace[960233636] 'agreement among raft nodes before linearized reading' (duration: 382.490954ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:30.823746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:30.441092Z","time spent":"382.645038ms","remote":"127.0.0.1:51086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-12T22:59:30.823767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:29.815865Z","time spent":"1.007829673s","remote":"127.0.0.1:51280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17416,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T22:59:30.823588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:29.807329Z","time spent":"1.016141775s","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:59:30.823303Z","caller":"traceutil/trace.go:171","msg":"trace[1570095142] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.015864079s","start":"2026-06-12T22:59:29.807406Z","end":"2026-06-12T22:59:30.82327Z","steps":["trace[1570095142] 'process raft request' (duration: 1.015791717s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:30.824126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:29.807401Z","time spent":"1.016665965s","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:59:30.823745Z","caller":"traceutil/trace.go:171","msg":"trace[1453856699] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"1.01587321s","start":"2026-06-12T22:59:29.807813Z","end":"2026-06-12T22:59:30.823686Z","steps":["trace[1453856699] 'process raft request' (duration: 1.015423761s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:30.825451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:29.807806Z","time spent":"1.01745986s","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:59:31.295632Z","caller":"traceutil/trace.go:171","msg":"trace[901470663] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"248.98335ms","start":"2026-06-12T22:59:31.046618Z","end":"2026-06-12T22:59:31.295601Z","steps":["trace[901470663] 'process raft request' (duration: 248.801256ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:31.296935Z","caller":"traceutil/trace.go:171","msg":"trace[2145186169] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:698; }","duration":"163.828002ms","start":"2026-06-12T22:59:31.133082Z","end":"2026-06-12T22:59:31.296909Z","steps":["trace[2145186169] 'read index received' (duration: 162.341303ms)","trace[2145186169] 'applied index is now lower than readState.Index' (duration: 1.485559ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:59:31.297073Z","caller":"traceutil/trace.go:171","msg":"trace[1176303373] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"207.113755ms","start":"2026-06-12T22:59:31.089937Z","end":"2026-06-12T22:59:31.297051Z","steps":["trace[1176303373] 'process raft request' (duration: 206.86142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:31.297222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.117847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-12T22:59:31.297287Z","caller":"traceutil/trace.go:171","msg":"trace[1862228923] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:669; }","duration":"164.26103ms","start":"2026-06-12T22:59:31.133009Z","end":"2026-06-12T22:59:31.29727Z","steps":["trace[1862228923] 'agreement among raft nodes before linearized reading' (duration: 164.020485ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:32.539645Z","caller":"traceutil/trace.go:171","msg":"trace[1785816020] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"488.243976ms","start":"2026-06-12T22:59:32.051381Z","end":"2026-06-12T22:59:32.539624Z","steps":["trace[1785816020] 'read index received' (duration: 488.099034ms)","trace[1785816020] 'applied index is now lower than readState.Index' (duration: 143.792µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:59:32.539694Z","caller":"traceutil/trace.go:171","msg":"trace[74265943] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"713.056547ms","start":"2026-06-12T22:59:31.826602Z","end":"2026-06-12T22:59:32.539658Z","steps":["trace[74265943] 'process raft request' (duration: 712.872964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:32.539824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.41225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-12T22:59:32.539869Z","caller":"traceutil/trace.go:171","msg":"trace[310711091] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:672; }","duration":"488.507352ms","start":"2026-06-12T22:59:32.051352Z","end":"2026-06-12T22:59:32.539859Z","steps":["trace[310711091] 'agreement among raft nodes before linearized reading' (duration: 488.38427ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:32.539918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:32.051337Z","time spent":"488.564883ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":20,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-06-12T22:59:32.53999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.920445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nxcl8\" ","response":"range_response_count:1 size:17524"} {"level":"warn","ts":"2026-06-12T22:59:32.539824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:31.826572Z","time spent":"713.18777ms","remote":"127.0.0.1:51146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-12T22:59:32.540139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.276788ms","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-06-12T22:59:32.540169Z","caller":"traceutil/trace.go:171","msg":"trace[1106188637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:672; }","duration":"323.34204ms","start":"2026-06-12T22:59:32.21682Z","end":"2026-06-12T22:59:32.540162Z","steps":["trace[1106188637] 'agreement among raft nodes before linearized reading' (duration: 323.265818ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:32.540064Z","caller":"traceutil/trace.go:171","msg":"trace[20937283] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nxcl8; range_end:; response_count:1; response_revision:672; }","duration":"483.039038ms","start":"2026-06-12T22:59:32.057008Z","end":"2026-06-12T22:59:32.540047Z","steps":["trace[20937283] 'agreement among raft nodes before linearized reading' (duration: 482.804783ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:32.540203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:32.216788Z","time spent":"323.406991ms","remote":"127.0.0.1:51368","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-06-12T22:59:32.540244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:32.056991Z","time spent":"483.219151ms","remote":"127.0.0.1:51280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17546,"request content":"key:\"/registry/pods/kube-system/cilium-nxcl8\" "} {"level":"warn","ts":"2026-06-12T22:59:33.169716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.788358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-12T22:59:33.169885Z","caller":"traceutil/trace.go:171","msg":"trace[2051333863] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"337.010926ms","start":"2026-06-12T22:59:32.832856Z","end":"2026-06-12T22:59:33.169867Z","steps":["trace[2051333863] 'process raft request' (duration: 336.948425ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:33.169982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:32.832844Z","time spent":"337.078897ms","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:59:33.169961Z","caller":"traceutil/trace.go:171","msg":"trace[1242678112] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"337.346192ms","start":"2026-06-12T22:59:32.832577Z","end":"2026-06-12T22:59:33.169923Z","steps":["trace[1242678112] 'process raft request' (duration: 147.999913ms)","trace[1242678112] 'compare' (duration: 188.654856ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T22:59:33.170159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:32.83256Z","time spent":"337.495006ms","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:59:33.172225Z","caller":"traceutil/trace.go:171","msg":"trace[188345336] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"131.162969ms","start":"2026-06-12T22:59:33.041042Z","end":"2026-06-12T22:59:33.172205Z","steps":["trace[188345336] 'read index received' (duration: 131.020136ms)","trace[188345336] 'applied index is now lower than readState.Index' (duration: 142.103µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:59:33.172329Z","caller":"traceutil/trace.go:171","msg":"trace[2112174649] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"151.244252ms","start":"2026-06-12T22:59:33.021075Z","end":"2026-06-12T22:59:33.172319Z","steps":["trace[2112174649] 'process raft request' (duration: 151.044928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:33.172608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.538126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-12T22:59:33.172626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.219922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nxcl8\" ","response":"range_response_count:1 size:17708"} {"level":"info","ts":"2026-06-12T22:59:33.172667Z","caller":"traceutil/trace.go:171","msg":"trace[1697509076] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:678; }","duration":"131.616267ms","start":"2026-06-12T22:59:33.041037Z","end":"2026-06-12T22:59:33.172653Z","steps":["trace[1697509076] 'agreement among raft nodes before linearized reading' (duration: 131.457674ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:33.172687Z","caller":"traceutil/trace.go:171","msg":"trace[2020157399] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nxcl8; range_end:; response_count:1; response_revision:678; }","duration":"108.315824ms","start":"2026-06-12T22:59:33.064353Z","end":"2026-06-12T22:59:33.172669Z","steps":["trace[2020157399] 'agreement among raft nodes before linearized reading' (duration: 108.074999ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:43.193563Z","caller":"traceutil/trace.go:171","msg":"trace[884951780] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"118.190832ms","start":"2026-06-12T22:59:43.075335Z","end":"2026-06-12T22:59:43.193526Z","steps":["trace[884951780] 'read index received' (duration: 118.045579ms)","trace[884951780] 'applied index is now lower than readState.Index' (duration: 143.883µs)"],"step_count":2} {"level":"warn","ts":"2026-06-12T22:59:43.193755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.389896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-12T22:59:43.193718Z","caller":"traceutil/trace.go:171","msg":"trace[2132710842] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"219.64307ms","start":"2026-06-12T22:59:42.973985Z","end":"2026-06-12T22:59:43.193628Z","steps":["trace[2132710842] 'process raft request' (duration: 219.314634ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:43.193806Z","caller":"traceutil/trace.go:171","msg":"trace[483310067] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:789; }","duration":"118.623689ms","start":"2026-06-12T22:59:43.075171Z","end":"2026-06-12T22:59:43.193795Z","steps":["trace[483310067] 'agreement among raft nodes before linearized reading' (duration: 118.498617ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:44.145017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.695263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-12T22:59:44.145105Z","caller":"traceutil/trace.go:171","msg":"trace[1724471533] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"473.495908ms","start":"2026-06-12T22:59:43.671594Z","end":"2026-06-12T22:59:44.145089Z","steps":["trace[1724471533] 'process raft request' (duration: 300.660883ms)","trace[1724471533] 'compare' (duration: 172.55945ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T22:59:44.145156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:43.671573Z","time spent":"473.55393ms","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:59:45.435456Z","caller":"traceutil/trace.go:171","msg":"trace[588016603] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"138.747054ms","start":"2026-06-12T22:59:45.296671Z","end":"2026-06-12T22:59:45.435418Z","steps":["trace[588016603] 'read index received' (duration: 138.567521ms)","trace[588016603] 'applied index is now lower than readState.Index' (duration: 178.843µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:59:45.43553Z","caller":"traceutil/trace.go:171","msg":"trace[1469225230] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"232.873714ms","start":"2026-06-12T22:59:45.20263Z","end":"2026-06-12T22:59:45.435504Z","steps":["trace[1469225230] 'process raft request' (duration: 232.620309ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:45.435767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.071721ms","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-06-12T22:59:45.435852Z","caller":"traceutil/trace.go:171","msg":"trace[117696230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"139.211242ms","start":"2026-06-12T22:59:45.296622Z","end":"2026-06-12T22:59:45.435833Z","steps":["trace[117696230] 'agreement among raft nodes before linearized reading' (duration: 138.961178ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:45.435997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.98049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-xqqj8\" ","response":"range_response_count:1 size:4741"} {"level":"info","ts":"2026-06-12T22:59:45.436081Z","caller":"traceutil/trace.go:171","msg":"trace[1062331953] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-xqqj8; range_end:; response_count:1; response_revision:796; }","duration":"123.118263ms","start":"2026-06-12T22:59:45.312947Z","end":"2026-06-12T22:59:45.436066Z","steps":["trace[1062331953] 'agreement among raft nodes before linearized reading' (duration: 122.651424ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:45.576603Z","caller":"traceutil/trace.go:171","msg":"trace[352075467] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"134.243823ms","start":"2026-06-12T22:59:45.44233Z","end":"2026-06-12T22:59:45.576574Z","steps":["trace[352075467] 'process raft request' (duration: 117.738645ms)","trace[352075467] 'compare' (duration: 16.329875ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:59:45.576847Z","caller":"traceutil/trace.go:171","msg":"trace[493317613] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:831; }","duration":"133.809125ms","start":"2026-06-12T22:59:45.443006Z","end":"2026-06-12T22:59:45.576816Z","steps":["trace[493317613] 'read index received' (duration: 48.618208ms)","trace[493317613] 'applied index is now lower than readState.Index' (duration: 85.189447ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:59:45.576917Z","caller":"traceutil/trace.go:171","msg":"trace[1940830639] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"130.877342ms","start":"2026-06-12T22:59:45.44601Z","end":"2026-06-12T22:59:45.576887Z","steps":["trace[1940830639] 'process raft request' (duration: 130.507806ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:45.577271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.239033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-12T22:59:45.577311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.359276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T22:59:45.577362Z","caller":"traceutil/trace.go:171","msg":"trace[2106651360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"134.371216ms","start":"2026-06-12T22:59:45.442967Z","end":"2026-06-12T22:59:45.577338Z","steps":["trace[2106651360] 'agreement among raft nodes before linearized reading' (duration: 134.098361ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:45.578147Z","caller":"traceutil/trace.go:171","msg":"trace[465140428] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:799; }","duration":"128.459819ms","start":"2026-06-12T22:59:45.448899Z","end":"2026-06-12T22:59:45.577359Z","steps":["trace[465140428] 'agreement among raft nodes before linearized reading' (duration: 128.338537ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:45.826126Z","caller":"traceutil/trace.go:171","msg":"trace[83379131] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"240.714624ms","start":"2026-06-12T22:59:45.585386Z","end":"2026-06-12T22:59:45.8261Z","steps":["trace[83379131] 'process raft request' (duration: 240.416629ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:45.82662Z","caller":"traceutil/trace.go:171","msg":"trace[490826033] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"236.858285ms","start":"2026-06-12T22:59:45.589726Z","end":"2026-06-12T22:59:45.826585Z","steps":["trace[490826033] 'process raft request' (duration: 236.296295ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:45.826769Z","caller":"traceutil/trace.go:171","msg":"trace[460502458] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"204.509261ms","start":"2026-06-12T22:59:45.622238Z","end":"2026-06-12T22:59:45.826748Z","steps":["trace[460502458] 'process raft request' (duration: 204.185815ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:46.118022Z","caller":"traceutil/trace.go:171","msg":"trace[1809546343] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"138.42665ms","start":"2026-06-12T22:59:45.979567Z","end":"2026-06-12T22:59:46.117993Z","steps":["trace[1809546343] 'process raft request' (duration: 77.677258ms)","trace[1809546343] 'compare' (duration: 60.568609ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T22:59:47.24734Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185750370154219,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-12T22:59:47.623847Z","caller":"traceutil/trace.go:171","msg":"trace[1970538382] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:844; }","duration":"877.522957ms","start":"2026-06-12T22:59:46.746302Z","end":"2026-06-12T22:59:47.623825Z","steps":["trace[1970538382] 'read index received' (duration: 788.004315ms)","trace[1970538382] 'applied index is now lower than readState.Index' (duration: 89.515192ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T22:59:47.624047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.787542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-12T22:59:47.624051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.397032ms","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-06-12T22:59:47.6241Z","caller":"traceutil/trace.go:171","msg":"trace[153710664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"182.865373ms","start":"2026-06-12T22:59:47.441217Z","end":"2026-06-12T22:59:47.624082Z","steps":["trace[153710664] 'agreement among raft nodes before linearized reading' (duration: 182.780461ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:47.624114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.905569ms","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-06-12T22:59:47.624167Z","caller":"traceutil/trace.go:171","msg":"trace[165925693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:809; }","duration":"127.973271ms","start":"2026-06-12T22:59:47.496171Z","end":"2026-06-12T22:59:47.624144Z","steps":["trace[165925693] 'agreement among raft nodes before linearized reading' (duration: 127.833848ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:47.624111Z","caller":"traceutil/trace.go:171","msg":"trace[1535303607] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:809; }","duration":"512.497974ms","start":"2026-06-12T22:59:47.111598Z","end":"2026-06-12T22:59:47.624096Z","steps":["trace[1535303607] 'agreement among raft nodes before linearized reading' (duration: 512.392602ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:47.624354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.038866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-12T22:59:47.624403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:47.111583Z","time spent":"512.798409ms","remote":"127.0.0.1:51530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-06-12T22:59:47.624435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.478064ms","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-06-12T22:59:47.624643Z","caller":"traceutil/trace.go:171","msg":"trace[353872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:809; }","duration":"183.77404ms","start":"2026-06-12T22:59:47.440851Z","end":"2026-06-12T22:59:47.624625Z","steps":["trace[353872] 'agreement among raft nodes before linearized reading' (duration: 183.21718ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:47.62449Z","caller":"traceutil/trace.go:171","msg":"trace[550388128] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:809; }","duration":"878.166388ms","start":"2026-06-12T22:59:46.746273Z","end":"2026-06-12T22:59:47.624439Z","steps":["trace[550388128] 'agreement among raft nodes before linearized reading' (duration: 877.69062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:47.624755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:46.746258Z","time spent":"878.470583ms","remote":"127.0.0.1:51368","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-06-12T22:59:47.84058Z","caller":"traceutil/trace.go:171","msg":"trace[873939363] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"211.582147ms","start":"2026-06-12T22:59:47.62897Z","end":"2026-06-12T22:59:47.840552Z","steps":["trace[873939363] 'process raft request' (duration: 211.394674ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:47.91941Z","caller":"traceutil/trace.go:171","msg":"trace[32268725] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"289.095585ms","start":"2026-06-12T22:59:47.630302Z","end":"2026-06-12T22:59:47.919397Z","steps":["trace[32268725] 'process raft request' (duration: 288.745678ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:47.919411Z","caller":"traceutil/trace.go:171","msg":"trace[958532129] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"289.077144ms","start":"2026-06-12T22:59:47.630218Z","end":"2026-06-12T22:59:47.919295Z","steps":["trace[958532129] 'process raft request' (duration: 288.681937ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:47.919575Z","caller":"traceutil/trace.go:171","msg":"trace[1897034760] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"289.007083ms","start":"2026-06-12T22:59:47.630346Z","end":"2026-06-12T22:59:47.919354Z","steps":["trace[1897034760] 'process raft request' (duration: 288.931631ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:53.939866Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185750370154285,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T22:59:54.440712Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185750370154285,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T22:59:54.733552Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.38040668s","expected-duration":"1s"} {"level":"info","ts":"2026-06-12T22:59:54.733962Z","caller":"traceutil/trace.go:171","msg":"trace[629873875] linearizableReadLoop","detail":"{readStateIndex:871; appliedIndex:870; }","duration":"1.294106131s","start":"2026-06-12T22:59:53.439831Z","end":"2026-06-12T22:59:54.733937Z","steps":["trace[629873875] 'read index received' (duration: 1.293896867s)","trace[629873875] 'applied index is now lower than readState.Index' (duration: 208.304µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:59:54.734037Z","caller":"traceutil/trace.go:171","msg":"trace[1584648747] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"1.381031591s","start":"2026-06-12T22:59:53.352973Z","end":"2026-06-12T22:59:54.734005Z","steps":["trace[1584648747] 'process raft request' (duration: 1.380815257s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:54.734137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026723358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-12T22:59:54.734186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:53.352952Z","time spent":"1.381154323s","remote":"127.0.0.1:51280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4022,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T22:59:54.734199Z","caller":"traceutil/trace.go:171","msg":"trace[556995620] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:834; }","duration":"1.026844129s","start":"2026-06-12T22:59:53.70734Z","end":"2026-06-12T22:59:54.734184Z","steps":["trace[556995620] 'agreement among raft nodes before linearized reading' (duration: 1.026718637s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:54.734196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294358956s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T22:59:54.734293Z","caller":"traceutil/trace.go:171","msg":"trace[1921215657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:834; }","duration":"1.294486148s","start":"2026-06-12T22:59:53.43979Z","end":"2026-06-12T22:59:54.734276Z","steps":["trace[1921215657] 'agreement among raft nodes before linearized reading' (duration: 1.294351745s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:54.734293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:53.707318Z","time spent":"1.026956432s","remote":"127.0.0.1:51452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-06-12T22:59:54.734379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:53.439772Z","time spent":"1.294552719s","remote":"127.0.0.1:51090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-12T22:59:54.734383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.918483ms","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-06-12T22:59:54.734444Z","caller":"traceutil/trace.go:171","msg":"trace[961271090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:834; }","duration":"716.990494ms","start":"2026-06-12T22:59:54.017438Z","end":"2026-06-12T22:59:54.734429Z","steps":["trace[961271090] 'agreement among raft nodes before linearized reading' (duration: 716.818131ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:54.734478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:54.017404Z","time spent":"717.065896ms","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-12T22:59:54.734616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.220141ms","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-06-12T22:59:54.734646Z","caller":"traceutil/trace.go:171","msg":"trace[599042655] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:834; }","duration":"787.278152ms","start":"2026-06-12T22:59:53.947359Z","end":"2026-06-12T22:59:54.734638Z","steps":["trace[599042655] 'agreement among raft nodes before linearized reading' (duration: 787.15728ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:54.73467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:53.947342Z","time spent":"787.319583ms","remote":"127.0.0.1:51368","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-06-12T22:59:54.734735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.353072ms","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":"warn","ts":"2026-06-12T22:59:54.734751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.162506ms","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-06-12T22:59:54.734807Z","caller":"traceutil/trace.go:171","msg":"trace[636074043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:834; }","duration":"872.246508ms","start":"2026-06-12T22:59:53.862546Z","end":"2026-06-12T22:59:54.734792Z","steps":["trace[636074043] 'agreement among raft nodes before linearized reading' (duration: 872.038644ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:54.734852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.033313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-12T22:59:54.734851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:53.862529Z","time spent":"872.310518ms","remote":"127.0.0.1:51368","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":"info","ts":"2026-06-12T22:59:54.734881Z","caller":"traceutil/trace.go:171","msg":"trace[1044929877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:834; }","duration":"718.104414ms","start":"2026-06-12T22:59:54.016769Z","end":"2026-06-12T22:59:54.734873Z","steps":["trace[1044929877] 'agreement among raft nodes before linearized reading' (duration: 718.027673ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:54.734769Z","caller":"traceutil/trace.go:171","msg":"trace[1465452911] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:834; }","duration":"419.424803ms","start":"2026-06-12T22:59:54.315335Z","end":"2026-06-12T22:59:54.73476Z","steps":["trace[1465452911] 'agreement among raft nodes before linearized reading' (duration: 419.310091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T22:59:54.734908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:54.016746Z","time spent":"718.155155ms","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-12T22:59:54.734941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T22:59:54.315308Z","time spent":"419.612876ms","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-12T22:59:54.951198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.170109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-12T22:59:54.951645Z","caller":"traceutil/trace.go:171","msg":"trace[1191682088] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"211.524323ms","start":"2026-06-12T22:59:54.7401Z","end":"2026-06-12T22:59:54.951625Z","steps":["trace[1191682088] 'process raft request' (duration: 27.834445ms)","trace[1191682088] 'compare' (duration: 182.674151ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:59:54.956462Z","caller":"traceutil/trace.go:171","msg":"trace[639335238] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"216.073472ms","start":"2026-06-12T22:59:54.740368Z","end":"2026-06-12T22:59:54.956441Z","steps":["trace[639335238] 'process raft request' (duration: 216.002781ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:54.956594Z","caller":"traceutil/trace.go:171","msg":"trace[2067721028] linearizableReadLoop","detail":"{readStateIndex:876; appliedIndex:871; }","duration":"214.722089ms","start":"2026-06-12T22:59:54.741784Z","end":"2026-06-12T22:59:54.956506Z","steps":["trace[2067721028] 'read index received' (duration: 26.172986ms)","trace[2067721028] 'applied index is now lower than readState.Index' (duration: 188.548373ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T22:59:54.956716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.913383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T22:59:54.956785Z","caller":"traceutil/trace.go:171","msg":"trace[649345668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:839; }","duration":"215.012504ms","start":"2026-06-12T22:59:54.74176Z","end":"2026-06-12T22:59:54.956773Z","steps":["trace[649345668] 'agreement among raft nodes before linearized reading' (duration: 214.885482ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:54.956822Z","caller":"traceutil/trace.go:171","msg":"trace[1994254070] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"216.48978ms","start":"2026-06-12T22:59:54.740314Z","end":"2026-06-12T22:59:54.956804Z","steps":["trace[1994254070] 'process raft request' (duration: 215.92517ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:54.956915Z","caller":"traceutil/trace.go:171","msg":"trace[2074014457] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"216.468059ms","start":"2026-06-12T22:59:54.740427Z","end":"2026-06-12T22:59:54.956895Z","steps":["trace[2074014457] 'process raft request' (duration: 216.032822ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:54.956805Z","caller":"traceutil/trace.go:171","msg":"trace[953189640] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"216.370847ms","start":"2026-06-12T22:59:54.740368Z","end":"2026-06-12T22:59:54.956739Z","steps":["trace[953189640] 'process raft request' (duration: 216.039712ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:58.634298Z","caller":"traceutil/trace.go:171","msg":"trace[1944163786] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"180.558988ms","start":"2026-06-12T22:59:58.453682Z","end":"2026-06-12T22:59:58.634241Z","steps":["trace[1944163786] 'process raft request' (duration: 180.410905ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:58.828389Z","caller":"traceutil/trace.go:171","msg":"trace[1659439284] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"186.493969ms","start":"2026-06-12T22:59:58.641873Z","end":"2026-06-12T22:59:58.828367Z","steps":["trace[1659439284] 'process raft request' (duration: 184.236211ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:59.021629Z","caller":"traceutil/trace.go:171","msg":"trace[207184596] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"186.53683ms","start":"2026-06-12T22:59:58.835057Z","end":"2026-06-12T22:59:59.021594Z","steps":["trace[207184596] 'process raft request' (duration: 159.66482ms)","trace[207184596] 'compare' (duration: 26.517544ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:59:59.189058Z","caller":"traceutil/trace.go:171","msg":"trace[357621951] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"162.126912ms","start":"2026-06-12T22:59:59.026909Z","end":"2026-06-12T22:59:59.189036Z","steps":["trace[357621951] 'process raft request' (duration: 145.837994ms)","trace[357621951] 'compare' (duration: 16.073315ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:59:59.189271Z","caller":"traceutil/trace.go:171","msg":"trace[2105897889] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"162.191143ms","start":"2026-06-12T22:59:59.027073Z","end":"2026-06-12T22:59:59.189265Z","steps":["trace[2105897889] 'process raft request' (duration: 161.864698ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:59.189414Z","caller":"traceutil/trace.go:171","msg":"trace[131437180] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"162.316535ms","start":"2026-06-12T22:59:59.027091Z","end":"2026-06-12T22:59:59.189408Z","steps":["trace[131437180] 'process raft request' (duration: 161.911048ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:59.189543Z","caller":"traceutil/trace.go:171","msg":"trace[1318956348] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"162.400287ms","start":"2026-06-12T22:59:59.027136Z","end":"2026-06-12T22:59:59.189536Z","steps":["trace[1318956348] 'process raft request' (duration: 162.038781ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:59.189588Z","caller":"traceutil/trace.go:171","msg":"trace[1209801154] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"161.778466ms","start":"2026-06-12T22:59:59.027805Z","end":"2026-06-12T22:59:59.189583Z","steps":["trace[1209801154] 'process raft request' (duration: 161.649363ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:59.189603Z","caller":"traceutil/trace.go:171","msg":"trace[1394800555] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"160.602785ms","start":"2026-06-12T22:59:59.028975Z","end":"2026-06-12T22:59:59.189578Z","steps":["trace[1394800555] 'process raft request' (duration: 160.536454ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:59.521153Z","caller":"traceutil/trace.go:171","msg":"trace[379008962] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"136.130407ms","start":"2026-06-12T22:59:59.384971Z","end":"2026-06-12T22:59:59.521101Z","steps":["trace[379008962] 'process raft request' (duration: 66.208452ms)","trace[379008962] 'compare' (duration: 69.716482ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:59:59.673653Z","caller":"traceutil/trace.go:171","msg":"trace[1626082993] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"147.467661ms","start":"2026-06-12T22:59:59.526161Z","end":"2026-06-12T22:59:59.673628Z","steps":["trace[1626082993] 'process raft request' (duration: 98.905681ms)","trace[1626082993] 'compare' (duration: 48.396967ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T22:59:59.990152Z","caller":"traceutil/trace.go:171","msg":"trace[1677304618] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"175.655113ms","start":"2026-06-12T22:59:59.814471Z","end":"2026-06-12T22:59:59.990126Z","steps":["trace[1677304618] 'process raft request' (duration: 172.877246ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T22:59:59.990153Z","caller":"traceutil/trace.go:171","msg":"trace[77974194] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"173.18288ms","start":"2026-06-12T22:59:59.816948Z","end":"2026-06-12T22:59:59.990131Z","steps":["trace[77974194] 'process raft request' (duration: 173.091239ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T23:00:00.217168Z","caller":"traceutil/trace.go:171","msg":"trace[1503661364] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:910; }","duration":"157.90993ms","start":"2026-06-12T23:00:00.059231Z","end":"2026-06-12T23:00:00.217141Z","steps":["trace[1503661364] 'read index received' (duration: 81.143382ms)","trace[1503661364] 'applied index is now lower than readState.Index' (duration: 76.765378ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T23:00:00.217212Z","caller":"traceutil/trace.go:171","msg":"trace[259201797] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"159.175162ms","start":"2026-06-12T23:00:00.05801Z","end":"2026-06-12T23:00:00.217185Z","steps":["trace[259201797] 'process raft request' (duration: 159.003959ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T23:00:00.217391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.759646ms","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-06-12T23:00:00.217465Z","caller":"traceutil/trace.go:171","msg":"trace[374061883] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:874; }","duration":"107.892267ms","start":"2026-06-12T23:00:00.109556Z","end":"2026-06-12T23:00:00.217448Z","steps":["trace[374061883] 'agreement among raft nodes before linearized reading' (duration: 107.756395ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T23:00:00.217614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.096273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T23:00:00.217674Z","caller":"traceutil/trace.go:171","msg":"trace[1344873361] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:874; }","duration":"158.47649ms","start":"2026-06-12T23:00:00.059183Z","end":"2026-06-12T23:00:00.217659Z","steps":["trace[1344873361] 'agreement among raft nodes before linearized reading' (duration: 158.092513ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T23:00:00.513743Z","caller":"traceutil/trace.go:171","msg":"trace[1569140912] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"257.476936ms","start":"2026-06-12T23:00:00.256248Z","end":"2026-06-12T23:00:00.513725Z","steps":["trace[1569140912] 'process raft request' (duration: 257.359414ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T23:00:00.575437Z","caller":"traceutil/trace.go:171","msg":"trace[496717623] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"319.126745ms","start":"2026-06-12T23:00:00.256275Z","end":"2026-06-12T23:00:00.575401Z","steps":["trace[496717623] 'process raft request' (duration: 318.037867ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T23:00:00.575478Z","caller":"traceutil/trace.go:171","msg":"trace[468359994] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"319.134696ms","start":"2026-06-12T23:00:00.256315Z","end":"2026-06-12T23:00:00.575449Z","steps":["trace[468359994] 'process raft request' (duration: 318.936893ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T23:00:00.575599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T23:00:00.256259Z","time spent":"319.267619ms","remote":"127.0.0.1:51146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":755,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-12T23:00:00.575847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T23:00:00.256292Z","time spent":"319.442901ms","remote":"127.0.0.1:51596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5099,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T23:00:00.576132Z","caller":"traceutil/trace.go:171","msg":"trace[447245665] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"315.961982ms","start":"2026-06-12T23:00:00.260146Z","end":"2026-06-12T23:00:00.576108Z","steps":["trace[447245665] 'process raft request' (duration: 315.170488ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T23:00:00.576254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T23:00:00.260109Z","time spent":"316.093924ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5562,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T23:00:00.577981Z","caller":"traceutil/trace.go:171","msg":"trace[967935837] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"175.482628ms","start":"2026-06-12T23:00:00.402475Z","end":"2026-06-12T23:00:00.577957Z","steps":["trace[967935837] 'process raft request' (duration: 173.560235ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T23:00:00.691802Z","caller":"traceutil/trace.go:171","msg":"trace[437586353] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"170.027107ms","start":"2026-06-12T23:00:00.521743Z","end":"2026-06-12T23:00:00.691771Z","steps":["trace[437586353] 'process raft request' (duration: 95.708461ms)","trace[437586353] 'compare' (duration: 73.857808ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T23:00:00.69221Z","caller":"traceutil/trace.go:171","msg":"trace[1604025716] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"100.275729ms","start":"2026-06-12T23:00:00.591846Z","end":"2026-06-12T23:00:00.692122Z","steps":["trace[1604025716] 'process raft request' (duration: 99.652998ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T23:00:00.995591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.160947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-12T23:00:00.99569Z","caller":"traceutil/trace.go:171","msg":"trace[1102707122] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:921; }","duration":"256.737394ms","start":"2026-06-12T23:00:00.738937Z","end":"2026-06-12T23:00:00.995674Z","steps":["trace[1102707122] 'read index received' (duration: 89.322892ms)","trace[1102707122] 'applied index is now lower than readState.Index' (duration: 167.413532ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T23:00:00.995798Z","caller":"traceutil/trace.go:171","msg":"trace[121485662] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"296.292648ms","start":"2026-06-12T23:00:00.699473Z","end":"2026-06-12T23:00:00.995766Z","steps":["trace[121485662] 'process raft request' (duration: 128.880816ms)","trace[121485662] 'compare' (duration: 167.047855ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T23:00:00.995855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.914467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-12T23:00:00.995888Z","caller":"traceutil/trace.go:171","msg":"trace[1347907353] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:884; }","duration":"256.984227ms","start":"2026-06-12T23:00:00.738891Z","end":"2026-06-12T23:00:00.995875Z","steps":["trace[1347907353] 'agreement among raft nodes before linearized reading' (duration: 256.873426ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T23:00:01.427334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.015455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-12T23:00:01.427916Z","caller":"traceutil/trace.go:171","msg":"trace[1483360681] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"215.571259ms","start":"2026-06-12T23:00:01.212332Z","end":"2026-06-12T23:00:01.427903Z","steps":["trace[1483360681] 'process raft request' (duration: 80.919533ms)","trace[1483360681] 'compare' (duration: 133.876883ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T23:00:01.435816Z","caller":"traceutil/trace.go:171","msg":"trace[155834998] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:924; }","duration":"222.230472ms","start":"2026-06-12T23:00:01.213571Z","end":"2026-06-12T23:00:01.435801Z","steps":["trace[155834998] 'read index received' (duration: 79.703453ms)","trace[155834998] 'applied index is now lower than readState.Index' (duration: 142.526239ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T23:00:01.435953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.365965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-12T23:00:01.435983Z","caller":"traceutil/trace.go:171","msg":"trace[941285375] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:890; }","duration":"222.427356ms","start":"2026-06-12T23:00:01.213546Z","end":"2026-06-12T23:00:01.435974Z","steps":["trace[941285375] 'agreement among raft nodes before linearized reading' (duration: 222.307084ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T23:00:01.436216Z","caller":"traceutil/trace.go:171","msg":"trace[771935458] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"223.831049ms","start":"2026-06-12T23:00:01.212377Z","end":"2026-06-12T23:00:01.436208Z","steps":["trace[771935458] 'process raft request' (duration: 223.27302ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T23:00:01.436298Z","caller":"traceutil/trace.go:171","msg":"trace[1837018352] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"223.900561ms","start":"2026-06-12T23:00:01.212391Z","end":"2026-06-12T23:00:01.436292Z","steps":["trace[1837018352] 'process raft request' (duration: 223.341211ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T23:00:01.436367Z","caller":"traceutil/trace.go:171","msg":"trace[1526737627] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"223.906961ms","start":"2026-06-12T23:00:01.212454Z","end":"2026-06-12T23:00:01.436361Z","steps":["trace[1526737627] 'process raft request' (duration: 223.303031ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T23:00:01.43642Z","caller":"traceutil/trace.go:171","msg":"trace[78470526] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"223.954682ms","start":"2026-06-12T23:00:01.21246Z","end":"2026-06-12T23:00:01.436415Z","steps":["trace[78470526] 'process raft request' (duration: 223.319361ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T23:00:01.608675Z","caller":"traceutil/trace.go:171","msg":"trace[239439846] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"112.699273ms","start":"2026-06-12T23:00:01.495954Z","end":"2026-06-12T23:00:01.608653Z","steps":["trace[239439846] 'process raft request' (duration: 71.652746ms)","trace[239439846] 'compare' (duration: 40.945995ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T23:00:09.796152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.368551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-12T23:00:09.796232Z","caller":"traceutil/trace.go:171","msg":"trace[1701009382] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"313.477282ms","start":"2026-06-12T23:00:09.482742Z","end":"2026-06-12T23:00:09.79622Z","steps":["trace[1701009382] 'process raft request' (duration: 123.983219ms)","trace[1701009382] 'compare' (duration: 189.28173ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T23:00:09.796269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T23:00:09.482725Z","time spent":"313.528864ms","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T23:00:09.819277Z","caller":"traceutil/trace.go:171","msg":"trace[1285678399] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1034; }","duration":"335.932784ms","start":"2026-06-12T23:00:09.483317Z","end":"2026-06-12T23:00:09.81925Z","steps":["trace[1285678399] 'read index received' (duration: 123.4095ms)","trace[1285678399] 'applied index is now lower than readState.Index' (duration: 212.522314ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T23:00:09.819368Z","caller":"traceutil/trace.go:171","msg":"trace[1797289217] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"333.438333ms","start":"2026-06-12T23:00:09.48589Z","end":"2026-06-12T23:00:09.819328Z","steps":["trace[1797289217] 'process raft request' (duration: 333.330531ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T23:00:09.819796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T23:00:09.485877Z","time spent":"333.8328ms","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T23:00:09.819556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.212369ms","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-06-12T23:00:09.820488Z","caller":"traceutil/trace.go:171","msg":"trace[647150287] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:997; }","duration":"337.175974ms","start":"2026-06-12T23:00:09.483298Z","end":"2026-06-12T23:00:09.820474Z","steps":["trace[647150287] 'agreement among raft nodes before linearized reading' (duration: 336.117487ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T23:00:09.820611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T23:00:09.48329Z","time spent":"337.308686ms","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-12T23:00:09.819485Z","caller":"traceutil/trace.go:171","msg":"trace[503054841] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"336.629096ms","start":"2026-06-12T23:00:09.482838Z","end":"2026-06-12T23:00:09.819467Z","steps":["trace[503054841] 'process raft request' (duration: 336.27193ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T23:00:09.822263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T23:00:09.482822Z","time spent":"339.402651ms","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T23:00:11.595002Z","caller":"traceutil/trace.go:171","msg":"trace[553811508] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"111.795116ms","start":"2026-06-12T23:00:11.483175Z","end":"2026-06-12T23:00:11.59497Z","steps":["trace[553811508] 'process raft request' (duration: 89.986798ms)","trace[553811508] 'compare' (duration: 21.687866ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T23:00:11.912002Z","caller":"traceutil/trace.go:171","msg":"trace[859866408] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"103.50367ms","start":"2026-06-12T23:00:11.808469Z","end":"2026-06-12T23:00:11.911972Z","steps":["trace[859866408] 'process raft request' (duration: 103.261946ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T23:00:17.8458Z","caller":"traceutil/trace.go:171","msg":"trace[623784859] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"131.35333ms","start":"2026-06-12T23:00:17.714425Z","end":"2026-06-12T23:00:17.845779Z","steps":["trace[623784859] 'process raft request' (duration: 131.216518ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T23:01:41.139694Z","caller":"traceutil/trace.go:171","msg":"trace[933623876] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1409; }","duration":"238.708886ms","start":"2026-06-12T23:01:40.90096Z","end":"2026-06-12T23:01:41.139669Z","steps":["trace[933623876] 'read index received' (duration: 238.542403ms)","trace[933623876] 'applied index is now lower than readState.Index' (duration: 163.563µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T23:01:41.139724Z","caller":"traceutil/trace.go:171","msg":"trace[1517870134] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"275.830386ms","start":"2026-06-12T23:01:40.863865Z","end":"2026-06-12T23:01:41.139695Z","steps":["trace[1517870134] 'process raft request' (duration: 275.656764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T23:01:41.140625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.644579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T23:01:41.140726Z","caller":"traceutil/trace.go:171","msg":"trace[1021414191] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1349; }","duration":"239.79205ms","start":"2026-06-12T23:01:40.90091Z","end":"2026-06-12T23:01:41.140703Z","steps":["trace[1021414191] 'agreement among raft nodes before linearized reading' (duration: 238.894888ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T23:01:50.877454Z","caller":"traceutil/trace.go:171","msg":"trace[1353077677] linearizableReadLoop","detail":"{readStateIndex:1447; appliedIndex:1446; }","duration":"242.885897ms","start":"2026-06-12T23:01:50.634539Z","end":"2026-06-12T23:01:50.877425Z","steps":["trace[1353077677] 'read index received' (duration: 242.634854ms)","trace[1353077677] 'applied index is now lower than readState.Index' (duration: 250.053µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T23:01:50.877551Z","caller":"traceutil/trace.go:171","msg":"trace[229041094] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"282.69012ms","start":"2026-06-12T23:01:50.594832Z","end":"2026-06-12T23:01:50.877522Z","steps":["trace[229041094] 'process raft request' (duration: 282.431477ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T23:01:50.877724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.617436ms","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-06-12T23:01:50.877764Z","caller":"traceutil/trace.go:171","msg":"trace[1985350458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1384; }","duration":"135.706027ms","start":"2026-06-12T23:01:50.742049Z","end":"2026-06-12T23:01:50.877755Z","steps":["trace[1985350458] 'agreement among raft nodes before linearized reading' (duration: 135.542854ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T23:01:50.877846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.200642ms","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-06-12T23:01:50.877944Z","caller":"traceutil/trace.go:171","msg":"trace[236219047] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1384; }","duration":"243.433015ms","start":"2026-06-12T23:01:50.634485Z","end":"2026-06-12T23:01:50.877918Z","steps":["trace[236219047] 'agreement among raft nodes before linearized reading' (duration: 243.08724ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T23:02:13.283961Z","caller":"traceutil/trace.go:171","msg":"trace[761324484] linearizableReadLoop","detail":"{readStateIndex:1531; appliedIndex:1530; }","duration":"193.266112ms","start":"2026-06-12T23:02:13.090672Z","end":"2026-06-12T23:02:13.283939Z","steps":["trace[761324484] 'read index received' (duration: 193.090122ms)","trace[761324484] 'applied index is now lower than readState.Index' (duration: 175µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T23:02:13.284048Z","caller":"traceutil/trace.go:171","msg":"trace[1066174299] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"239.772767ms","start":"2026-06-12T23:02:13.044245Z","end":"2026-06-12T23:02:13.284018Z","steps":["trace[1066174299] 'process raft request' (duration: 239.497772ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T23:02:13.284133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.437471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:1 size:3017"} {"level":"info","ts":"2026-06-12T23:02:13.284191Z","caller":"traceutil/trace.go:171","msg":"trace[403777224] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:1; response_revision:1462; }","duration":"193.527946ms","start":"2026-06-12T23:02:13.090638Z","end":"2026-06-12T23:02:13.284166Z","steps":["trace[403777224] 'agreement among raft nodes before linearized reading' (duration: 193.432681ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T23:02:13.284417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.113722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/local-path-storage/\" range_end:\"/registry/cronjobs/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T23:02:13.284492Z","caller":"traceutil/trace.go:171","msg":"trace[804891528] range","detail":"{range_begin:/registry/cronjobs/local-path-storage/; range_end:/registry/cronjobs/local-path-storage0; response_count:0; response_revision:1462; }","duration":"151.232169ms","start":"2026-06-12T23:02:13.13324Z","end":"2026-06-12T23:02:13.284472Z","steps":["trace[804891528] 'agreement among raft nodes before linearized reading' (duration: 151.108132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T23:02:13.284557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.645852ms","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-06-12T23:02:13.284646Z","caller":"traceutil/trace.go:171","msg":"trace[540647568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1462; }","duration":"139.744687ms","start":"2026-06-12T23:02:13.144881Z","end":"2026-06-12T23:02:13.284626Z","steps":["trace[540647568] 'agreement among raft nodes before linearized reading' (duration: 139.550056ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T23:02:13.284746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.372712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" ","response":"range_response_count:1 size:370"} {"level":"warn","ts":"2026-06-12T23:02:13.284779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.885105ms","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-06-12T23:02:13.284848Z","caller":"traceutil/trace.go:171","msg":"trace[1491288271] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1462; }","duration":"140.001161ms","start":"2026-06-12T23:02:13.144828Z","end":"2026-06-12T23:02:13.284829Z","steps":["trace[1491288271] 'agreement among raft nodes before linearized reading' (duration: 139.810281ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T23:02:13.28487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.695294ms","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-06-12T23:02:13.284797Z","caller":"traceutil/trace.go:171","msg":"trace[1034196284] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking; range_end:; response_count:1; response_revision:1462; }","duration":"145.447935ms","start":"2026-06-12T23:02:13.139334Z","end":"2026-06-12T23:02:13.284782Z","steps":["trace[1034196284] 'agreement among raft nodes before linearized reading' (duration: 145.34393ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T23:02:13.284913Z","caller":"traceutil/trace.go:171","msg":"trace[1492567525] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1462; }","duration":"139.751938ms","start":"2026-06-12T23:02:13.145148Z","end":"2026-06-12T23:02:13.2849Z","steps":["trace[1492567525] 'agreement among raft nodes before linearized reading' (duration: 139.633121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T23:02:13.284949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.065265ms","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-06-12T23:02:13.284994Z","caller":"traceutil/trace.go:171","msg":"trace[1672862823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1462; }","duration":"140.143979ms","start":"2026-06-12T23:02:13.144836Z","end":"2026-06-12T23:02:13.28498Z","steps":["trace[1672862823] 'agreement among raft nodes before linearized reading' (duration: 140.030813ms)"],"step_count":1}