{"level":"warn","ts":"2026-05-28T10:40:40.817021Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T10:40:40.81716Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35: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.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-05-28T10:40:40.817266Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T10:40:40.817307Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-05-28T10:40:40.81738Z","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-05-28T10:40:40.818049Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-05-28T10:40:40.81819Z","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.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-05-28T10:40:40.82384Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.359543ms"} {"level":"info","ts":"2026-05-28T10:40:40.836443Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-05-28T10:40:40.836587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-05-28T10:40:40.836637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-05-28T10:40:40.836676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-28T10:40:40.836722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-05-28T10:40:40.836789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-05-28T10:40:40.849374Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-28T10:40:40.852201Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-28T10:40:40.854049Z","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-05-28T10:40:40.856719Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-28T10:40:40.857402Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-28T10:40:40.857599Z","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-05-28T10:40:40.861008Z","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-05-28T10:40:40.861666Z","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-05-28T10:40:40.863014Z","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-05-28T10:40:40.863062Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-05-28T10:40:40.863078Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-05-28T10:40:40.863194Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-28T10:40:40.863225Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-28T10:40:40.863879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-05-28T10:40:40.86417Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-05-28T10:40:41.237856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-05-28T10:40:41.237921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-05-28T10:40:41.237944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-05-28T10:40:41.237993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-05-28T10:40:41.238007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-05-28T10:40:41.238024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-05-28T10:40:41.238037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-05-28T10:40:41.30028Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T10:40:41.38454Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-05-28T10:40:41.384756Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T10:40:41.38481Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T10:40:41.38582Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-28T10:40:41.38588Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-28T10:40:41.387184Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-28T10:40:41.387272Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-05-28T10:40:41.397985Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T10:40:41.398208Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T10:40:41.398249Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T10:40:49.50633Z","caller":"traceutil/trace.go:171","msg":"trace[150654604] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:312; }","duration":"113.239379ms","start":"2026-05-28T10:40:49.393061Z","end":"2026-05-28T10:40:49.506301Z","steps":["trace[150654604] 'read index received' (duration: 109.316558ms)","trace[150654604] 'applied index is now lower than readState.Index' (duration: 3.921501ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:40:49.506411Z","caller":"traceutil/trace.go:171","msg":"trace[697715987] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"114.128888ms","start":"2026-05-28T10:40:49.392234Z","end":"2026-05-28T10:40:49.506363Z","steps":["trace[697715987] 'process raft request' (duration: 110.188077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:49.506676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.527979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-28T10:40:49.506836Z","caller":"traceutil/trace.go:171","msg":"trace[1718889136] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:297; }","duration":"113.813718ms","start":"2026-05-28T10:40:49.392991Z","end":"2026-05-28T10:40:49.506804Z","steps":["trace[1718889136] 'agreement among raft nodes before linearized reading' (duration: 113.468401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:49.722161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.592454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-28T10:40:49.722332Z","caller":"traceutil/trace.go:171","msg":"trace[906763324] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:300; }","duration":"106.821515ms","start":"2026-05-28T10:40:49.615484Z","end":"2026-05-28T10:40:49.722305Z","steps":["trace[906763324] 'range keys from in-memory index tree' (duration: 106.376191ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:40:50.021639Z","caller":"traceutil/trace.go:171","msg":"trace[1262280714] linearizableReadLoop","detail":"{readStateIndex:319; appliedIndex:318; }","duration":"157.584232ms","start":"2026-05-28T10:40:49.864027Z","end":"2026-05-28T10:40:50.021612Z","steps":["trace[1262280714] 'read index received' (duration: 157.394529ms)","trace[1262280714] 'applied index is now lower than readState.Index' (duration: 184.263µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:40:50.021682Z","caller":"traceutil/trace.go:171","msg":"trace[1615253590] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"199.554134ms","start":"2026-05-28T10:40:49.822086Z","end":"2026-05-28T10:40:50.02164Z","steps":["trace[1615253590] 'process raft request' (duration: 199.369401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:50.022154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.107715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-28T10:40:50.022265Z","caller":"traceutil/trace.go:171","msg":"trace[773174484] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:303; }","duration":"158.25472ms","start":"2026-05-28T10:40:49.863985Z","end":"2026-05-28T10:40:50.02224Z","steps":["trace[773174484] 'agreement among raft nodes before linearized reading' (duration: 157.782666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:50.022371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.253267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-28T10:40:50.022456Z","caller":"traceutil/trace.go:171","msg":"trace[2007022257] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:303; }","duration":"107.404512ms","start":"2026-05-28T10:40:49.915038Z","end":"2026-05-28T10:40:50.022442Z","steps":["trace[2007022257] 'agreement among raft nodes before linearized reading' (duration: 106.734815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:50.479987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.468885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-28T10:40:50.480193Z","caller":"traceutil/trace.go:171","msg":"trace[1024718477] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"251.849196ms","start":"2026-05-28T10:40:50.22833Z","end":"2026-05-28T10:40:50.480179Z","steps":["trace[1024718477] 'process raft request' (duration: 251.800937ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:40:50.480356Z","caller":"traceutil/trace.go:171","msg":"trace[29462406] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"254.942292ms","start":"2026-05-28T10:40:50.225348Z","end":"2026-05-28T10:40:50.48029Z","steps":["trace[29462406] 'process raft request' (duration: 78.677803ms)","trace[29462406] 'compare' (duration: 175.31364ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:40:50.480401Z","caller":"traceutil/trace.go:171","msg":"trace[1793136976] linearizableReadLoop","detail":"{readStateIndex:321; appliedIndex:320; }","duration":"253.905897ms","start":"2026-05-28T10:40:50.226378Z","end":"2026-05-28T10:40:50.480284Z","steps":["trace[1793136976] 'read index received' (duration: 77.634689ms)","trace[1793136976] 'applied index is now lower than readState.Index' (duration: 176.266178ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T10:40:50.48068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.289384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-05-28T10:40:50.480719Z","caller":"traceutil/trace.go:171","msg":"trace[1294193315] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:306; }","duration":"254.356311ms","start":"2026-05-28T10:40:50.226354Z","end":"2026-05-28T10:40:50.48071Z","steps":["trace[1294193315] 'agreement among raft nodes before linearized reading' (duration: 254.246565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:50.480902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.516519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-28T10:40:50.481002Z","caller":"traceutil/trace.go:171","msg":"trace[1010690722] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:306; }","duration":"216.649564ms","start":"2026-05-28T10:40:50.264326Z","end":"2026-05-28T10:40:50.480976Z","steps":["trace[1010690722] 'agreement among raft nodes before linearized reading' (duration: 216.397683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:50.481126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.759844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-28T10:40:50.481211Z","caller":"traceutil/trace.go:171","msg":"trace[760035885] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:306; }","duration":"165.87276ms","start":"2026-05-28T10:40:50.315306Z","end":"2026-05-28T10:40:50.481179Z","steps":["trace[760035885] 'agreement among raft nodes before linearized reading' (duration: 165.719115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:50.485845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.711656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-28T10:40:50.485997Z","caller":"traceutil/trace.go:171","msg":"trace[1005355994] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:306; }","duration":"121.402312ms","start":"2026-05-28T10:40:50.364548Z","end":"2026-05-28T10:40:50.485951Z","steps":["trace[1005355994] 'agreement among raft nodes before linearized reading' (duration: 117.619469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:50.800318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.664621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-28T10:40:50.800644Z","caller":"traceutil/trace.go:171","msg":"trace[179699706] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"305.547523ms","start":"2026-05-28T10:40:50.495034Z","end":"2026-05-28T10:40:50.800581Z","steps":["trace[179699706] 'process raft request' (duration: 188.563332ms)","trace[179699706] 'compare' (duration: 116.525266ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T10:40:50.800854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:40:50.495009Z","time spent":"305.745797ms","remote":"127.0.0.1:48140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T10:40:50.800898Z","caller":"traceutil/trace.go:171","msg":"trace[643467220] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"304.721341ms","start":"2026-05-28T10:40:50.496107Z","end":"2026-05-28T10:40:50.800829Z","steps":["trace[643467220] 'process raft request' (duration: 304.387402ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:40:50.801011Z","caller":"traceutil/trace.go:171","msg":"trace[173352870] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"304.781899ms","start":"2026-05-28T10:40:50.496215Z","end":"2026-05-28T10:40:50.800997Z","steps":["trace[173352870] 'process raft request' (duration: 304.430481ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:40:50.801183Z","caller":"traceutil/trace.go:171","msg":"trace[625793670] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"303.713465ms","start":"2026-05-28T10:40:50.497452Z","end":"2026-05-28T10:40:50.801165Z","steps":["trace[625793670] 'process raft request' (duration: 303.388316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:50.801273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:40:50.4961Z","time spent":"304.989852ms","remote":"127.0.0.1:48232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1247,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T10:40:50.801377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:40:50.497442Z","time spent":"303.8372ms","remote":"127.0.0.1:48480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T10:40:50.801237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:40:50.496201Z","time spent":"304.876466ms","remote":"127.0.0.1:48152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4354,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T10:40:50.802067Z","caller":"traceutil/trace.go:171","msg":"trace[61259501] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"304.018905ms","start":"2026-05-28T10:40:50.498026Z","end":"2026-05-28T10:40:50.802045Z","steps":["trace[61259501] 'process raft request' (duration: 303.011818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:50.802356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:40:50.498013Z","time spent":"304.263797ms","remote":"127.0.0.1:48316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T10:40:50.802396Z","caller":"traceutil/trace.go:171","msg":"trace[1706867841] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"304.016845ms","start":"2026-05-28T10:40:50.498351Z","end":"2026-05-28T10:40:50.802368Z","steps":["trace[1706867841] 'process raft request' (duration: 303.670856ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:40:50.8025Z","caller":"traceutil/trace.go:171","msg":"trace[2105986324] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:323; }","duration":"248.999163ms","start":"2026-05-28T10:40:50.553483Z","end":"2026-05-28T10:40:50.802482Z","steps":["trace[2105986324] 'read index received' (duration: 130.144045ms)","trace[2105986324] 'applied index is now lower than readState.Index' (duration: 118.853158ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T10:40:50.802692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:40:50.498344Z","time spent":"304.15674ms","remote":"127.0.0.1:48316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T10:40:50.80282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.420025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-28T10:40:50.802922Z","caller":"traceutil/trace.go:171","msg":"trace[828204386] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:314; }","duration":"287.668647ms","start":"2026-05-28T10:40:50.515239Z","end":"2026-05-28T10:40:50.802908Z","steps":["trace[828204386] 'agreement among raft nodes before linearized reading' (duration: 287.425675ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:40:50.802091Z","caller":"traceutil/trace.go:171","msg":"trace[2061773684] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"303.87262ms","start":"2026-05-28T10:40:50.4982Z","end":"2026-05-28T10:40:50.802072Z","steps":["trace[2061773684] 'process raft request' (duration: 303.775523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:50.803318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:40:50.498193Z","time spent":"305.071689ms","remote":"127.0.0.1:48316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T10:40:50.803788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.084862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-05-28T10:40:50.803916Z","caller":"traceutil/trace.go:171","msg":"trace[330570913] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:315; }","duration":"246.214967ms","start":"2026-05-28T10:40:50.557646Z","end":"2026-05-28T10:40:50.803861Z","steps":["trace[330570913] 'agreement among raft nodes before linearized reading' (duration: 246.084951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:50.804406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.622348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-28T10:40:50.804457Z","caller":"traceutil/trace.go:171","msg":"trace[796222287] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"245.790451ms","start":"2026-05-28T10:40:50.558644Z","end":"2026-05-28T10:40:50.804435Z","steps":["trace[796222287] 'process raft request' (duration: 243.664513ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:40:50.80452Z","caller":"traceutil/trace.go:171","msg":"trace[520970378] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:315; }","duration":"239.765005ms","start":"2026-05-28T10:40:50.564731Z","end":"2026-05-28T10:40:50.804496Z","steps":["trace[520970378] 'agreement among raft nodes before linearized reading' (duration: 239.569441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:50.804478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.087202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-05-28T10:40:50.804729Z","caller":"traceutil/trace.go:171","msg":"trace[703219508] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:315; }","duration":"190.333704ms","start":"2026-05-28T10:40:50.614379Z","end":"2026-05-28T10:40:50.804713Z","steps":["trace[703219508] 'agreement among raft nodes before linearized reading' (duration: 190.052743ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:40:51.870484Z","caller":"traceutil/trace.go:171","msg":"trace[804062132] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:364; }","duration":"344.965662ms","start":"2026-05-28T10:40:51.525482Z","end":"2026-05-28T10:40:51.870447Z","steps":["trace[804062132] 'read index received' (duration: 344.596824ms)","trace[804062132] 'applied index is now lower than readState.Index' (duration: 367.208µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:40:51.870635Z","caller":"traceutil/trace.go:171","msg":"trace[2072487394] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"353.589503ms","start":"2026-05-28T10:40:51.517005Z","end":"2026-05-28T10:40:51.870595Z","steps":["trace[2072487394] 'process raft request' (duration: 353.193556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:51.870748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.225424ms","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-05-28T10:40:51.870804Z","caller":"traceutil/trace.go:171","msg":"trace[2004741827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:349; }","duration":"345.33519ms","start":"2026-05-28T10:40:51.525455Z","end":"2026-05-28T10:40:51.87079Z","steps":["trace[2004741827] 'agreement among raft nodes before linearized reading' (duration: 345.200614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:51.870849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:40:51.516987Z","time spent":"353.738879ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T10:40:51.870937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.585611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-28T10:40:51.870979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.275249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-05-28T10:40:51.871016Z","caller":"traceutil/trace.go:171","msg":"trace[599689352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:349; }","duration":"337.727346ms","start":"2026-05-28T10:40:51.533271Z","end":"2026-05-28T10:40:51.870998Z","steps":["trace[599689352] 'agreement among raft nodes before linearized reading' (duration: 337.586331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:51.870857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:40:51.525439Z","time spent":"345.406678ms","remote":"127.0.0.1:48248","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-05-28T10:40:51.871059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:40:51.533242Z","time spent":"337.810783ms","remote":"127.0.0.1:48248","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-05-28T10:40:51.871064Z","caller":"traceutil/trace.go:171","msg":"trace[628749605] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:349; }","duration":"148.426704ms","start":"2026-05-28T10:40:51.722615Z","end":"2026-05-28T10:40:51.871042Z","steps":["trace[628749605] 'agreement among raft nodes before linearized reading' (duration: 148.253199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:52.674721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219122029685606188,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T10:40:53.040599Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.159399426s","expected-duration":"1s"} {"level":"info","ts":"2026-05-28T10:40:53.065095Z","caller":"traceutil/trace.go:171","msg":"trace[205979028] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"1.18399722s","start":"2026-05-28T10:40:51.881068Z","end":"2026-05-28T10:40:53.065065Z","steps":["trace[205979028] 'process raft request' (duration: 1.159800944s)","trace[205979028] 'compare' (duration: 23.566884ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T10:40:53.065262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:40:51.881035Z","time spent":"1.184145386s","remote":"127.0.0.1:48164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T10:40:53.079553Z","caller":"traceutil/trace.go:171","msg":"trace[802115988] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:365; }","duration":"905.062461ms","start":"2026-05-28T10:40:52.174463Z","end":"2026-05-28T10:40:53.079526Z","steps":["trace[802115988] 'read index received' (duration: 866.398661ms)","trace[802115988] 'applied index is now lower than readState.Index' (duration: 38.66266ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:40:53.079645Z","caller":"traceutil/trace.go:171","msg":"trace[101204346] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"1.198455274s","start":"2026-05-28T10:40:51.881155Z","end":"2026-05-28T10:40:53.07961Z","steps":["trace[101204346] 'process raft request' (duration: 1.198320029s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:53.079724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.246685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:40:53.079765Z","caller":"traceutil/trace.go:171","msg":"trace[1832177021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:352; }","duration":"905.355842ms","start":"2026-05-28T10:40:52.174397Z","end":"2026-05-28T10:40:53.079753Z","steps":["trace[1832177021] 'agreement among raft nodes before linearized reading' (duration: 905.249165ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:40:53.079592Z","caller":"traceutil/trace.go:171","msg":"trace[1158180231] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"1.198474404s","start":"2026-05-28T10:40:51.8811Z","end":"2026-05-28T10:40:53.079575Z","steps":["trace[1158180231] 'process raft request' (duration: 1.198240171s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:53.079843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:40:51.881036Z","time spent":"1.198706167s","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T10:40:53.079891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.181852ms","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-05-28T10:40:53.07998Z","caller":"traceutil/trace.go:171","msg":"trace[1936366671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:352; }","duration":"100.300579ms","start":"2026-05-28T10:40:52.979668Z","end":"2026-05-28T10:40:53.079969Z","steps":["trace[1936366671] 'agreement among raft nodes before linearized reading' (duration: 100.108555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:53.08011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:40:51.881075Z","time spent":"1.198776354s","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T10:40:53.079807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:40:52.174364Z","time spent":"905.43484ms","remote":"127.0.0.1:47964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T10:40:54.407611Z","caller":"traceutil/trace.go:171","msg":"trace[1242643505] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"447.70479ms","start":"2026-05-28T10:40:53.959878Z","end":"2026-05-28T10:40:54.407583Z","steps":["trace[1242643505] 'process raft request' (duration: 447.551055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:54.407875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:40:53.959849Z","time spent":"447.873625ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T10:40:54.578621Z","caller":"traceutil/trace.go:171","msg":"trace[70743093] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:373; }","duration":"404.536034ms","start":"2026-05-28T10:40:54.17406Z","end":"2026-05-28T10:40:54.578596Z","steps":["trace[70743093] 'read index received' (duration: 233.399353ms)","trace[70743093] 'applied index is now lower than readState.Index' (duration: 171.135521ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:40:54.578638Z","caller":"traceutil/trace.go:171","msg":"trace[182249157] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"334.355636ms","start":"2026-05-28T10:40:54.244253Z","end":"2026-05-28T10:40:54.578609Z","steps":["trace[182249157] 'process raft request' (duration: 334.231579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:54.578772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:40:54.244228Z","time spent":"334.476292ms","remote":"127.0.0.1:48056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T10:40:54.579222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.097907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:40:54.579287Z","caller":"traceutil/trace.go:171","msg":"trace[805853539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:359; }","duration":"405.272383ms","start":"2026-05-28T10:40:54.174004Z","end":"2026-05-28T10:40:54.579277Z","steps":["trace[805853539] 'agreement among raft nodes before linearized reading' (duration: 404.700329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:54.579292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:40:53.994734Z","time spent":"584.554743ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-28T10:40:54.579311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:40:54.173982Z","time spent":"405.321512ms","remote":"127.0.0.1:47964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T10:40:55.065704Z","caller":"traceutil/trace.go:171","msg":"trace[111111824] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"137.409942ms","start":"2026-05-28T10:40:54.928266Z","end":"2026-05-28T10:40:55.065676Z","steps":["trace[111111824] 'process raft request' (duration: 137.220497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:40:55.413749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.955721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:40:55.413874Z","caller":"traceutil/trace.go:171","msg":"trace[1920034808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:367; }","duration":"239.116816ms","start":"2026-05-28T10:40:55.174725Z","end":"2026-05-28T10:40:55.413842Z","steps":["trace[1920034808] 'range keys from in-memory index tree' (duration: 238.885042ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:40:55.662333Z","caller":"traceutil/trace.go:171","msg":"trace[1551764726] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"124.551331ms","start":"2026-05-28T10:40:55.537765Z","end":"2026-05-28T10:40:55.662316Z","steps":["trace[1551764726] 'process raft request' (duration: 124.397645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:00.674086Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219122029685606254,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T10:41:00.908682Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.037570535s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-28T10:41:01.206548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.637637ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-28T10:41:01.206754Z","caller":"traceutil/trace.go:171","msg":"trace[1648817308] linearizableReadLoop","detail":"{readStateIndex:399; appliedIndex:398; }","duration":"1.033607962s","start":"2026-05-28T10:41:00.173133Z","end":"2026-05-28T10:41:01.206741Z","steps":["trace[1648817308] 'read index received' (duration: 735.783169ms)","trace[1648817308] 'applied index is now lower than readState.Index' (duration: 297.823603ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T10:41:01.206954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.708695ms","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-05-28T10:41:01.207038Z","caller":"traceutil/trace.go:171","msg":"trace[114387637] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:380; }","duration":"762.808753ms","start":"2026-05-28T10:41:00.444207Z","end":"2026-05-28T10:41:01.207016Z","steps":["trace[114387637] 'agreement among raft nodes before linearized reading' (duration: 762.650066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:01.207098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:00.444144Z","time spent":"762.94485ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T10:41:01.207223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034089643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:41:01.207263Z","caller":"traceutil/trace.go:171","msg":"trace[1504744861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:380; }","duration":"1.034160781s","start":"2026-05-28T10:41:00.173089Z","end":"2026-05-28T10:41:01.20725Z","steps":["trace[1504744861] 'agreement among raft nodes before linearized reading' (duration: 1.03369826s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:01.207304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:00.173067Z","time spent":"1.03422836s","remote":"127.0.0.1:47964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T10:41:01.36176Z","caller":"traceutil/trace.go:171","msg":"trace[860853422] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"149.664126ms","start":"2026-05-28T10:41:01.212062Z","end":"2026-05-28T10:41:01.361727Z","steps":["trace[860853422] 'process raft request' (duration: 149.486579ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:01.545283Z","caller":"traceutil/trace.go:171","msg":"trace[1426819914] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"333.057702ms","start":"2026-05-28T10:41:01.212199Z","end":"2026-05-28T10:41:01.545257Z","steps":["trace[1426819914] 'process raft request' (duration: 251.391884ms)","trace[1426819914] 'compare' (duration: 81.504211ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T10:41:01.545425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:01.212169Z","time spent":"333.182269ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T10:41:01.545741Z","caller":"traceutil/trace.go:171","msg":"trace[870735913] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"333.317157ms","start":"2026-05-28T10:41:01.212408Z","end":"2026-05-28T10:41:01.545726Z","steps":["trace[870735913] 'process raft request' (duration: 332.812486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:01.545843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:01.212397Z","time spent":"333.395635ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T10:41:01.545931Z","caller":"traceutil/trace.go:171","msg":"trace[2138069573] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"333.309497ms","start":"2026-05-28T10:41:01.212555Z","end":"2026-05-28T10:41:01.545865Z","steps":["trace[2138069573] 'process raft request' (duration: 333.033322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:01.54602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.181994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T10:41:01.54612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:01.212524Z","time spent":"333.500823ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T10:41:01.546187Z","caller":"traceutil/trace.go:171","msg":"trace[1211844744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:384; }","duration":"329.356821ms","start":"2026-05-28T10:41:01.216793Z","end":"2026-05-28T10:41:01.54615Z","steps":["trace[1211844744] 'agreement among raft nodes before linearized reading' (duration: 329.178515ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:01.545806Z","caller":"traceutil/trace.go:171","msg":"trace[508477857] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:399; }","duration":"328.958589ms","start":"2026-05-28T10:41:01.216822Z","end":"2026-05-28T10:41:01.545781Z","steps":["trace[508477857] 'read index received' (duration: 144.779678ms)","trace[508477857] 'applied index is now lower than readState.Index' (duration: 184.177711ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T10:41:01.546585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:01.216784Z","time spent":"329.456749ms","remote":"127.0.0.1:47964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T10:41:05.833195Z","caller":"traceutil/trace.go:171","msg":"trace[1081752715] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"240.647655ms","start":"2026-05-28T10:41:05.59252Z","end":"2026-05-28T10:41:05.833167Z","steps":["trace[1081752715] 'process raft request' (duration: 231.935828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:07.673652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219122029685606294,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T10:41:08.174371Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219122029685606294,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T10:41:08.658033Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.782176324s","expected-duration":"1s"} {"level":"info","ts":"2026-05-28T10:41:08.658705Z","caller":"traceutil/trace.go:171","msg":"trace[2058910698] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:414; }","duration":"1.485111133s","start":"2026-05-28T10:41:07.173572Z","end":"2026-05-28T10:41:08.658683Z","steps":["trace[2058910698] 'read index received' (duration: 1.484879026s)","trace[2058910698] 'applied index is now lower than readState.Index' (duration: 231.127µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:41:08.658822Z","caller":"traceutil/trace.go:171","msg":"trace[1637947092] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"1.783011645s","start":"2026-05-28T10:41:06.875755Z","end":"2026-05-28T10:41:08.658767Z","steps":["trace[1637947092] 'process raft request' (duration: 1.782787698s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:08.65893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.485341351s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T10:41:08.659073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.669736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T10:41:08.659115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:06.875734Z","time spent":"1.783237503s","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T10:41:08.659134Z","caller":"traceutil/trace.go:171","msg":"trace[1069780140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:395; }","duration":"407.781164ms","start":"2026-05-28T10:41:08.251329Z","end":"2026-05-28T10:41:08.65911Z","steps":["trace[1069780140] 'agreement among raft nodes before linearized reading' (duration: 407.724175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:08.659182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:08.251299Z","time spent":"407.876223ms","remote":"127.0.0.1:47964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T10:41:08.65909Z","caller":"traceutil/trace.go:171","msg":"trace[1466412365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:395; }","duration":"1.485551639s","start":"2026-05-28T10:41:07.173524Z","end":"2026-05-28T10:41:08.659076Z","steps":["trace[1466412365] 'agreement among raft nodes before linearized reading' (duration: 1.485352011s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:08.659367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:07.173505Z","time spent":"1.485847465s","remote":"127.0.0.1:47964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T10:41:08.659439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062806535s","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-05-28T10:41:08.659474Z","caller":"traceutil/trace.go:171","msg":"trace[1909363567] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:395; }","duration":"1.062867134s","start":"2026-05-28T10:41:07.596598Z","end":"2026-05-28T10:41:08.659465Z","steps":["trace[1909363567] 'agreement among raft nodes before linearized reading' (duration: 1.062817905s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:08.659505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:07.596586Z","time spent":"1.062912604s","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T10:41:08.659746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067750767s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-28T10:41:08.659399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.795368ms","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-05-28T10:41:08.659831Z","caller":"traceutil/trace.go:171","msg":"trace[1040949984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:395; }","duration":"822.269103ms","start":"2026-05-28T10:41:07.837552Z","end":"2026-05-28T10:41:08.659821Z","steps":["trace[1040949984] 'agreement among raft nodes before linearized reading' (duration: 821.816977ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:08.659835Z","caller":"traceutil/trace.go:171","msg":"trace[332169842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:395; }","duration":"1.067859166s","start":"2026-05-28T10:41:07.591958Z","end":"2026-05-28T10:41:08.659817Z","steps":["trace[332169842] 'agreement among raft nodes before linearized reading' (duration: 1.067636609s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:08.659003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21199809s","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-05-28T10:41:08.659867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:07.837525Z","time spent":"822.332253ms","remote":"127.0.0.1:48248","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-05-28T10:41:08.659887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:07.591915Z","time spent":"1.067960995s","remote":"127.0.0.1:48248","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-05-28T10:41:08.659949Z","caller":"traceutil/trace.go:171","msg":"trace[828721496] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:395; }","duration":"1.21296818s","start":"2026-05-28T10:41:07.446942Z","end":"2026-05-28T10:41:08.65991Z","steps":["trace[828721496] 'agreement among raft nodes before linearized reading' (duration: 1.21199245s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:08.660154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:07.446923Z","time spent":"1.213172717s","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T10:41:10.361941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.293472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T10:41:10.362571Z","caller":"traceutil/trace.go:171","msg":"trace[1709528619] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"1.696886076s","start":"2026-05-28T10:41:08.665659Z","end":"2026-05-28T10:41:10.362545Z","steps":["trace[1709528619] 'process raft request' (duration: 1.696663108s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:10.362676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:08.665634Z","time spent":"1.696995215s","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T10:41:10.362686Z","caller":"traceutil/trace.go:171","msg":"trace[1487102074] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"1.696341512s","start":"2026-05-28T10:41:08.666211Z","end":"2026-05-28T10:41:10.362553Z","steps":["trace[1487102074] 'process raft request' (duration: 1.696185993s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:10.363453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:08.66619Z","time spent":"1.697171586s","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T10:41:10.362806Z","caller":"traceutil/trace.go:171","msg":"trace[1789357837] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"1.69760989s","start":"2026-05-28T10:41:08.665155Z","end":"2026-05-28T10:41:10.362765Z","steps":["trace[1789357837] 'process raft request' (duration: 994.364986ms)","trace[1789357837] 'compare' (duration: 700.321288ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T10:41:10.363539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:08.665131Z","time spent":"1.698370233s","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T10:41:10.362972Z","caller":"traceutil/trace.go:171","msg":"trace[1037487124] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"1.695727599s","start":"2026-05-28T10:41:08.666766Z","end":"2026-05-28T10:41:10.362494Z","steps":["trace[1037487124] 'process raft request' (duration: 1.69567493s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:10.364037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:08.666745Z","time spent":"1.697082487s","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T10:41:10.440839Z","caller":"traceutil/trace.go:171","msg":"trace[1980309532] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"266.643174ms","start":"2026-05-28T10:41:10.174167Z","end":"2026-05-28T10:41:10.44081Z","steps":["trace[1980309532] 'read index received' (duration: 193.402341ms)","trace[1980309532] 'applied index is now lower than readState.Index' (duration: 73.239053ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T10:41:10.440979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.791513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:41:10.441202Z","caller":"traceutil/trace.go:171","msg":"trace[232604733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:399; }","duration":"267.067402ms","start":"2026-05-28T10:41:10.174119Z","end":"2026-05-28T10:41:10.441187Z","steps":["trace[232604733] 'agreement among raft nodes before linearized reading' (duration: 266.795684ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:14.62312Z","caller":"traceutil/trace.go:171","msg":"trace[1504931908] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"157.529004ms","start":"2026-05-28T10:41:14.465556Z","end":"2026-05-28T10:41:14.623085Z","steps":["trace[1504931908] 'read index received' (duration: 157.314725ms)","trace[1504931908] 'applied index is now lower than readState.Index' (duration: 213.159µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:41:14.623185Z","caller":"traceutil/trace.go:171","msg":"trace[1475348097] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"161.017197ms","start":"2026-05-28T10:41:14.462139Z","end":"2026-05-28T10:41:14.623156Z","steps":["trace[1475348097] 'process raft request' (duration: 160.790799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:14.623283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.683873ms","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-05-28T10:41:14.623323Z","caller":"traceutil/trace.go:171","msg":"trace[184441147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:405; }","duration":"157.772553ms","start":"2026-05-28T10:41:14.46554Z","end":"2026-05-28T10:41:14.623313Z","steps":["trace[184441147] 'agreement among raft nodes before linearized reading' (duration: 157.668473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:14.623353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.205859ms","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-05-28T10:41:14.623423Z","caller":"traceutil/trace.go:171","msg":"trace[543175409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:405; }","duration":"156.331348ms","start":"2026-05-28T10:41:14.467074Z","end":"2026-05-28T10:41:14.623405Z","steps":["trace[543175409] 'agreement among raft nodes before linearized reading' (duration: 156.188579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:14.623538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.386579ms","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-05-28T10:41:14.62361Z","caller":"traceutil/trace.go:171","msg":"trace[412330031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:405; }","duration":"156.519078ms","start":"2026-05-28T10:41:14.467074Z","end":"2026-05-28T10:41:14.623593Z","steps":["trace[412330031] 'agreement among raft nodes before linearized reading' (duration: 156.403028ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:17.082824Z","caller":"traceutil/trace.go:171","msg":"trace[906380655] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:432; }","duration":"414.289199ms","start":"2026-05-28T10:41:16.668509Z","end":"2026-05-28T10:41:17.082798Z","steps":["trace[906380655] 'read index received' (duration: 414.005309ms)","trace[906380655] 'applied index is now lower than readState.Index' (duration: 282.62µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:41:17.082901Z","caller":"traceutil/trace.go:171","msg":"trace[1377324585] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"450.657655ms","start":"2026-05-28T10:41:16.632213Z","end":"2026-05-28T10:41:17.08287Z","steps":["trace[1377324585] 'process raft request' (duration: 450.407656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:17.083325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.732459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-28T10:41:17.083119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.552059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-28T10:41:17.0834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.925471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"warn","ts":"2026-05-28T10:41:17.083519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.27818ms","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-05-28T10:41:17.083561Z","caller":"traceutil/trace.go:171","msg":"trace[955632362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:411; }","duration":"414.932418ms","start":"2026-05-28T10:41:16.668545Z","end":"2026-05-28T10:41:17.083478Z","steps":["trace[955632362] 'agreement among raft nodes before linearized reading' (duration: 414.717689ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:17.083593Z","caller":"traceutil/trace.go:171","msg":"trace[556509064] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:411; }","duration":"282.19362ms","start":"2026-05-28T10:41:16.801372Z","end":"2026-05-28T10:41:17.083566Z","steps":["trace[556509064] 'agreement among raft nodes before linearized reading' (duration: 281.913531ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:17.083606Z","caller":"traceutil/trace.go:171","msg":"trace[1625314867] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:411; }","duration":"414.40258ms","start":"2026-05-28T10:41:16.669185Z","end":"2026-05-28T10:41:17.083587Z","steps":["trace[1625314867] 'agreement among raft nodes before linearized reading' (duration: 414.207ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:17.083521Z","caller":"traceutil/trace.go:171","msg":"trace[1971985335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:411; }","duration":"415.032288ms","start":"2026-05-28T10:41:16.668476Z","end":"2026-05-28T10:41:17.083509Z","steps":["trace[1971985335] 'agreement among raft nodes before linearized reading' (duration: 414.484378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:17.083701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:16.669178Z","time spent":"414.46777ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T10:41:17.083722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:16.668459Z","time spent":"415.255488ms","remote":"127.0.0.1:48248","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-05-28T10:41:17.08393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:16.632189Z","time spent":"451.091375ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T10:41:17.083629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:16.66854Z","time spent":"415.074948ms","remote":"127.0.0.1:48248","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-05-28T10:41:19.275422Z","caller":"traceutil/trace.go:171","msg":"trace[1798786765] linearizableReadLoop","detail":"{readStateIndex:471; appliedIndex:469; }","duration":"102.504677ms","start":"2026-05-28T10:41:19.172892Z","end":"2026-05-28T10:41:19.275397Z","steps":["trace[1798786765] 'read index received' (duration: 50.048852ms)","trace[1798786765] 'applied index is now lower than readState.Index' (duration: 52.453965ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:41:19.275446Z","caller":"traceutil/trace.go:171","msg":"trace[1097481555] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"139.017738ms","start":"2026-05-28T10:41:19.136222Z","end":"2026-05-28T10:41:19.27524Z","steps":["trace[1097481555] 'process raft request' (duration: 86.777083ms)","trace[1097481555] 'compare' (duration: 52.090085ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T10:41:19.275582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.673457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:41:19.275614Z","caller":"traceutil/trace.go:171","msg":"trace[2108807085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:448; }","duration":"102.746247ms","start":"2026-05-28T10:41:19.172858Z","end":"2026-05-28T10:41:19.275604Z","steps":["trace[2108807085] 'agreement among raft nodes before linearized reading' (duration: 102.662687ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:19.275647Z","caller":"traceutil/trace.go:171","msg":"trace[244532261] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"139.314638ms","start":"2026-05-28T10:41:19.136299Z","end":"2026-05-28T10:41:19.275614Z","steps":["trace[244532261] 'process raft request' (duration: 138.903738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:32.139901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.139362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-28T10:41:32.140228Z","caller":"traceutil/trace.go:171","msg":"trace[1354670465] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"253.515879ms","start":"2026-05-28T10:41:31.886681Z","end":"2026-05-28T10:41:32.140197Z","steps":["trace[1354670465] 'process raft request' (duration: 130.82581ms)","trace[1354670465] 'compare' (duration: 121.923319ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:41:32.140422Z","caller":"traceutil/trace.go:171","msg":"trace[1262869163] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"245.704538ms","start":"2026-05-28T10:41:31.894698Z","end":"2026-05-28T10:41:32.140403Z","steps":["trace[1262869163] 'process raft request' (duration: 245.432504ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:32.14074Z","caller":"traceutil/trace.go:171","msg":"trace[1448449301] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"207.985146ms","start":"2026-05-28T10:41:31.932744Z","end":"2026-05-28T10:41:32.14073Z","steps":["trace[1448449301] 'process raft request' (duration: 207.521791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:33.02138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.786848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-05-28T10:41:33.021492Z","caller":"traceutil/trace.go:171","msg":"trace[1262826837] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:596; }","duration":"421.92055ms","start":"2026-05-28T10:41:32.59955Z","end":"2026-05-28T10:41:33.021471Z","steps":["trace[1262826837] 'range keys from in-memory index tree' (duration: 421.409483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:33.02154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:32.599495Z","time spent":"422.035031ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":151259,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-05-28T10:41:33.0328Z","caller":"traceutil/trace.go:171","msg":"trace[1045485414] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"404.677985ms","start":"2026-05-28T10:41:32.628069Z","end":"2026-05-28T10:41:33.032747Z","steps":["trace[1045485414] 'read index received' (duration: 402.799932ms)","trace[1045485414] 'applied index is now lower than readState.Index' (duration: 1.876673ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:41:33.03587Z","caller":"traceutil/trace.go:171","msg":"trace[176464604] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"424.023222ms","start":"2026-05-28T10:41:32.611806Z","end":"2026-05-28T10:41:33.03583Z","steps":["trace[176464604] 'process raft request' (duration: 419.55496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:33.035917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.820712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-j7xz9\" ","response":"range_response_count:1 size:4679"} {"level":"info","ts":"2026-05-28T10:41:33.036022Z","caller":"traceutil/trace.go:171","msg":"trace[1296357216] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-j7xz9; range_end:; response_count:1; response_revision:597; }","duration":"407.928463ms","start":"2026-05-28T10:41:32.628033Z","end":"2026-05-28T10:41:33.035961Z","steps":["trace[1296357216] 'agreement among raft nodes before linearized reading' (duration: 407.738171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:33.036042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:32.61179Z","time spent":"424.178174ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T10:41:33.036059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:32.628018Z","time spent":"408.030814ms","remote":"127.0.0.1:48164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4702,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-j7xz9\" "} {"level":"warn","ts":"2026-05-28T10:41:33.036101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.778959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:41:33.036156Z","caller":"traceutil/trace.go:171","msg":"trace[705028202] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:597; }","duration":"284.892551ms","start":"2026-05-28T10:41:32.751247Z","end":"2026-05-28T10:41:33.03614Z","steps":["trace[705028202] 'agreement among raft nodes before linearized reading' (duration: 284.77417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:33.660306Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219122029685606654,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T10:41:34.160559Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219122029685606654,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T10:41:34.329134Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.176690326s","expected-duration":"1s"} {"level":"info","ts":"2026-05-28T10:41:34.329587Z","caller":"traceutil/trace.go:171","msg":"trace[1168431011] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"1.177198552s","start":"2026-05-28T10:41:33.152365Z","end":"2026-05-28T10:41:34.329564Z","steps":["trace[1168431011] 'process raft request' (duration: 1.17706293s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:34.32979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:33.152357Z","time spent":"1.177304173s","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T10:41:34.335931Z","caller":"traceutil/trace.go:171","msg":"trace[1361923971] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:632; }","duration":"1.175959661s","start":"2026-05-28T10:41:33.159946Z","end":"2026-05-28T10:41:34.335906Z","steps":["trace[1361923971] 'read index received' (duration: 1.169399708s)","trace[1361923971] 'applied index is now lower than readState.Index' (duration: 6.559053ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:41:34.33604Z","caller":"traceutil/trace.go:171","msg":"trace[1933506545] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"978.991218ms","start":"2026-05-28T10:41:33.357039Z","end":"2026-05-28T10:41:34.336031Z","steps":["trace[1933506545] 'process raft request' (duration: 978.624833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:34.336139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:33.356993Z","time spent":"979.084359ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T10:41:34.336294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171285048s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:41:34.336301Z","caller":"traceutil/trace.go:171","msg":"trace[760665996] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"583.660132ms","start":"2026-05-28T10:41:33.752573Z","end":"2026-05-28T10:41:34.336233Z","steps":["trace[760665996] 'process raft request' (duration: 583.272628ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:34.336372Z","caller":"traceutil/trace.go:171","msg":"trace[731667037] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:610; }","duration":"1.171410929s","start":"2026-05-28T10:41:33.164923Z","end":"2026-05-28T10:41:34.336334Z","steps":["trace[731667037] 'agreement among raft nodes before linearized reading' (duration: 1.171256577s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:34.336389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.843705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-28T10:41:34.336418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:33.164897Z","time spent":"1.17151086s","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-28T10:41:34.336488Z","caller":"traceutil/trace.go:171","msg":"trace[676950734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:610; }","duration":"956.952457ms","start":"2026-05-28T10:41:33.379482Z","end":"2026-05-28T10:41:34.336435Z","steps":["trace[676950734] 'agreement among raft nodes before linearized reading' (duration: 956.732883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:34.336499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:33.752548Z","time spent":"583.849766ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T10:41:34.33655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:33.379472Z","time spent":"957.050278ms","remote":"127.0.0.1:48248","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-05-28T10:41:34.336656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176697491s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backends.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:18056"} {"level":"info","ts":"2026-05-28T10:41:34.336713Z","caller":"traceutil/trace.go:171","msg":"trace[2120303768] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backends.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:610; }","duration":"1.176808543s","start":"2026-05-28T10:41:33.159888Z","end":"2026-05-28T10:41:34.336696Z","steps":["trace[2120303768] 'agreement among raft nodes before linearized reading' (duration: 1.176682321s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:34.336704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.783627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-28T10:41:34.336752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:33.159872Z","time spent":"1.176869424s","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":18079,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backends.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-05-28T10:41:34.336758Z","caller":"traceutil/trace.go:171","msg":"trace[2094069796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"968.838518ms","start":"2026-05-28T10:41:33.367909Z","end":"2026-05-28T10:41:34.336748Z","steps":["trace[2094069796] 'agreement among raft nodes before linearized reading' (duration: 968.672696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:34.336812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:33.367832Z","time spent":"968.948869ms","remote":"127.0.0.1:48248","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-05-28T10:41:34.336844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141283352s","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-05-28T10:41:34.336876Z","caller":"traceutil/trace.go:171","msg":"trace[4923065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"1.141344102s","start":"2026-05-28T10:41:33.195523Z","end":"2026-05-28T10:41:34.336867Z","steps":["trace[4923065] 'agreement among raft nodes before linearized reading' (duration: 1.14115909s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:34.336911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:33.195513Z","time spent":"1.141393403s","remote":"127.0.0.1:48248","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-05-28T10:41:34.336992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095640918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-28T10:41:34.337009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165449198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:41:34.337031Z","caller":"traceutil/trace.go:171","msg":"trace[279390457] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"1.165481799s","start":"2026-05-28T10:41:33.171542Z","end":"2026-05-28T10:41:34.337023Z","steps":["trace[279390457] 'agreement among raft nodes before linearized reading' (duration: 1.165446119s)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:34.33703Z","caller":"traceutil/trace.go:171","msg":"trace[148278346] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"1.095700958s","start":"2026-05-28T10:41:33.241319Z","end":"2026-05-28T10:41:34.33702Z","steps":["trace[148278346] 'agreement among raft nodes before linearized reading' (duration: 1.095611147s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:34.337049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:33.171531Z","time spent":"1.165514379s","remote":"127.0.0.1:47964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T10:41:34.33706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:33.241305Z","time spent":"1.095748358s","remote":"127.0.0.1:48248","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-05-28T10:41:34.336097Z","caller":"traceutil/trace.go:171","msg":"trace[1664065805] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"384.184918ms","start":"2026-05-28T10:41:33.951896Z","end":"2026-05-28T10:41:34.33608Z","steps":["trace[1664065805] 'process raft request' (duration: 383.981016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:34.337241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.810362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-05-28T10:41:34.337278Z","caller":"traceutil/trace.go:171","msg":"trace[104396866] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:610; }","duration":"186.889403ms","start":"2026-05-28T10:41:34.150378Z","end":"2026-05-28T10:41:34.337267Z","steps":["trace[104396866] 'agreement among raft nodes before linearized reading' (duration: 186.358016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:34.337359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:33.95187Z","time spent":"385.221602ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T10:41:34.336179Z","caller":"traceutil/trace.go:171","msg":"trace[1355729630] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"782.790572ms","start":"2026-05-28T10:41:33.553359Z","end":"2026-05-28T10:41:34.33615Z","steps":["trace[1355729630] 'process raft request' (duration: 782.450198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:34.337892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:33.553332Z","time spent":"784.519334ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T10:41:38.4195Z","caller":"traceutil/trace.go:171","msg":"trace[792978630] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"169.32275ms","start":"2026-05-28T10:41:38.250154Z","end":"2026-05-28T10:41:38.419476Z","steps":["trace[792978630] 'read index received' (duration: 168.464147ms)","trace[792978630] 'applied index is now lower than readState.Index' (duration: 854.912µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T10:41:38.419688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.489582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:41:38.419538Z","caller":"traceutil/trace.go:171","msg":"trace[1583514275] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"813.250758ms","start":"2026-05-28T10:41:37.606254Z","end":"2026-05-28T10:41:38.419505Z","steps":["trace[1583514275] 'process raft request' (duration: 812.627698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:38.41992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:37.606228Z","time spent":"813.585684ms","remote":"127.0.0.1:48078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T10:41:38.420547Z","caller":"traceutil/trace.go:171","msg":"trace[2045855111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"169.675875ms","start":"2026-05-28T10:41:38.250114Z","end":"2026-05-28T10:41:38.41979Z","steps":["trace[2045855111] 'agreement among raft nodes before linearized reading' (duration: 169.485202ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:38.706085Z","caller":"traceutil/trace.go:171","msg":"trace[1050769678] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"277.528944ms","start":"2026-05-28T10:41:38.428525Z","end":"2026-05-28T10:41:38.706054Z","steps":["trace[1050769678] 'process raft request' (duration: 277.279731ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:38.706368Z","caller":"traceutil/trace.go:171","msg":"trace[1224597734] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"277.687037ms","start":"2026-05-28T10:41:38.428657Z","end":"2026-05-28T10:41:38.706344Z","steps":["trace[1224597734] 'process raft request' (duration: 277.331911ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:38.706523Z","caller":"traceutil/trace.go:171","msg":"trace[475566955] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"275.705436ms","start":"2026-05-28T10:41:38.4308Z","end":"2026-05-28T10:41:38.706505Z","steps":["trace[475566955] 'process raft request' (duration: 275.637684ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:38.70652Z","caller":"traceutil/trace.go:171","msg":"trace[163640497] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"276.305386ms","start":"2026-05-28T10:41:38.430194Z","end":"2026-05-28T10:41:38.706499Z","steps":["trace[163640497] 'process raft request' (duration: 276.052731ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:38.707854Z","caller":"traceutil/trace.go:171","msg":"trace[1322962216] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"223.136172ms","start":"2026-05-28T10:41:38.484701Z","end":"2026-05-28T10:41:38.707837Z","steps":["trace[1322962216] 'process raft request' (duration: 223.060811ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:41.045514Z","caller":"traceutil/trace.go:171","msg":"trace[1347918558] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"335.856857ms","start":"2026-05-28T10:41:40.709591Z","end":"2026-05-28T10:41:41.045448Z","steps":["trace[1347918558] 'read index received' (duration: 329.948994ms)","trace[1347918558] 'applied index is now lower than readState.Index' (duration: 5.905093ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:41:41.045647Z","caller":"traceutil/trace.go:171","msg":"trace[1503807413] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"398.578894ms","start":"2026-05-28T10:41:40.647057Z","end":"2026-05-28T10:41:41.045636Z","steps":["trace[1503807413] 'process raft request' (duration: 396.144962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:41.045759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:40.647031Z","time spent":"398.645875ms","remote":"127.0.0.1:48078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T10:41:41.04593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.330016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-28T10:41:41.045966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.207218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-28T10:41:41.04596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.357231ms","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-05-28T10:41:41.046028Z","caller":"traceutil/trace.go:171","msg":"trace[866083534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:665; }","duration":"335.278689ms","start":"2026-05-28T10:41:40.710727Z","end":"2026-05-28T10:41:41.046006Z","steps":["trace[866083534] 'agreement among raft nodes before linearized reading' (duration: 335.043935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:41.046064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:40.710718Z","time spent":"335.33828ms","remote":"127.0.0.1:48248","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-05-28T10:41:41.045986Z","caller":"traceutil/trace.go:171","msg":"trace[465380142] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:665; }","duration":"336.421568ms","start":"2026-05-28T10:41:40.709549Z","end":"2026-05-28T10:41:41.04597Z","steps":["trace[465380142] 'agreement among raft nodes before linearized reading' (duration: 336.208165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:41.046156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:40.709527Z","time spent":"336.602191ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T10:41:41.046645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.198395ms","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-05-28T10:41:41.046714Z","caller":"traceutil/trace.go:171","msg":"trace[1207032210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:665; }","duration":"336.277337ms","start":"2026-05-28T10:41:40.71041Z","end":"2026-05-28T10:41:41.046687Z","steps":["trace[1207032210] 'agreement among raft nodes before linearized reading' (duration: 335.347621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:41.046758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:40.710402Z","time spent":"336.347358ms","remote":"127.0.0.1:48248","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-05-28T10:41:41.047204Z","caller":"traceutil/trace.go:171","msg":"trace[919519923] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:665; }","duration":"336.617472ms","start":"2026-05-28T10:41:40.710572Z","end":"2026-05-28T10:41:41.047189Z","steps":["trace[919519923] 'agreement among raft nodes before linearized reading' (duration: 335.233438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:41.047243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:40.710521Z","time spent":"336.712334ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T10:41:41.263908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.617436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T10:41:41.26409Z","caller":"traceutil/trace.go:171","msg":"trace[400786262] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"213.419451ms","start":"2026-05-28T10:41:41.050647Z","end":"2026-05-28T10:41:41.264067Z","steps":["trace[400786262] 'process raft request' (duration: 79.550441ms)","trace[400786262] 'compare' (duration: 133.428272ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:41:41.264221Z","caller":"traceutil/trace.go:171","msg":"trace[176653141] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"212.640968ms","start":"2026-05-28T10:41:41.051573Z","end":"2026-05-28T10:41:41.264214Z","steps":["trace[176653141] 'process raft request' (duration: 212.430704ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:41.264109Z","caller":"traceutil/trace.go:171","msg":"trace[259437334] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"211.201452ms","start":"2026-05-28T10:41:41.052891Z","end":"2026-05-28T10:41:41.264093Z","steps":["trace[259437334] 'process raft request' (duration: 211.160452ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:41.309997Z","caller":"traceutil/trace.go:171","msg":"trace[583328415] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"136.70476ms","start":"2026-05-28T10:41:41.173268Z","end":"2026-05-28T10:41:41.309973Z","steps":["trace[583328415] 'read index received' (duration: 136.509967ms)","trace[583328415] 'applied index is now lower than readState.Index' (duration: 193.933µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:41:41.310074Z","caller":"traceutil/trace.go:171","msg":"trace[82111897] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"213.466912ms","start":"2026-05-28T10:41:41.096575Z","end":"2026-05-28T10:41:41.310042Z","steps":["trace[82111897] 'process raft request' (duration: 213.269049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:41.310152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.867254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:41:41.310187Z","caller":"traceutil/trace.go:171","msg":"trace[2083734654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:670; }","duration":"136.962195ms","start":"2026-05-28T10:41:41.173214Z","end":"2026-05-28T10:41:41.310177Z","steps":["trace[2083734654] 'agreement among raft nodes before linearized reading' (duration: 136.856883ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:42.639292Z","caller":"traceutil/trace.go:171","msg":"trace[578193345] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"465.627784ms","start":"2026-05-28T10:41:42.173635Z","end":"2026-05-28T10:41:42.639263Z","steps":["trace[578193345] 'read index received' (duration: 464.682507ms)","trace[578193345] 'applied index is now lower than readState.Index' (duration: 943.517µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:41:42.639374Z","caller":"traceutil/trace.go:171","msg":"trace[1889725210] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"892.66787ms","start":"2026-05-28T10:41:41.746686Z","end":"2026-05-28T10:41:42.639354Z","steps":["trace[1889725210] 'process raft request' (duration: 891.713933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:42.639459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.808697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:41:42.63949Z","caller":"traceutil/trace.go:171","msg":"trace[1676894861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"465.889039ms","start":"2026-05-28T10:41:42.173592Z","end":"2026-05-28T10:41:42.639481Z","steps":["trace[1676894861] 'agreement among raft nodes before linearized reading' (duration: 465.816097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:42.639501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:41.746666Z","time spent":"892.757052ms","remote":"127.0.0.1:48164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T10:41:42.63952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:42.173571Z","time spent":"465.941989ms","remote":"127.0.0.1:47964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T10:41:42.639749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.49747ms","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-05-28T10:41:42.639782Z","caller":"traceutil/trace.go:171","msg":"trace[1331885625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:679; }","duration":"171.588022ms","start":"2026-05-28T10:41:42.468183Z","end":"2026-05-28T10:41:42.639771Z","steps":["trace[1331885625] 'agreement among raft nodes before linearized reading' (duration: 171.434999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:45.674518Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219122029685606830,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T10:41:46.092614Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.331660028s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-28T10:41:46.102058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.928217856s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:41:46.102137Z","caller":"traceutil/trace.go:171","msg":"trace[1142175489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"2.928339099s","start":"2026-05-28T10:41:43.173779Z","end":"2026-05-28T10:41:46.102118Z","steps":["trace[1142175489] 'range keys from in-memory index tree' (duration: 2.928112084s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:46.102163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.440840999s","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-05-28T10:41:46.102172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.543176335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-28T10:41:46.102231Z","caller":"traceutil/trace.go:171","msg":"trace[1471386375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:680; }","duration":"1.440969362s","start":"2026-05-28T10:41:44.661245Z","end":"2026-05-28T10:41:46.102214Z","steps":["trace[1471386375] 'range keys from in-memory index tree' (duration: 1.440710027s)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:46.102245Z","caller":"traceutil/trace.go:171","msg":"trace[1018744578] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:680; }","duration":"1.543294026s","start":"2026-05-28T10:41:44.558931Z","end":"2026-05-28T10:41:46.102225Z","steps":["trace[1018744578] 'range keys from in-memory index tree' (duration: 1.54301355s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:46.102239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.833854335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-28T10:41:46.102274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:44.661222Z","time spent":"1.441045713s","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T10:41:46.102288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:44.558911Z","time spent":"1.543367698s","remote":"127.0.0.1:47998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.35\" "} {"level":"info","ts":"2026-05-28T10:41:46.102333Z","caller":"traceutil/trace.go:171","msg":"trace[236084249] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:680; }","duration":"2.833986158s","start":"2026-05-28T10:41:43.268316Z","end":"2026-05-28T10:41:46.102302Z","steps":["trace[236084249] 'range keys from in-memory index tree' (duration: 2.833690622s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:46.102401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:43.268295Z","time spent":"2.83409173s","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T10:41:46.102422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.022172931s","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-05-28T10:41:46.102493Z","caller":"traceutil/trace.go:171","msg":"trace[1358296465] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:680; }","duration":"3.022283453s","start":"2026-05-28T10:41:43.080194Z","end":"2026-05-28T10:41:46.102477Z","steps":["trace[1358296465] 'range keys from in-memory index tree' (duration: 3.021971487s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:46.102177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:43.173764Z","time spent":"2.92840468s","remote":"127.0.0.1:47964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T10:41:46.102591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.832408918s","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-05-28T10:41:46.102659Z","caller":"traceutil/trace.go:171","msg":"trace[258027204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:680; }","duration":"2.83251092s","start":"2026-05-28T10:41:43.270136Z","end":"2026-05-28T10:41:46.102647Z","steps":["trace[258027204] 'range keys from in-memory index tree' (duration: 2.832236445s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:46.102538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:43.080176Z","time spent":"3.022352854s","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T10:41:46.102697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:43.270127Z","time spent":"2.832562462s","remote":"127.0.0.1:48248","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-05-28T10:41:46.102775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.832349817s","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-05-28T10:41:46.102798Z","caller":"traceutil/trace.go:171","msg":"trace[207295113] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"1.341964911s","start":"2026-05-28T10:41:44.760821Z","end":"2026-05-28T10:41:46.102786Z","steps":["trace[207295113] 'process raft request' (duration: 1.332110247s)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:46.102829Z","caller":"traceutil/trace.go:171","msg":"trace[936920045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:680; }","duration":"2.832422229s","start":"2026-05-28T10:41:43.270393Z","end":"2026-05-28T10:41:46.102815Z","steps":["trace[936920045] 'range keys from in-memory index tree' (duration: 2.832255526s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:46.102845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.590695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:41:46.102879Z","caller":"traceutil/trace.go:171","msg":"trace[1297952788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"928.665476ms","start":"2026-05-28T10:41:45.174204Z","end":"2026-05-28T10:41:46.10287Z","steps":["trace[1297952788] 'agreement among raft nodes before linearized reading' (duration: 928.604555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:46.10289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:44.760794Z","time spent":"1.342036003s","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T10:41:46.102917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:45.17418Z","time spent":"928.729798ms","remote":"127.0.0.1:47976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T10:41:46.102877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:43.270383Z","time spent":"2.83248304s","remote":"127.0.0.1:48248","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-05-28T10:41:46.10276Z","caller":"traceutil/trace.go:171","msg":"trace[1038440572] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"928.493963ms","start":"2026-05-28T10:41:45.174248Z","end":"2026-05-28T10:41:46.102742Z","steps":["trace[1038440572] 'read index received' (duration: 918.567298ms)","trace[1038440572] 'applied index is now lower than readState.Index' (duration: 9.925165ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T10:41:46.10308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.566462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:41:46.103118Z","caller":"traceutil/trace.go:171","msg":"trace[1149537631] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:681; }","duration":"841.633464ms","start":"2026-05-28T10:41:45.261473Z","end":"2026-05-28T10:41:46.103107Z","steps":["trace[1149537631] 'agreement among raft nodes before linearized reading' (duration: 841.576873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:41:46.103147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:45.261455Z","time spent":"841.684185ms","remote":"127.0.0.1:48208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-05-28T10:41:56.899355Z","caller":"traceutil/trace.go:171","msg":"trace[543520785] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"127.926766ms","start":"2026-05-28T10:41:56.7714Z","end":"2026-05-28T10:41:56.899327Z","steps":["trace[543520785] 'process raft request' (duration: 127.762852ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:41:58.800281Z","caller":"traceutil/trace.go:171","msg":"trace[1021746981] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"121.390477ms","start":"2026-05-28T10:41:58.678864Z","end":"2026-05-28T10:41:58.800254Z","steps":["trace[1021746981] 'process raft request' (duration: 121.265514ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:42:00.340029Z","caller":"traceutil/trace.go:171","msg":"trace[737636956] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"498.220689ms","start":"2026-05-28T10:41:59.841794Z","end":"2026-05-28T10:42:00.340014Z","steps":["trace[737636956] 'process raft request' (duration: 498.129906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:42:00.340126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:41:59.841778Z","time spent":"498.299011ms","remote":"127.0.0.1:48164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4853,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T10:42:00.344448Z","caller":"traceutil/trace.go:171","msg":"trace[1114352981] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:811; }","duration":"171.094902ms","start":"2026-05-28T10:42:00.173329Z","end":"2026-05-28T10:42:00.344423Z","steps":["trace[1114352981] 'read index received' (duration: 166.579005ms)","trace[1114352981] 'applied index is now lower than readState.Index' (duration: 4.514847ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:42:00.344757Z","caller":"traceutil/trace.go:171","msg":"trace[47110659] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"304.737155ms","start":"2026-05-28T10:42:00.040006Z","end":"2026-05-28T10:42:00.344743Z","steps":["trace[47110659] 'process raft request' (duration: 304.309113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:42:00.344912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:42:00.039981Z","time spent":"304.859348ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T10:42:00.345186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.860304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:42:00.34527Z","caller":"traceutil/trace.go:171","msg":"trace[1146044531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"171.977357ms","start":"2026-05-28T10:42:00.173278Z","end":"2026-05-28T10:42:00.345256Z","steps":["trace[1146044531] 'agreement among raft nodes before linearized reading' (duration: 171.843683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:42:03.675464Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219122029685607153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T10:42:04.175603Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219122029685607153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T10:42:04.676202Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219122029685607153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T10:42:04.679445Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.848823825s","expected-duration":"1s"} {"level":"info","ts":"2026-05-28T10:42:04.679906Z","caller":"traceutil/trace.go:171","msg":"trace[984746382] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"1.505315957s","start":"2026-05-28T10:42:03.174566Z","end":"2026-05-28T10:42:04.679882Z","steps":["trace[984746382] 'read index received' (duration: 1.505180373s)","trace[984746382] 'applied index is now lower than readState.Index' (duration: 134.074µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:42:04.679983Z","caller":"traceutil/trace.go:171","msg":"trace[1677995844] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"1.849418112s","start":"2026-05-28T10:42:02.83051Z","end":"2026-05-28T10:42:04.679928Z","steps":["trace[1677995844] 'process raft request' (duration: 1.849220986s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:42:04.680112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.505532134s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T10:42:04.680156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:42:02.830491Z","time spent":"1.849558836s","remote":"127.0.0.1:48164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T10:42:04.680208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.057048ms","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-05-28T10:42:04.680183Z","caller":"traceutil/trace.go:171","msg":"trace[2003979024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"1.505661157s","start":"2026-05-28T10:42:03.174508Z","end":"2026-05-28T10:42:04.680169Z","steps":["trace[2003979024] 'agreement among raft nodes before linearized reading' (duration: 1.505516553s)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:42:04.680278Z","caller":"traceutil/trace.go:171","msg":"trace[1371963716] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:802; }","duration":"313.12855ms","start":"2026-05-28T10:42:04.367104Z","end":"2026-05-28T10:42:04.680233Z","steps":["trace[1371963716] 'agreement among raft nodes before linearized reading' (duration: 313.013067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:42:04.68034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:42:03.174472Z","time spent":"1.505856153s","remote":"127.0.0.1:47964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T10:42:04.680311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:42:04.367086Z","time spent":"313.217043ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T10:42:04.680404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.665226ms","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-05-28T10:42:04.680467Z","caller":"traceutil/trace.go:171","msg":"trace[208653567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:802; }","duration":"306.746989ms","start":"2026-05-28T10:42:04.373703Z","end":"2026-05-28T10:42:04.68045Z","steps":["trace[208653567] 'agreement among raft nodes before linearized reading' (duration: 306.602894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:42:04.680526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.879261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-28T10:42:04.680558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:42:04.373692Z","time spent":"306.832551ms","remote":"127.0.0.1:48248","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-05-28T10:42:04.680579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.385021ms","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-05-28T10:42:04.680694Z","caller":"traceutil/trace.go:171","msg":"trace[1025732081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"249.505574ms","start":"2026-05-28T10:42:04.431167Z","end":"2026-05-28T10:42:04.680673Z","steps":["trace[1025732081] 'agreement among raft nodes before linearized reading' (duration: 249.046601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:42:04.680763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.793143ms","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-05-28T10:42:04.680596Z","caller":"traceutil/trace.go:171","msg":"trace[1191389532] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:802; }","duration":"121.004605ms","start":"2026-05-28T10:42:04.559574Z","end":"2026-05-28T10:42:04.680579Z","steps":["trace[1191389532] 'agreement among raft nodes before linearized reading' (duration: 120.788538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:42:04.680806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.906186ms","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-05-28T10:42:04.680817Z","caller":"traceutil/trace.go:171","msg":"trace[2106542113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:802; }","duration":"245.878045ms","start":"2026-05-28T10:42:04.434926Z","end":"2026-05-28T10:42:04.680804Z","steps":["trace[2106542113] 'agreement among raft nodes before linearized reading' (duration: 245.748871ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:42:04.680837Z","caller":"traceutil/trace.go:171","msg":"trace[698794839] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:802; }","duration":"245.958827ms","start":"2026-05-28T10:42:04.434869Z","end":"2026-05-28T10:42:04.680827Z","steps":["trace[698794839] 'agreement among raft nodes before linearized reading' (duration: 245.843364ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:42:20.969818Z","caller":"traceutil/trace.go:171","msg":"trace[868185801] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1022; }","duration":"157.862122ms","start":"2026-05-28T10:42:20.811931Z","end":"2026-05-28T10:42:20.969793Z","steps":["trace[868185801] 'read index received' (duration: 13.659239ms)","trace[868185801] 'applied index is now lower than readState.Index' (duration: 144.201823ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:42:20.969937Z","caller":"traceutil/trace.go:171","msg":"trace[1351901543] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"194.17873ms","start":"2026-05-28T10:42:20.775724Z","end":"2026-05-28T10:42:20.969903Z","steps":["trace[1351901543] 'process raft request' (duration: 192.114954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:42:20.970051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.103641ms","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-05-28T10:42:20.970111Z","caller":"traceutil/trace.go:171","msg":"trace[1446204854] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:984; }","duration":"158.207225ms","start":"2026-05-28T10:42:20.81189Z","end":"2026-05-28T10:42:20.970097Z","steps":["trace[1446204854] 'agreement among raft nodes before linearized reading' (duration: 158.038429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:42:20.970369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.646816ms","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-05-28T10:42:20.970407Z","caller":"traceutil/trace.go:171","msg":"trace[1930351622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:984; }","duration":"136.744961ms","start":"2026-05-28T10:42:20.833653Z","end":"2026-05-28T10:42:20.970398Z","steps":["trace[1930351622] 'agreement among raft nodes before linearized reading' (duration: 136.651067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:42:20.970385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.045427ms","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-05-28T10:42:20.970471Z","caller":"traceutil/trace.go:171","msg":"trace[385323789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:984; }","duration":"135.158361ms","start":"2026-05-28T10:42:20.835294Z","end":"2026-05-28T10:42:20.970452Z","steps":["trace[385323789] 'agreement among raft nodes before linearized reading' (duration: 134.936623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:42:20.970496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.789682ms","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-05-28T10:42:20.970689Z","caller":"traceutil/trace.go:171","msg":"trace[854764767] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:984; }","duration":"137.106603ms","start":"2026-05-28T10:42:20.833561Z","end":"2026-05-28T10:42:20.970668Z","steps":["trace[854764767] 'agreement among raft nodes before linearized reading' (duration: 136.655857ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:42:24.745497Z","caller":"traceutil/trace.go:171","msg":"trace[363014011] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"132.120092ms","start":"2026-05-28T10:42:24.613355Z","end":"2026-05-28T10:42:24.745475Z","steps":["trace[363014011] 'process raft request' (duration: 128.832598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:42:27.53572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.402817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T10:42:27.536106Z","caller":"traceutil/trace.go:171","msg":"trace[19054496] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1046; }","duration":"362.027487ms","start":"2026-05-28T10:42:27.174055Z","end":"2026-05-28T10:42:27.536082Z","steps":["trace[19054496] 'read index received' (duration: 3.002486ms)","trace[19054496] 'applied index is now lower than readState.Index' (duration: 359.024181ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:42:27.536246Z","caller":"traceutil/trace.go:171","msg":"trace[1691298538] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"491.560988ms","start":"2026-05-28T10:42:27.044673Z","end":"2026-05-28T10:42:27.536234Z","steps":["trace[1691298538] 'process raft request' (duration: 491.286898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:42:27.536347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:42:27.044648Z","time spent":"491.661382ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T10:42:27.536382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.308378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:42:27.536269Z","caller":"traceutil/trace.go:171","msg":"trace[485417597] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"492.321838ms","start":"2026-05-28T10:42:27.043823Z","end":"2026-05-28T10:42:27.536145Z","steps":["trace[485417597] 'process raft request' (duration: 133.37382ms)","trace[485417597] 'compare' (duration: 358.198809ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:42:27.536442Z","caller":"traceutil/trace.go:171","msg":"trace[804130335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1008; }","duration":"362.408033ms","start":"2026-05-28T10:42:27.17402Z","end":"2026-05-28T10:42:27.536428Z","steps":["trace[804130335] 'agreement among raft nodes before linearized reading' (duration: 362.308949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:42:27.536482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:42:27.174002Z","time spent":"362.472885ms","remote":"127.0.0.1:47964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T10:42:27.536548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:42:27.043809Z","time spent":"492.655721ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T10:42:27.536594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.954636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:42:27.536712Z","caller":"traceutil/trace.go:171","msg":"trace[1024144743] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1008; }","duration":"131.003428ms","start":"2026-05-28T10:42:27.405621Z","end":"2026-05-28T10:42:27.536624Z","steps":["trace[1024144743] 'agreement among raft nodes before linearized reading' (duration: 130.933906ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:42:27.536726Z","caller":"traceutil/trace.go:171","msg":"trace[1450735917] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"491.907691ms","start":"2026-05-28T10:42:27.044798Z","end":"2026-05-28T10:42:27.536706Z","steps":["trace[1450735917] 'process raft request' (duration: 491.244076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:42:27.536785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:42:27.044778Z","time spent":"491.976944ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T10:42:29.749113Z","caller":"traceutil/trace.go:171","msg":"trace[2146809974] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1053; }","duration":"152.446427ms","start":"2026-05-28T10:42:29.596649Z","end":"2026-05-28T10:42:29.749096Z","steps":["trace[2146809974] 'read index received' (duration: 106.972529ms)","trace[2146809974] 'applied index is now lower than readState.Index' (duration: 45.473018ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T10:42:29.749249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.578922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T10:42:29.74923Z","caller":"traceutil/trace.go:171","msg":"trace[12278636] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"205.1243ms","start":"2026-05-28T10:42:29.544076Z","end":"2026-05-28T10:42:29.749201Z","steps":["trace[12278636] 'process raft request' (duration: 204.988425ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:42:29.74927Z","caller":"traceutil/trace.go:171","msg":"trace[1034562993] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1014; }","duration":"152.648435ms","start":"2026-05-28T10:42:29.596614Z","end":"2026-05-28T10:42:29.749262Z","steps":["trace[1034562993] 'agreement among raft nodes before linearized reading' (duration: 152.556101ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:42:29.749713Z","caller":"traceutil/trace.go:171","msg":"trace[434246823] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"206.050096ms","start":"2026-05-28T10:42:29.543629Z","end":"2026-05-28T10:42:29.749679Z","steps":["trace[434246823] 'process raft request' (duration: 160.083919ms)","trace[434246823] 'compare' (duration: 45.26331ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:42:31.829901Z","caller":"traceutil/trace.go:171","msg":"trace[1578009863] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"247.226415ms","start":"2026-05-28T10:42:31.582653Z","end":"2026-05-28T10:42:31.82988Z","steps":["trace[1578009863] 'process raft request' (duration: 247.08768ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:42:34.21485Z","caller":"traceutil/trace.go:171","msg":"trace[1638414304] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1066; }","duration":"339.671815ms","start":"2026-05-28T10:42:33.875161Z","end":"2026-05-28T10:42:34.214833Z","steps":["trace[1638414304] 'read index received' (duration: 339.462267ms)","trace[1638414304] 'applied index is now lower than readState.Index' (duration: 208.828µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:42:34.214998Z","caller":"traceutil/trace.go:171","msg":"trace[1098035963] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"377.35412ms","start":"2026-05-28T10:42:33.837602Z","end":"2026-05-28T10:42:34.214956Z","steps":["trace[1098035963] 'process raft request' (duration: 377.10618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:42:34.215175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:42:33.83758Z","time spent":"377.502906ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T10:42:34.215037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.860603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-28T10:42:34.215235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.693814ms","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-05-28T10:42:34.215262Z","caller":"traceutil/trace.go:171","msg":"trace[529190155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1025; }","duration":"340.115043ms","start":"2026-05-28T10:42:33.875114Z","end":"2026-05-28T10:42:34.215229Z","steps":["trace[529190155] 'agreement among raft nodes before linearized reading' (duration: 339.820081ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:42:34.215324Z","caller":"traceutil/trace.go:171","msg":"trace[1828813405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1025; }","duration":"335.824839ms","start":"2026-05-28T10:42:33.879479Z","end":"2026-05-28T10:42:34.215304Z","steps":["trace[1828813405] 'agreement among raft nodes before linearized reading' (duration: 335.560549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:42:34.215351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:42:33.875096Z","time spent":"340.228418ms","remote":"127.0.0.1:48248","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-05-28T10:42:34.215377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:42:33.879469Z","time spent":"335.899622ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-28T10:42:34.714091Z","caller":"traceutil/trace.go:171","msg":"trace[891834641] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1069; }","duration":"115.549479ms","start":"2026-05-28T10:42:34.598518Z","end":"2026-05-28T10:42:34.714067Z","steps":["trace[891834641] 'read index received' (duration: 26.831µs)","trace[891834641] 'applied index is now lower than readState.Index' (duration: 115.521388ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T10:42:34.714264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.714155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T10:42:34.714316Z","caller":"traceutil/trace.go:171","msg":"trace[1153398473] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1027; }","duration":"115.788298ms","start":"2026-05-28T10:42:34.598513Z","end":"2026-05-28T10:42:34.714302Z","steps":["trace[1153398473] 'agreement among raft nodes before linearized reading' (duration: 115.649273ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:43:01.663152Z","caller":"traceutil/trace.go:171","msg":"trace[1385669641] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"108.065908ms","start":"2026-05-28T10:43:01.555059Z","end":"2026-05-28T10:43:01.663125Z","steps":["trace[1385669641] 'process raft request' (duration: 47.785098ms)","trace[1385669641] 'compare' (duration: 60.167515ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:43:03.084144Z","caller":"traceutil/trace.go:171","msg":"trace[30272828] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"110.829173ms","start":"2026-05-28T10:43:02.973287Z","end":"2026-05-28T10:43:03.084117Z","steps":["trace[30272828] 'process raft request' (duration: 110.677036ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:43:05.744745Z","caller":"traceutil/trace.go:171","msg":"trace[564514403] linearizableReadLoop","detail":"{readStateIndex:1184; appliedIndex:1183; }","duration":"104.440642ms","start":"2026-05-28T10:43:05.640277Z","end":"2026-05-28T10:43:05.744717Z","steps":["trace[564514403] 'read index received' (duration: 104.301895ms)","trace[564514403] 'applied index is now lower than readState.Index' (duration: 137.526µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:43:05.744788Z","caller":"traceutil/trace.go:171","msg":"trace[1471973156] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"197.186311ms","start":"2026-05-28T10:43:05.547568Z","end":"2026-05-28T10:43:05.744754Z","steps":["trace[1471973156] 'process raft request' (duration: 196.977001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:43:05.744989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.685213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:43:05.745063Z","caller":"traceutil/trace.go:171","msg":"trace[873354251] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1134; }","duration":"104.816399ms","start":"2026-05-28T10:43:05.640231Z","end":"2026-05-28T10:43:05.745047Z","steps":["trace[873354251] 'agreement among raft nodes before linearized reading' (duration: 104.62787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:43:07.390873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.631672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:43:07.391445Z","caller":"traceutil/trace.go:171","msg":"trace[1381550858] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1139; }","duration":"150.279713ms","start":"2026-05-28T10:43:07.241143Z","end":"2026-05-28T10:43:07.391423Z","steps":["trace[1381550858] 'count revisions from in-memory index tree' (duration: 149.541018ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:43:15.36306Z","caller":"traceutil/trace.go:171","msg":"trace[1865416235] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"117.977971ms","start":"2026-05-28T10:43:15.245063Z","end":"2026-05-28T10:43:15.363041Z","steps":["trace[1865416235] 'process raft request' (duration: 117.859856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:43:24.958703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.834551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T10:43:24.958818Z","caller":"traceutil/trace.go:171","msg":"trace[505857232] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"271.769969ms","start":"2026-05-28T10:43:24.687021Z","end":"2026-05-28T10:43:24.95879Z","steps":["trace[505857232] 'process raft request' (duration: 143.76038ms)","trace[505857232] 'compare' (duration: 127.600888ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:43:24.959094Z","caller":"traceutil/trace.go:171","msg":"trace[24295650] linearizableReadLoop","detail":"{readStateIndex:1249; appliedIndex:1248; }","duration":"247.283995ms","start":"2026-05-28T10:43:24.711506Z","end":"2026-05-28T10:43:24.95879Z","steps":["trace[24295650] 'read index received' (duration: 119.211662ms)","trace[24295650] 'applied index is now lower than readState.Index' (duration: 128.070243ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T10:43:24.95919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.666584ms","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-05-28T10:43:24.959242Z","caller":"traceutil/trace.go:171","msg":"trace[614217615] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1195; }","duration":"247.761459ms","start":"2026-05-28T10:43:24.711467Z","end":"2026-05-28T10:43:24.959229Z","steps":["trace[614217615] 'agreement among raft nodes before linearized reading' (duration: 247.478155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:43:24.959395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.657485ms","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-05-28T10:43:24.959436Z","caller":"traceutil/trace.go:171","msg":"trace[1842235427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1195; }","duration":"131.736819ms","start":"2026-05-28T10:43:24.827688Z","end":"2026-05-28T10:43:24.959425Z","steps":["trace[1842235427] 'agreement among raft nodes before linearized reading' (duration: 131.507128ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:43:26.076424Z","caller":"traceutil/trace.go:171","msg":"trace[232379444] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"105.063086ms","start":"2026-05-28T10:43:25.971332Z","end":"2026-05-28T10:43:26.076395Z","steps":["trace[232379444] 'process raft request' (duration: 103.210061ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:43:27.85469Z","caller":"traceutil/trace.go:171","msg":"trace[1118098471] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1258; }","duration":"309.61468ms","start":"2026-05-28T10:43:27.545051Z","end":"2026-05-28T10:43:27.854666Z","steps":["trace[1118098471] 'read index received' (duration: 309.492684ms)","trace[1118098471] 'applied index is now lower than readState.Index' (duration: 121.216µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:43:27.854811Z","caller":"traceutil/trace.go:171","msg":"trace[1352453122] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"317.932467ms","start":"2026-05-28T10:43:27.536858Z","end":"2026-05-28T10:43:27.854791Z","steps":["trace[1352453122] 'process raft request' (duration: 317.675654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:43:27.854886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.810931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T10:43:27.854925Z","caller":"traceutil/trace.go:171","msg":"trace[611037356] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1205; }","duration":"309.890235ms","start":"2026-05-28T10:43:27.545024Z","end":"2026-05-28T10:43:27.854914Z","steps":["trace[611037356] 'agreement among raft nodes before linearized reading' (duration: 309.748148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:43:27.854948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:43:27.536832Z","time spent":"318.022341ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T10:43:27.854962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:43:27.545012Z","time spent":"309.939777ms","remote":"127.0.0.1:59720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-28T10:43:50.180057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.22005ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-28T10:43:50.180457Z","caller":"traceutil/trace.go:171","msg":"trace[265636351] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"227.778897ms","start":"2026-05-28T10:43:49.952651Z","end":"2026-05-28T10:43:50.18043Z","steps":["trace[265636351] 'process raft request' (duration: 227.510302ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:43:55.447292Z","caller":"traceutil/trace.go:171","msg":"trace[1021106387] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"126.518606ms","start":"2026-05-28T10:43:55.32075Z","end":"2026-05-28T10:43:55.447269Z","steps":["trace[1021106387] 'process raft request' (duration: 126.358887ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:43:55.575555Z","caller":"traceutil/trace.go:171","msg":"trace[1609585219] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"119.790001ms","start":"2026-05-28T10:43:55.455739Z","end":"2026-05-28T10:43:55.575529Z","steps":["trace[1609585219] 'process raft request' (duration: 78.392535ms)","trace[1609585219] 'compare' (duration: 41.29218ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:43:56.466957Z","caller":"traceutil/trace.go:171","msg":"trace[414918209] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"128.821459ms","start":"2026-05-28T10:43:56.338117Z","end":"2026-05-28T10:43:56.466939Z","steps":["trace[414918209] 'process raft request' (duration: 128.785687ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:43:56.467189Z","caller":"traceutil/trace.go:171","msg":"trace[1908419130] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"129.446512ms","start":"2026-05-28T10:43:56.337705Z","end":"2026-05-28T10:43:56.467152Z","steps":["trace[1908419130] 'process raft request' (duration: 37.53768ms)","trace[1908419130] 'compare' (duration: 91.578095ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:43:57.854384Z","caller":"traceutil/trace.go:171","msg":"trace[85489579] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1400; }","duration":"366.940291ms","start":"2026-05-28T10:43:57.487419Z","end":"2026-05-28T10:43:57.854359Z","steps":["trace[85489579] 'read index received' (duration: 366.721529ms)","trace[85489579] 'applied index is now lower than readState.Index' (duration: 217.482µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T10:43:57.854433Z","caller":"traceutil/trace.go:171","msg":"trace[1278559806] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"380.935889ms","start":"2026-05-28T10:43:57.473465Z","end":"2026-05-28T10:43:57.854401Z","steps":["trace[1278559806] 'process raft request' (duration: 380.680425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:43:57.854606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:43:57.473446Z","time spent":"381.059076ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T10:43:57.854613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.165563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-28T10:43:57.854693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.442858ms","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-05-28T10:43:57.854734Z","caller":"traceutil/trace.go:171","msg":"trace[1307786218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1339; }","duration":"367.31023ms","start":"2026-05-28T10:43:57.487407Z","end":"2026-05-28T10:43:57.854717Z","steps":["trace[1307786218] 'agreement among raft nodes before linearized reading' (duration: 367.081958ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:43:57.85476Z","caller":"traceutil/trace.go:171","msg":"trace[1824531853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1339; }","duration":"274.537053ms","start":"2026-05-28T10:43:57.580206Z","end":"2026-05-28T10:43:57.854743Z","steps":["trace[1824531853] 'agreement among raft nodes before linearized reading' (duration: 274.361084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:43:57.854856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:43:57.487402Z","time spent":"367.369263ms","remote":"127.0.0.1:48248","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-05-28T10:43:58.452325Z","caller":"traceutil/trace.go:171","msg":"trace[1620437945] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"108.068093ms","start":"2026-05-28T10:43:58.344232Z","end":"2026-05-28T10:43:58.4523Z","steps":["trace[1620437945] 'process raft request' (duration: 107.931796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:43:58.864354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.473462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T10:43:58.864485Z","caller":"traceutil/trace.go:171","msg":"trace[212825421] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"389.251818ms","start":"2026-05-28T10:43:58.475213Z","end":"2026-05-28T10:43:58.864465Z","steps":["trace[212825421] 'process raft request' (duration: 229.582055ms)","trace[212825421] 'compare' (duration: 159.365746ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T10:43:58.864544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:43:58.475203Z","time spent":"389.314102ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T10:43:59.130098Z","caller":"traceutil/trace.go:171","msg":"trace[1828040419] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"654.73309ms","start":"2026-05-28T10:43:58.475346Z","end":"2026-05-28T10:43:59.130079Z","steps":["trace[1828040419] 'process raft request' (duration: 645.617205ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T10:43:59.1302Z","caller":"traceutil/trace.go:171","msg":"trace[1081958382] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"514.731874ms","start":"2026-05-28T10:43:58.615456Z","end":"2026-05-28T10:43:59.130188Z","steps":["trace[1081958382] 'process raft request' (duration: 514.554575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T10:43:59.130251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:43:58.615435Z","time spent":"514.791497ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T10:43:59.130245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T10:43:58.475324Z","time spent":"654.825565ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}