{"level":"warn","ts":"2026-04-30T15:37:21.664319Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-30T15:37:21.664404Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.138:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.138:2380","--initial-cluster=instance=https://199.19.213.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.138:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-30T15:37:21.664584Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-30T15:37:21.664602Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.138:2380"]} {"level":"info","ts":"2026-04-30T15:37:21.664683Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-30T15:37:21.665437Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.138:2379"]} {"level":"info","ts":"2026-04-30T15:37:21.665606Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.138:2380"],"listen-peer-urls":["https://199.19.213.138:2380"],"advertise-client-urls":["https://199.19.213.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.138:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-30T15:37:21.6696Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.725621ms"} {"level":"info","ts":"2026-04-30T15:37:21.676316Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2062be27cfb43adb","cluster-id":"f353072e75ed4d57"} {"level":"info","ts":"2026-04-30T15:37:21.676551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb switched to configuration voters=()"} {"level":"info","ts":"2026-04-30T15:37:21.676584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became follower at term 0"} {"level":"info","ts":"2026-04-30T15:37:21.67667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2062be27cfb43adb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-30T15:37:21.676776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became follower at term 1"} {"level":"info","ts":"2026-04-30T15:37:21.676914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb switched to configuration voters=(2333636635129035483)"} {"level":"warn","ts":"2026-04-30T15:37:21.682772Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-30T15:37:21.69019Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-30T15:37:21.69577Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-30T15:37:21.70254Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2062be27cfb43adb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-30T15:37:21.702722Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2062be27cfb43adb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-30T15:37:21.702834Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-30T15:37:21.702931Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-30T15:37:21.70295Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-30T15:37:21.705947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb switched to configuration voters=(2333636635129035483)"} {"level":"info","ts":"2026-04-30T15:37:21.706161Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f353072e75ed4d57","local-member-id":"2062be27cfb43adb","added-peer-id":"2062be27cfb43adb","added-peer-peer-urls":["https://199.19.213.138:2380"]} {"level":"info","ts":"2026-04-30T15:37:21.709634Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-30T15:37:21.709816Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.138:2380"} {"level":"info","ts":"2026-04-30T15:37:21.709842Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.138:2380"} {"level":"info","ts":"2026-04-30T15:37:21.70998Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2062be27cfb43adb","initial-advertise-peer-urls":["https://199.19.213.138:2380"],"listen-peer-urls":["https://199.19.213.138:2380"],"advertise-client-urls":["https://199.19.213.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-30T15:37:21.710039Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-30T15:37:21.878017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb is starting a new election at term 1"} {"level":"info","ts":"2026-04-30T15:37:21.878074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-30T15:37:21.878109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb received MsgPreVoteResp from 2062be27cfb43adb at term 1"} {"level":"info","ts":"2026-04-30T15:37:21.878127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became candidate at term 2"} {"level":"info","ts":"2026-04-30T15:37:21.878142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb received MsgVoteResp from 2062be27cfb43adb at term 2"} {"level":"info","ts":"2026-04-30T15:37:21.878161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became leader at term 2"} {"level":"info","ts":"2026-04-30T15:37:21.878177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2062be27cfb43adb elected leader 2062be27cfb43adb at term 2"} {"level":"info","ts":"2026-04-30T15:37:21.880004Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-30T15:37:21.881067Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-30T15:37:21.881097Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-30T15:37:21.881752Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-30T15:37:21.881792Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-30T15:37:21.881783Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2062be27cfb43adb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.138:2379]}","request-path":"/0/members/2062be27cfb43adb/attributes","cluster-id":"f353072e75ed4d57","publish-timeout":"7s"} {"level":"info","ts":"2026-04-30T15:37:21.882609Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f353072e75ed4d57","local-member-id":"2062be27cfb43adb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-30T15:37:21.882881Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-30T15:37:21.883046Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-30T15:37:21.884227Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.138:2379"} {"level":"info","ts":"2026-04-30T15:37:21.885854Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-30T15:37:24.869051Z","caller":"traceutil/trace.go:171","msg":"trace[1473317238] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"262.636907ms","start":"2026-04-30T15:37:24.606381Z","end":"2026-04-30T15:37:24.869018Z","steps":["trace[1473317238] 'process raft request' (duration: 251.190556ms)","trace[1473317238] 'compare' (duration: 11.29567ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T15:37:35.053228Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4241157055363813485,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T15:37:35.553691Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4241157055363813485,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-30T15:37:35.568019Z","caller":"traceutil/trace.go:171","msg":"trace[1705346768] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:406; }","duration":"1.015395328s","start":"2026-04-30T15:37:34.552599Z","end":"2026-04-30T15:37:35.567994Z","steps":["trace[1705346768] 'read index received' (duration: 1.015210657s)","trace[1705346768] 'applied index is now lower than readState.Index' (duration: 183.921µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T15:37:35.568123Z","caller":"traceutil/trace.go:171","msg":"trace[1228923589] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"1.079243404s","start":"2026-04-30T15:37:34.488849Z","end":"2026-04-30T15:37:35.568093Z","steps":["trace[1228923589] 'process raft request' (duration: 1.079025592s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:37:35.568183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.538286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-04-30T15:37:35.568276Z","caller":"traceutil/trace.go:171","msg":"trace[1293038114] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:398; }","duration":"767.682056ms","start":"2026-04-30T15:37:34.800582Z","end":"2026-04-30T15:37:35.568264Z","steps":["trace[1293038114] 'agreement among raft nodes before linearized reading' (duration: 767.543265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:37:35.568256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01564904s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-30T15:37:35.568317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:37:34.800561Z","time spent":"767.747387ms","remote":"127.0.0.1:45884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-04-30T15:37:35.568369Z","caller":"traceutil/trace.go:171","msg":"trace[1729350860] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:398; }","duration":"1.015793752s","start":"2026-04-30T15:37:34.552531Z","end":"2026-04-30T15:37:35.568325Z","steps":["trace[1729350860] 'agreement among raft nodes before linearized reading' (duration: 1.01559165s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:37:35.568352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.709723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.138\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-30T15:37:35.568421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:37:34.552512Z","time spent":"1.015899932s","remote":"127.0.0.1:45970","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":"info","ts":"2026-04-30T15:37:35.568476Z","caller":"traceutil/trace.go:171","msg":"trace[1510987699] range","detail":"{range_begin:/registry/masterleases/199.19.213.138; range_end:; response_count:1; response_revision:398; }","duration":"367.836883ms","start":"2026-04-30T15:37:35.200575Z","end":"2026-04-30T15:37:35.568412Z","steps":["trace[1510987699] 'agreement among raft nodes before linearized reading' (duration: 367.707722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:37:35.568562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:37:34.488829Z","time spent":"1.079356855s","remote":"127.0.0.1:45970","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-04-30T15:37:35.56861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:37:35.200557Z","time spent":"368.036885ms","remote":"127.0.0.1:45706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.138\" "} {"level":"warn","ts":"2026-04-30T15:37:37.04118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.900139ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T15:37:37.041289Z","caller":"traceutil/trace.go:171","msg":"trace[21333858] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:398; }","duration":"442.02573ms","start":"2026-04-30T15:37:36.59925Z","end":"2026-04-30T15:37:37.041275Z","steps":["trace[21333858] 'range keys from in-memory index tree' (duration: 441.885459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:37:37.041674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.22553014s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-30T15:37:37.041724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:37:35.570657Z","time spent":"1.471063718s","remote":"127.0.0.1:45706","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-30T15:37:37.869534Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.053564106s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-30T15:37:38.836879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.020534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T15:37:38.83741Z","caller":"traceutil/trace.go:171","msg":"trace[941917829] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:407; }","duration":"3.054190184s","start":"2026-04-30T15:37:35.783197Z","end":"2026-04-30T15:37:38.837387Z","steps":["trace[941917829] 'read index received' (duration: 32.847349ms)","trace[941917829] 'applied index is now lower than readState.Index' (duration: 3.021341634s)"],"step_count":2} {"level":"info","ts":"2026-04-30T15:37:38.837469Z","caller":"traceutil/trace.go:171","msg":"trace[1926676459] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"3.262969854s","start":"2026-04-30T15:37:35.574465Z","end":"2026-04-30T15:37:38.837435Z","steps":["trace[1926676459] 'process raft request' (duration: 3.262582471s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:37:38.837835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:37:35.574447Z","time spent":"3.263099985s","remote":"127.0.0.1:45970","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-04-30T15:37:38.837896Z","caller":"traceutil/trace.go:171","msg":"trace[1150737150] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"3.263087994s","start":"2026-04-30T15:37:35.57479Z","end":"2026-04-30T15:37:38.837878Z","steps":["trace[1150737150] 'process raft request' (duration: 3.26251791s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:37:38.838011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:37:35.574777Z","time spent":"3.263179115s","remote":"127.0.0.1:45884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T15:37:38.838306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.055110471s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-30T15:37:38.838377Z","caller":"traceutil/trace.go:171","msg":"trace[629061378] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:401; }","duration":"3.055204252s","start":"2026-04-30T15:37:35.783153Z","end":"2026-04-30T15:37:38.838357Z","steps":["trace[629061378] 'agreement among raft nodes before linearized reading' (duration: 3.055096712s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:37:38.838444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:37:35.783138Z","time spent":"3.055280433s","remote":"127.0.0.1:45970","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-04-30T15:37:38.83804Z","caller":"traceutil/trace.go:171","msg":"trace[1440790106] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"3.264887297s","start":"2026-04-30T15:37:35.57314Z","end":"2026-04-30T15:37:38.838028Z","steps":["trace[1440790106] 'process raft request' (duration: 2.296599874s)","trace[1440790106] 'compare' (duration: 966.621771ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T15:37:38.838786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:37:35.573092Z","time spent":"3.265452313s","remote":"127.0.0.1:45970","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-04-30T15:37:38.888735Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.019026093s","expected-duration":"1s"} {"level":"info","ts":"2026-04-30T15:37:38.889165Z","caller":"traceutil/trace.go:171","msg":"trace[1421989237] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"1.846488313s","start":"2026-04-30T15:37:37.042657Z","end":"2026-04-30T15:37:38.889146Z","steps":["trace[1421989237] 'process raft request' (duration: 1.846315592s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:37:38.889301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:37:37.042603Z","time spent":"1.846626534s","remote":"127.0.0.1:45706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T15:37:38.889307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.30777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T15:37:38.889373Z","caller":"traceutil/trace.go:171","msg":"trace[1256208815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:403; }","duration":"532.448931ms","start":"2026-04-30T15:37:38.356904Z","end":"2026-04-30T15:37:38.889353Z","steps":["trace[1256208815] 'agreement among raft nodes before linearized reading' (duration: 532.273179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:37:38.889437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:37:38.356885Z","time spent":"532.539752ms","remote":"127.0.0.1:45686","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-04-30T15:37:38.889573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316617216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-30T15:37:38.889646Z","caller":"traceutil/trace.go:171","msg":"trace[157364480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:403; }","duration":"1.316773367s","start":"2026-04-30T15:37:37.572855Z","end":"2026-04-30T15:37:38.889628Z","steps":["trace[157364480] 'agreement among raft nodes before linearized reading' (duration: 1.316491135s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:37:38.889797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:37:37.572835Z","time spent":"1.316847578s","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-30T15:37:38.889831Z","caller":"traceutil/trace.go:171","msg":"trace[47297737] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"1.607537169s","start":"2026-04-30T15:37:37.282233Z","end":"2026-04-30T15:37:38.88977Z","steps":["trace[47297737] 'process raft request' (duration: 1.606841915s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:37:38.89019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:37:37.282213Z","time spent":"1.607828022s","remote":"127.0.0.1:45970","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-04-30T15:37:39.032064Z","caller":"traceutil/trace.go:171","msg":"trace[63747000] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:413; }","duration":"142.915417ms","start":"2026-04-30T15:37:38.889103Z","end":"2026-04-30T15:37:39.032018Z","steps":["trace[63747000] 'read index received' (duration: 129.782029ms)","trace[63747000] 'applied index is now lower than readState.Index' (duration: 13.132028ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T15:37:39.032134Z","caller":"traceutil/trace.go:171","msg":"trace[1125897359] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"188.070835ms","start":"2026-04-30T15:37:38.844039Z","end":"2026-04-30T15:37:39.03211Z","steps":["trace[1125897359] 'process raft request' (duration: 174.830876ms)","trace[1125897359] 'compare' (duration: 13.020897ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T15:37:39.032279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.904012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-04-30T15:37:39.032342Z","caller":"traceutil/trace.go:171","msg":"trace[2127449497] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:404; }","duration":"189.007223ms","start":"2026-04-30T15:37:38.84332Z","end":"2026-04-30T15:37:39.032327Z","steps":["trace[2127449497] 'agreement among raft nodes before linearized reading' (duration: 188.857541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:37:39.033823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.571879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T15:37:39.033805Z","caller":"traceutil/trace.go:171","msg":"trace[1119244360] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"138.660776ms","start":"2026-04-30T15:37:38.895106Z","end":"2026-04-30T15:37:39.033767Z","steps":["trace[1119244360] 'process raft request' (duration: 138.527115ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:37:39.033894Z","caller":"traceutil/trace.go:171","msg":"trace[1846191406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:405; }","duration":"136.659971ms","start":"2026-04-30T15:37:38.897215Z","end":"2026-04-30T15:37:39.033875Z","steps":["trace[1846191406] 'agreement among raft nodes before linearized reading' (duration: 136.56137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:37:39.033808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.801399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-30T15:37:39.034043Z","caller":"traceutil/trace.go:171","msg":"trace[260041513] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:405; }","duration":"142.082931ms","start":"2026-04-30T15:37:38.891949Z","end":"2026-04-30T15:37:39.034032Z","steps":["trace[260041513] 'agreement among raft nodes before linearized reading' (duration: 141.781849ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:37:40.98829Z","caller":"traceutil/trace.go:171","msg":"trace[1691096883] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"140.13664ms","start":"2026-04-30T15:37:40.848131Z","end":"2026-04-30T15:37:40.988267Z","steps":["trace[1691096883] 'process raft request' (duration: 113.036231ms)","trace[1691096883] 'compare' (duration: 26.990693ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T15:38:01.500802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.464963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-30T15:38:01.501181Z","caller":"traceutil/trace.go:171","msg":"trace[1056751207] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"629.096602ms","start":"2026-04-30T15:38:00.872062Z","end":"2026-04-30T15:38:01.501158Z","steps":["trace[1056751207] 'process raft request' (duration: 629.015899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:01.501359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:00.872036Z","time spent":"629.28406ms","remote":"127.0.0.1:45728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T15:38:01.501179Z","caller":"traceutil/trace.go:171","msg":"trace[689273246] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"629.232868ms","start":"2026-04-30T15:38:00.871918Z","end":"2026-04-30T15:38:01.501151Z","steps":["trace[689273246] 'process raft request' (duration: 386.349616ms)","trace[689273246] 'compare' (duration: 242.330968ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T15:38:01.501559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:00.871889Z","time spent":"629.639736ms","remote":"127.0.0.1:45728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T15:38:01.508282Z","caller":"traceutil/trace.go:171","msg":"trace[948579736] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"627.863814ms","start":"2026-04-30T15:38:00.880346Z","end":"2026-04-30T15:38:01.50821Z","steps":["trace[948579736] 'process raft request' (duration: 627.710907ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:01.508626Z","caller":"traceutil/trace.go:171","msg":"trace[1605372969] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"628.370136ms","start":"2026-04-30T15:38:00.880232Z","end":"2026-04-30T15:38:01.508603Z","steps":["trace[1605372969] 'process raft request' (duration: 627.742359ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:01.508711Z","caller":"traceutil/trace.go:171","msg":"trace[586022143] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"625.432485ms","start":"2026-04-30T15:38:00.883254Z","end":"2026-04-30T15:38:01.508686Z","steps":["trace[586022143] 'process raft request' (duration: 624.912282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:01.508748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:00.880214Z","time spent":"628.475611ms","remote":"127.0.0.1:45728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-30T15:38:01.508692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:00.88034Z","time spent":"628.255631ms","remote":"127.0.0.1:45728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-30T15:38:01.508833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:00.883247Z","time spent":"625.518819ms","remote":"127.0.0.1:45728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T15:38:01.508924Z","caller":"traceutil/trace.go:171","msg":"trace[187765830] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"625.925907ms","start":"2026-04-30T15:38:00.88299Z","end":"2026-04-30T15:38:01.508916Z","steps":["trace[187765830] 'process raft request' (duration: 625.106991ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:01.508953Z","caller":"traceutil/trace.go:171","msg":"trace[560740534] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"625.896355ms","start":"2026-04-30T15:38:00.883044Z","end":"2026-04-30T15:38:01.508941Z","steps":["trace[560740534] 'process raft request' (duration: 625.08505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:01.508963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:00.882982Z","time spent":"625.963298ms","remote":"127.0.0.1:46220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-30T15:38:01.508999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:00.883041Z","time spent":"625.936297ms","remote":"127.0.0.1:46220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T15:38:01.5091Z","caller":"traceutil/trace.go:171","msg":"trace[2034732526] transaction","detail":"{read_only:false; number_of_response:1; response_revision:528; }","duration":"625.745079ms","start":"2026-04-30T15:38:00.883347Z","end":"2026-04-30T15:38:01.509092Z","steps":["trace[2034732526] 'process raft request' (duration: 624.904502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:01.509158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:00.88333Z","time spent":"625.79112ms","remote":"127.0.0.1:45728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T15:38:01.509235Z","caller":"traceutil/trace.go:171","msg":"trace[31398661] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"625.926657ms","start":"2026-04-30T15:38:00.883301Z","end":"2026-04-30T15:38:01.509227Z","steps":["trace[31398661] 'process raft request' (duration: 624.913462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:01.509273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:00.883296Z","time spent":"625.959688ms","remote":"127.0.0.1:45728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T15:38:01.509333Z","caller":"traceutil/trace.go:171","msg":"trace[1205493286] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"621.089609ms","start":"2026-04-30T15:38:00.888235Z","end":"2026-04-30T15:38:01.509324Z","steps":["trace[1205493286] 'process raft request' (duration: 620.286994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:01.509361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:00.888224Z","time spent":"621.125111ms","remote":"127.0.0.1:45728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T15:38:01.509439Z","caller":"traceutil/trace.go:171","msg":"trace[1072846973] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"624.397849ms","start":"2026-04-30T15:38:00.88503Z","end":"2026-04-30T15:38:01.509427Z","steps":["trace[1072846973] 'process raft request' (duration: 623.378914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:01.50949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:00.88502Z","time spent":"624.44395ms","remote":"127.0.0.1:45728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T15:38:01.509587Z","caller":"traceutil/trace.go:171","msg":"trace[394387497] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"602.032608ms","start":"2026-04-30T15:38:00.907545Z","end":"2026-04-30T15:38:01.509578Z","steps":["trace[394387497] 'process raft request' (duration: 601.177911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:01.509636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:00.907524Z","time spent":"602.089441ms","remote":"127.0.0.1:45728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T15:38:01.509656Z","caller":"traceutil/trace.go:171","msg":"trace[2117822574] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"590.24628ms","start":"2026-04-30T15:38:00.919362Z","end":"2026-04-30T15:38:01.509608Z","steps":["trace[2117822574] 'process raft request' (duration: 589.651083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:01.509761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:00.919338Z","time spent":"590.362875ms","remote":"127.0.0.1:45728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T15:38:01.509897Z","caller":"traceutil/trace.go:171","msg":"trace[178621068] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:536; }","duration":"445.885351ms","start":"2026-04-30T15:38:01.063996Z","end":"2026-04-30T15:38:01.509881Z","steps":["trace[178621068] 'read index received' (duration: 194.319815ms)","trace[178621068] 'applied index is now lower than readState.Index' (duration: 251.564566ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T15:38:01.509948Z","caller":"traceutil/trace.go:171","msg":"trace[682394097] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"563.987785ms","start":"2026-04-30T15:38:00.94594Z","end":"2026-04-30T15:38:01.509927Z","steps":["trace[682394097] 'process raft request' (duration: 563.174079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:01.510009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:00.94592Z","time spent":"564.064848ms","remote":"127.0.0.1:45728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-30T15:38:01.51013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.141653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-30T15:38:01.510309Z","caller":"traceutil/trace.go:171","msg":"trace[1174450706] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:534; }","duration":"446.361143ms","start":"2026-04-30T15:38:01.063929Z","end":"2026-04-30T15:38:01.51029Z","steps":["trace[1174450706] 'agreement among raft nodes before linearized reading' (duration: 446.125692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:01.51046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:01.063906Z","time spent":"446.540011ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-30T15:38:01.510325Z","caller":"traceutil/trace.go:171","msg":"trace[1365245612] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"433.857129ms","start":"2026-04-30T15:38:01.076203Z","end":"2026-04-30T15:38:01.51006Z","steps":["trace[1365245612] 'process raft request' (duration: 433.391609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:01.510769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:01.076185Z","time spent":"434.54326ms","remote":"127.0.0.1:45728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-30T15:38:01.513733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.054358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-30T15:38:01.513807Z","caller":"traceutil/trace.go:171","msg":"trace[2028217113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:536; }","duration":"403.180263ms","start":"2026-04-30T15:38:01.110612Z","end":"2026-04-30T15:38:01.513792Z","steps":["trace[2028217113] 'agreement among raft nodes before linearized reading' (duration: 403.028486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:01.513928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:01.110591Z","time spent":"403.263637ms","remote":"127.0.0.1:45970","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-04-30T15:38:01.514527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.382785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-30T15:38:01.514579Z","caller":"traceutil/trace.go:171","msg":"trace[223887685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:536; }","duration":"302.469549ms","start":"2026-04-30T15:38:01.212095Z","end":"2026-04-30T15:38:01.514565Z","steps":["trace[223887685] 'agreement among raft nodes before linearized reading' (duration: 302.380605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:01.514612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:01.212076Z","time spent":"302.526282ms","remote":"127.0.0.1:45970","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-04-30T15:38:01.514822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.910017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-30T15:38:01.514861Z","caller":"traceutil/trace.go:171","msg":"trace[1560524602] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:536; }","duration":"291.009362ms","start":"2026-04-30T15:38:01.223843Z","end":"2026-04-30T15:38:01.514852Z","steps":["trace[1560524602] 'agreement among raft nodes before linearized reading' (duration: 290.883536ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:05.043021Z","caller":"traceutil/trace.go:171","msg":"trace[1804507491] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"117.180361ms","start":"2026-04-30T15:38:04.9258Z","end":"2026-04-30T15:38:05.04298Z","steps":["trace[1804507491] 'process raft request' (duration: 114.026617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:05.316514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.73667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-30T15:38:05.316817Z","caller":"traceutil/trace.go:171","msg":"trace[1538777592] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"221.551875ms","start":"2026-04-30T15:38:05.095233Z","end":"2026-04-30T15:38:05.316785Z","steps":["trace[1538777592] 'process raft request' (duration: 110.453199ms)","trace[1538777592] 'compare' (duration: 110.310533ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T15:38:05.316646Z","caller":"traceutil/trace.go:171","msg":"trace[1713407100] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"114.552512ms","start":"2026-04-30T15:38:05.202071Z","end":"2026-04-30T15:38:05.316623Z","steps":["trace[1713407100] 'read index received' (duration: 3.54699ms)","trace[1713407100] 'applied index is now lower than readState.Index' (duration: 111.003732ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T15:38:05.316957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.867335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-30T15:38:05.317065Z","caller":"traceutil/trace.go:171","msg":"trace[1295989192] range","detail":"{range_begin:/registry/masterleases/199.19.213.138; range_end:; response_count:1; response_revision:609; }","duration":"115.002402ms","start":"2026-04-30T15:38:05.202041Z","end":"2026-04-30T15:38:05.317043Z","steps":["trace[1295989192] 'agreement among raft nodes before linearized reading' (duration: 114.823164ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:05.988238Z","caller":"traceutil/trace.go:171","msg":"trace[1118174656] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"421.292139ms","start":"2026-04-30T15:38:05.566917Z","end":"2026-04-30T15:38:05.98821Z","steps":["trace[1118174656] 'process raft request' (duration: 361.969681ms)","trace[1118174656] 'compare' (duration: 58.714732ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T15:38:05.988385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:05.566904Z","time spent":"421.401163ms","remote":"127.0.0.1:45970","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-04-30T15:38:05.990015Z","caller":"traceutil/trace.go:171","msg":"trace[2020135735] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"422.906877ms","start":"2026-04-30T15:38:05.567071Z","end":"2026-04-30T15:38:05.989978Z","steps":["trace[2020135735] 'process raft request' (duration: 422.717379ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:05.990117Z","caller":"traceutil/trace.go:171","msg":"trace[1406038140] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"423.008431ms","start":"2026-04-30T15:38:05.567091Z","end":"2026-04-30T15:38:05.9901Z","steps":["trace[1406038140] 'process raft request' (duration: 422.789532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:05.990195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:05.567078Z","time spent":"423.079944ms","remote":"127.0.0.1:45970","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-04-30T15:38:05.990293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:05.567065Z","time spent":"423.070014ms","remote":"127.0.0.1:45970","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-04-30T15:38:05.999209Z","caller":"traceutil/trace.go:171","msg":"trace[1221911532] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"271.592861ms","start":"2026-04-30T15:38:05.72759Z","end":"2026-04-30T15:38:05.999183Z","steps":["trace[1221911532] 'process raft request' (duration: 263.731778ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:07.273245Z","caller":"traceutil/trace.go:171","msg":"trace[1360672489] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"158.306925ms","start":"2026-04-30T15:38:07.11491Z","end":"2026-04-30T15:38:07.273217Z","steps":["trace[1360672489] 'process raft request' (duration: 157.669668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:08.006118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.120121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-30T15:38:08.006209Z","caller":"traceutil/trace.go:171","msg":"trace[176730378] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:620; }","duration":"439.223885ms","start":"2026-04-30T15:38:07.566967Z","end":"2026-04-30T15:38:08.006191Z","steps":["trace[176730378] 'range keys from in-memory index tree' (duration: 438.958964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:08.006251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:07.56691Z","time spent":"439.330479ms","remote":"127.0.0.1:45970","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":"info","ts":"2026-04-30T15:38:08.042632Z","caller":"traceutil/trace.go:171","msg":"trace[1571566087] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"218.043946ms","start":"2026-04-30T15:38:07.824532Z","end":"2026-04-30T15:38:08.042576Z","steps":["trace[1571566087] 'process raft request' (duration: 217.15503ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:08.196226Z","caller":"traceutil/trace.go:171","msg":"trace[445128579] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"121.003097ms","start":"2026-04-30T15:38:08.075198Z","end":"2026-04-30T15:38:08.196201Z","steps":["trace[445128579] 'read index received' (duration: 33.390445ms)","trace[445128579] 'applied index is now lower than readState.Index' (duration: 87.611862ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T15:38:08.196379Z","caller":"traceutil/trace.go:171","msg":"trace[924215827] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"144.543419ms","start":"2026-04-30T15:38:08.051824Z","end":"2026-04-30T15:38:08.196367Z","steps":["trace[924215827] 'process raft request' (duration: 56.813392ms)","trace[924215827] 'compare' (duration: 87.291619ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T15:38:08.196572Z","caller":"traceutil/trace.go:171","msg":"trace[1894443844] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"114.448079ms","start":"2026-04-30T15:38:08.0821Z","end":"2026-04-30T15:38:08.196548Z","steps":["trace[1894443844] 'process raft request' (duration: 114.032522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:08.19674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.527028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T15:38:08.196778Z","caller":"traceutil/trace.go:171","msg":"trace[1632175891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"121.603341ms","start":"2026-04-30T15:38:08.075164Z","end":"2026-04-30T15:38:08.196767Z","steps":["trace[1632175891] 'agreement among raft nodes before linearized reading' (duration: 121.181884ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:08.196984Z","caller":"traceutil/trace.go:171","msg":"trace[349396789] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"100.279119ms","start":"2026-04-30T15:38:08.096693Z","end":"2026-04-30T15:38:08.196972Z","steps":["trace[349396789] 'process raft request' (duration: 99.784189ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:08.920849Z","caller":"traceutil/trace.go:171","msg":"trace[1938423776] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"326.001799ms","start":"2026-04-30T15:38:08.594825Z","end":"2026-04-30T15:38:08.920827Z","steps":["trace[1938423776] 'process raft request' (duration: 325.492818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:08.921128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:08.594795Z","time spent":"326.121053ms","remote":"127.0.0.1:45778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T15:38:10.499623Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4241157055363813923,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T15:38:10.70226Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.223891177s","expected-duration":"1s"} {"level":"info","ts":"2026-04-30T15:38:10.711028Z","caller":"traceutil/trace.go:171","msg":"trace[2075670281] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.234111337s","start":"2026-04-30T15:38:09.476885Z","end":"2026-04-30T15:38:10.710996Z","steps":["trace[2075670281] 'process raft request' (duration: 1.226403258s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:10.711193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:09.476861Z","time spent":"1.234257243s","remote":"127.0.0.1:45778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T15:38:11.494765Z","caller":"traceutil/trace.go:171","msg":"trace[643828159] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.926939789s","start":"2026-04-30T15:38:09.567774Z","end":"2026-04-30T15:38:11.494714Z","steps":["trace[643828159] 'process raft request' (duration: 1.926656418s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:11.495205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:09.567748Z","time spent":"1.92722426s","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T15:38:11.99862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999942788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-30T15:38:11.998714Z","caller":"traceutil/trace.go:171","msg":"trace[991138818] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000123366s","start":"2026-04-30T15:38:09.998569Z","end":"2026-04-30T15:38:11.998692Z","steps":["trace[991138818] 'agreement among raft nodes before linearized reading' (duration: 1.99997642s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:11.998764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:09.998548Z","time spent":"2.000206579s","remote":"127.0.0.1:45694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-30T15:38:14.006886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000331685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-30T15:38:14.006972Z","caller":"traceutil/trace.go:171","msg":"trace[1978202475] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00046641s","start":"2026-04-30T15:38:12.006485Z","end":"2026-04-30T15:38:14.006951Z","steps":["trace[1978202475] 'agreement among raft nodes before linearized reading' (duration: 2.000360416s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:14.007018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:12.00647Z","time spent":"2.000539363s","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-30T15:38:14.099668Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.097118784s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-30T15:38:14.110273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.615624051s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-30T15:38:14.110499Z","caller":"traceutil/trace.go:171","msg":"trace[1964982397] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:651; }","duration":"4.111843994s","start":"2026-04-30T15:38:09.998613Z","end":"2026-04-30T15:38:14.110457Z","steps":["trace[1964982397] 'read index received' (duration: 703.945173ms)","trace[1964982397] 'applied index is now lower than readState.Index' (duration: 3.407897731s)"],"step_count":2} {"level":"info","ts":"2026-04-30T15:38:14.110982Z","caller":"traceutil/trace.go:171","msg":"trace[382345739] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"3.333554117s","start":"2026-04-30T15:38:10.777397Z","end":"2026-04-30T15:38:14.110951Z","steps":["trace[382345739] 'process raft request' (duration: 3.332974874s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:14.111158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:10.777377Z","time spent":"3.333700013s","remote":"127.0.0.1:45944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T15:38:14.125476Z","caller":"traceutil/trace.go:171","msg":"trace[388279758] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"2.123043968s","start":"2026-04-30T15:38:12.002397Z","end":"2026-04-30T15:38:14.125441Z","steps":["trace[388279758] 'process raft request' (duration: 2.108533321s)","trace[388279758] 'compare' (duration: 14.384932ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T15:38:14.125541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.789651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T15:38:14.125579Z","caller":"traceutil/trace.go:171","msg":"trace[1705366569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"117.881634ms","start":"2026-04-30T15:38:14.007688Z","end":"2026-04-30T15:38:14.12557Z","steps":["trace[1705366569] 'agreement among raft nodes before linearized reading' (duration: 117.78563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:14.125583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:12.002339Z","time spent":"2.123189464s","remote":"127.0.0.1:45746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-30T15:38:14.125757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.923416132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-30T15:38:14.125787Z","caller":"traceutil/trace.go:171","msg":"trace[1132854442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"3.923468904s","start":"2026-04-30T15:38:10.202311Z","end":"2026-04-30T15:38:14.12578Z","steps":["trace[1132854442] 'agreement among raft nodes before linearized reading' (duration: 3.923404661s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:14.125808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:10.202299Z","time spent":"3.923503635s","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-30T15:38:14.125842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.085703202s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x5bbv\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-30T15:38:14.12586Z","caller":"traceutil/trace.go:171","msg":"trace[1289560586] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x5bbv; range_end:; response_count:1; response_revision:635; }","duration":"4.085757004s","start":"2026-04-30T15:38:10.040097Z","end":"2026-04-30T15:38:14.125854Z","steps":["trace[1289560586] 'agreement among raft nodes before linearized reading' (duration: 4.085673391s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:14.12588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:10.040077Z","time spent":"4.085798555s","remote":"127.0.0.1:45884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-x5bbv\" "} {"level":"warn","ts":"2026-04-30T15:38:14.125992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.923908981s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-30T15:38:14.126015Z","caller":"traceutil/trace.go:171","msg":"trace[1216429147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"3.923972823s","start":"2026-04-30T15:38:10.202038Z","end":"2026-04-30T15:38:14.126011Z","steps":["trace[1216429147] 'agreement among raft nodes before linearized reading' (duration: 3.923922801s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:14.126029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:10.202016Z","time spent":"3.924009335s","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-30T15:38:14.126095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.924335917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-30T15:38:14.126108Z","caller":"traceutil/trace.go:171","msg":"trace[1890383237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"3.924348809s","start":"2026-04-30T15:38:10.201754Z","end":"2026-04-30T15:38:14.126103Z","steps":["trace[1890383237] 'agreement among raft nodes before linearized reading' (duration: 3.924322818s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:14.126125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:10.201698Z","time spent":"3.924424101s","remote":"127.0.0.1:45970","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-04-30T15:38:14.126181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.048455121s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-30T15:38:14.126197Z","caller":"traceutil/trace.go:171","msg":"trace[1963184018] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"4.048508353s","start":"2026-04-30T15:38:10.077683Z","end":"2026-04-30T15:38:14.126192Z","steps":["trace[1963184018] 'agreement among raft nodes before linearized reading' (duration: 4.048476142s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:14.126213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:10.077663Z","time spent":"4.048545955s","remote":"127.0.0.1:45970","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":"info","ts":"2026-04-30T15:38:16.383162Z","caller":"traceutil/trace.go:171","msg":"trace[302195529] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"122.685959ms","start":"2026-04-30T15:38:16.260438Z","end":"2026-04-30T15:38:16.383124Z","steps":["trace[302195529] 'process raft request' (duration: 122.473161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:19.44197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.445617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-30T15:38:19.442205Z","caller":"traceutil/trace.go:171","msg":"trace[7610528] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:715; }","duration":"184.75912ms","start":"2026-04-30T15:38:19.257419Z","end":"2026-04-30T15:38:19.442178Z","steps":["trace[7610528] 'range keys from in-memory index tree' (duration: 184.184987ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:24.413073Z","caller":"traceutil/trace.go:171","msg":"trace[751624117] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"193.959788ms","start":"2026-04-30T15:38:24.21909Z","end":"2026-04-30T15:38:24.41305Z","steps":["trace[751624117] 'process raft request' (duration: 193.919027ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:24.413106Z","caller":"traceutil/trace.go:171","msg":"trace[1761059326] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"195.13816ms","start":"2026-04-30T15:38:24.217945Z","end":"2026-04-30T15:38:24.413083Z","steps":["trace[1761059326] 'process raft request' (duration: 105.982305ms)","trace[1761059326] 'compare' (duration: 88.909967ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T15:38:24.413201Z","caller":"traceutil/trace.go:171","msg":"trace[1958378364] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"194.909062ms","start":"2026-04-30T15:38:24.218262Z","end":"2026-04-30T15:38:24.413171Z","steps":["trace[1958378364] 'process raft request' (duration: 194.708125ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:27.333033Z","caller":"traceutil/trace.go:171","msg":"trace[308635563] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"195.188819ms","start":"2026-04-30T15:38:27.137826Z","end":"2026-04-30T15:38:27.333015Z","steps":["trace[308635563] 'process raft request' (duration: 195.071705ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:28.312904Z","caller":"traceutil/trace.go:171","msg":"trace[1225604386] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:807; }","duration":"315.089977ms","start":"2026-04-30T15:38:27.99779Z","end":"2026-04-30T15:38:28.31288Z","steps":["trace[1225604386] 'read index received' (duration: 314.932701ms)","trace[1225604386] 'applied index is now lower than readState.Index' (duration: 156.426µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T15:38:28.312967Z","caller":"traceutil/trace.go:171","msg":"trace[1608644942] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"382.333676ms","start":"2026-04-30T15:38:27.930607Z","end":"2026-04-30T15:38:28.312941Z","steps":["trace[1608644942] 'process raft request' (duration: 382.132609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:28.313046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.234511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T15:38:28.313078Z","caller":"traceutil/trace.go:171","msg":"trace[1584558518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"315.321835ms","start":"2026-04-30T15:38:27.997747Z","end":"2026-04-30T15:38:28.313069Z","steps":["trace[1584558518] 'agreement among raft nodes before linearized reading' (duration: 315.246572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:28.31309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:27.93059Z","time spent":"382.431369ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T15:38:28.313115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:27.997728Z","time spent":"315.379616ms","remote":"127.0.0.1:45686","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-04-30T15:38:28.813016Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4241157055363814217,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-30T15:38:28.837448Z","caller":"traceutil/trace.go:171","msg":"trace[537576114] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"524.451219ms","start":"2026-04-30T15:38:28.312967Z","end":"2026-04-30T15:38:28.837419Z","steps":["trace[537576114] 'read index received' (duration: 524.251812ms)","trace[537576114] 'applied index is now lower than readState.Index' (duration: 198.267µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T15:38:28.837515Z","caller":"traceutil/trace.go:171","msg":"trace[1084189613] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"788.788913ms","start":"2026-04-30T15:38:28.048666Z","end":"2026-04-30T15:38:28.837455Z","steps":["trace[1084189613] 'process raft request' (duration: 788.427941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:28.837723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:28.048643Z","time spent":"788.965139ms","remote":"127.0.0.1:45868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T15:38:28.837848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.976161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-30T15:38:28.83792Z","caller":"traceutil/trace.go:171","msg":"trace[565113381] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:783; }","duration":"610.124366ms","start":"2026-04-30T15:38:28.227776Z","end":"2026-04-30T15:38:28.837901Z","steps":["trace[565113381] 'agreement among raft nodes before linearized reading' (duration: 609.789165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:28.837975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:28.227755Z","time spent":"610.20508ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-30T15:38:28.838005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.343573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T15:38:28.838074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.932203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-30T15:38:28.83807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.21502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-30T15:38:28.838117Z","caller":"traceutil/trace.go:171","msg":"trace[1061378102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:783; }","duration":"523.510869ms","start":"2026-04-30T15:38:28.314574Z","end":"2026-04-30T15:38:28.838085Z","steps":["trace[1061378102] 'agreement among raft nodes before linearized reading' (duration: 523.320352ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:28.838138Z","caller":"traceutil/trace.go:171","msg":"trace[519965998] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:783; }","duration":"402.029836ms","start":"2026-04-30T15:38:28.436089Z","end":"2026-04-30T15:38:28.838119Z","steps":["trace[519965998] 'agreement among raft nodes before linearized reading' (duration: 401.831278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:28.838175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:28.314553Z","time spent":"523.611082ms","remote":"127.0.0.1:45694","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-04-30T15:38:28.838184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:28.436054Z","time spent":"402.120859ms","remote":"127.0.0.1:45970","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":"info","ts":"2026-04-30T15:38:28.838193Z","caller":"traceutil/trace.go:171","msg":"trace[2095726522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:783; }","duration":"363.343454ms","start":"2026-04-30T15:38:28.474804Z","end":"2026-04-30T15:38:28.838148Z","steps":["trace[2095726522] 'agreement among raft nodes before linearized reading' (duration: 363.081424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:28.838311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:28.474791Z","time spent":"363.449337ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-30T15:38:28.838431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.559671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-30T15:38:28.838495Z","caller":"traceutil/trace.go:171","msg":"trace[1999600796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:783; }","duration":"363.645264ms","start":"2026-04-30T15:38:28.474832Z","end":"2026-04-30T15:38:28.838477Z","steps":["trace[1999600796] 'agreement among raft nodes before linearized reading' (duration: 363.407766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:28.838539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:28.474815Z","time spent":"363.711746ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-30T15:38:29.37516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.345235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T15:38:29.375266Z","caller":"traceutil/trace.go:171","msg":"trace[411324130] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"530.87563ms","start":"2026-04-30T15:38:28.844371Z","end":"2026-04-30T15:38:29.375247Z","steps":["trace[411324130] 'process raft request' (duration: 292.358569ms)","trace[411324130] 'compare' (duration: 238.236662ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T15:38:29.375323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:28.844336Z","time spent":"530.960592ms","remote":"127.0.0.1:45970","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-04-30T15:38:29.401445Z","caller":"traceutil/trace.go:171","msg":"trace[2135353332] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"556.920705ms","start":"2026-04-30T15:38:28.8445Z","end":"2026-04-30T15:38:29.401421Z","steps":["trace[2135353332] 'process raft request' (duration: 556.637936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:29.401589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:28.844469Z","time spent":"557.041609ms","remote":"127.0.0.1:45970","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-04-30T15:38:29.401816Z","caller":"traceutil/trace.go:171","msg":"trace[397140425] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"557.220805ms","start":"2026-04-30T15:38:28.844568Z","end":"2026-04-30T15:38:29.401788Z","steps":["trace[397140425] 'process raft request' (duration: 556.687518ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:29.402004Z","caller":"traceutil/trace.go:171","msg":"trace[1626055189] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"557.188833ms","start":"2026-04-30T15:38:28.8448Z","end":"2026-04-30T15:38:29.401989Z","steps":["trace[1626055189] 'process raft request' (duration: 556.793101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:29.402028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:28.844551Z","time spent":"557.361949ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T15:38:29.401981Z","caller":"traceutil/trace.go:171","msg":"trace[580568938] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:809; }","duration":"402.726939ms","start":"2026-04-30T15:38:28.999236Z","end":"2026-04-30T15:38:29.401962Z","steps":["trace[580568938] 'read index received' (duration: 137.636886ms)","trace[580568938] 'applied index is now lower than readState.Index' (duration: 265.089122ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T15:38:29.402092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:28.844786Z","time spent":"557.254436ms","remote":"127.0.0.1:45970","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-04-30T15:38:29.402156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.910474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T15:38:29.402241Z","caller":"traceutil/trace.go:171","msg":"trace[1665991996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"403.048939ms","start":"2026-04-30T15:38:28.999172Z","end":"2026-04-30T15:38:29.402221Z","steps":["trace[1665991996] 'agreement among raft nodes before linearized reading' (duration: 402.907924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:29.402289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:28.999147Z","time spent":"403.133532ms","remote":"127.0.0.1:45686","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-04-30T15:38:31.483306Z","caller":"traceutil/trace.go:171","msg":"trace[526367123] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"102.056732ms","start":"2026-04-30T15:38:31.381223Z","end":"2026-04-30T15:38:31.48328Z","steps":["trace[526367123] 'process raft request' (duration: 101.701721ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:31.613717Z","caller":"traceutil/trace.go:171","msg":"trace[1613985384] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"125.348239ms","start":"2026-04-30T15:38:31.488333Z","end":"2026-04-30T15:38:31.613682Z","steps":["trace[1613985384] 'process raft request' (duration: 31.567684ms)","trace[1613985384] 'compare' (duration: 93.408213ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T15:38:31.613915Z","caller":"traceutil/trace.go:171","msg":"trace[1130314211] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"125.264667ms","start":"2026-04-30T15:38:31.48864Z","end":"2026-04-30T15:38:31.613905Z","steps":["trace[1130314211] 'process raft request' (duration: 124.915376ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:31.614014Z","caller":"traceutil/trace.go:171","msg":"trace[636648884] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"125.294207ms","start":"2026-04-30T15:38:31.488683Z","end":"2026-04-30T15:38:31.613977Z","steps":["trace[636648884] 'process raft request' (duration: 124.973327ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:32.702992Z","caller":"traceutil/trace.go:171","msg":"trace[1658722400] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"367.641305ms","start":"2026-04-30T15:38:32.335319Z","end":"2026-04-30T15:38:32.70296Z","steps":["trace[1658722400] 'process raft request' (duration: 367.447449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:32.703176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:32.335292Z","time spent":"367.79539ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T15:38:33.628987Z","caller":"traceutil/trace.go:171","msg":"trace[269138318] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"134.774839ms","start":"2026-04-30T15:38:33.494183Z","end":"2026-04-30T15:38:33.628957Z","steps":["trace[269138318] 'process raft request' (duration: 133.626832ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:35.847658Z","caller":"traceutil/trace.go:171","msg":"trace[386063537] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"153.428945ms","start":"2026-04-30T15:38:35.694206Z","end":"2026-04-30T15:38:35.847635Z","steps":["trace[386063537] 'read index received' (duration: 153.271591ms)","trace[386063537] 'applied index is now lower than readState.Index' (duration: 156.504µs)"],"step_count":2} {"level":"warn","ts":"2026-04-30T15:38:35.848174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.391468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-30T15:38:35.848249Z","caller":"traceutil/trace.go:171","msg":"trace[1839118541] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"210.957795ms","start":"2026-04-30T15:38:35.637275Z","end":"2026-04-30T15:38:35.848233Z","steps":["trace[1839118541] 'process raft request' (duration: 210.252443ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:35.848272Z","caller":"traceutil/trace.go:171","msg":"trace[180517608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"146.536612ms","start":"2026-04-30T15:38:35.701712Z","end":"2026-04-30T15:38:35.848249Z","steps":["trace[180517608] 'agreement among raft nodes before linearized reading' (duration: 146.268854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:35.848166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.947692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-30T15:38:35.848464Z","caller":"traceutil/trace.go:171","msg":"trace[1290807365] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:802; }","duration":"154.304392ms","start":"2026-04-30T15:38:35.694147Z","end":"2026-04-30T15:38:35.848451Z","steps":["trace[1290807365] 'agreement among raft nodes before linearized reading' (duration: 153.865079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:35.848543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.769899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-30T15:38:35.848616Z","caller":"traceutil/trace.go:171","msg":"trace[1539276526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:802; }","duration":"146.865482ms","start":"2026-04-30T15:38:35.701734Z","end":"2026-04-30T15:38:35.848599Z","steps":["trace[1539276526] 'agreement among raft nodes before linearized reading' (duration: 146.684156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:39.32125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.43349585s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T15:38:39.321392Z","caller":"traceutil/trace.go:171","msg":"trace[1079547368] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:847; }","duration":"1.4634559s","start":"2026-04-30T15:38:37.857893Z","end":"2026-04-30T15:38:39.321348Z","steps":["trace[1079547368] 'read index received' (duration: 29.800105ms)","trace[1079547368] 'applied index is now lower than readState.Index' (duration: 1.433653675s)"],"step_count":2} {"level":"info","ts":"2026-04-30T15:38:39.321717Z","caller":"traceutil/trace.go:171","msg":"trace[1508290481] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"1.464073628s","start":"2026-04-30T15:38:37.857622Z","end":"2026-04-30T15:38:39.321696Z","steps":["trace[1508290481] 'process raft request' (duration: 30.036982ms)","trace[1508290481] 'compare' (duration: 1.433323175s)"],"step_count":2} {"level":"warn","ts":"2026-04-30T15:38:39.321855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:37.857607Z","time spent":"1.464199262s","remote":"127.0.0.1:45970","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-04-30T15:38:39.321948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154657424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kgvrq\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-04-30T15:38:39.322002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.46412238s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-30T15:38:39.322038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.324936935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T15:38:39.322113Z","caller":"traceutil/trace.go:171","msg":"trace[745673309] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:820; }","duration":"1.464207472s","start":"2026-04-30T15:38:37.857821Z","end":"2026-04-30T15:38:39.322028Z","steps":["trace[745673309] 'agreement among raft nodes before linearized reading' (duration: 1.463986295s)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:39.322157Z","caller":"traceutil/trace.go:171","msg":"trace[2069753571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:820; }","duration":"1.325074289s","start":"2026-04-30T15:38:37.997064Z","end":"2026-04-30T15:38:39.322138Z","steps":["trace[2069753571] 'agreement among raft nodes before linearized reading' (duration: 1.324949075s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:39.322158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.674577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-30T15:38:39.322207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:37.997046Z","time spent":"1.325149732s","remote":"127.0.0.1:45686","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-04-30T15:38:39.322215Z","caller":"traceutil/trace.go:171","msg":"trace[707399279] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:820; }","duration":"580.76731ms","start":"2026-04-30T15:38:38.741433Z","end":"2026-04-30T15:38:39.3222Z","steps":["trace[707399279] 'agreement among raft nodes before linearized reading' (duration: 580.636106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:39.322262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:38.741414Z","time spent":"580.836182ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-30T15:38:39.322344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.46051425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-30T15:38:39.322404Z","caller":"traceutil/trace.go:171","msg":"trace[1469481811] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:820; }","duration":"1.460605332s","start":"2026-04-30T15:38:37.861784Z","end":"2026-04-30T15:38:39.322389Z","steps":["trace[1469481811] 'agreement among raft nodes before linearized reading' (duration: 1.460463728s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:39.322453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:37.861768Z","time spent":"1.460674214s","remote":"127.0.0.1:45970","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-04-30T15:38:39.322487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:37.857794Z","time spent":"1.464588093s","remote":"127.0.0.1:45970","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":"info","ts":"2026-04-30T15:38:39.322038Z","caller":"traceutil/trace.go:171","msg":"trace[1894065283] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kgvrq; range_end:; response_count:1; response_revision:820; }","duration":"1.154783707s","start":"2026-04-30T15:38:38.167234Z","end":"2026-04-30T15:38:39.322018Z","steps":["trace[1894065283] 'agreement among raft nodes before linearized reading' (duration: 1.15454152s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:39.322593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:38.167215Z","time spent":"1.155362424s","remote":"127.0.0.1:45884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kgvrq\" "} {"level":"warn","ts":"2026-04-30T15:38:39.321927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.460917763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-30T15:38:39.32283Z","caller":"traceutil/trace.go:171","msg":"trace[801908900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:820; }","duration":"1.461865831s","start":"2026-04-30T15:38:37.860948Z","end":"2026-04-30T15:38:39.322814Z","steps":["trace[801908900] 'agreement among raft nodes before linearized reading' (duration: 1.460785029s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:38:39.322886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T15:38:37.860925Z","time spent":"1.461947123s","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-30T15:38:53.648055Z","caller":"traceutil/trace.go:171","msg":"trace[1252999191] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"144.12437ms","start":"2026-04-30T15:38:53.503902Z","end":"2026-04-30T15:38:53.648026Z","steps":["trace[1252999191] 'process raft request' (duration: 143.949206ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:53.649666Z","caller":"traceutil/trace.go:171","msg":"trace[815840170] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"145.475335ms","start":"2026-04-30T15:38:53.504162Z","end":"2026-04-30T15:38:53.649637Z","steps":["trace[815840170] 'process raft request' (duration: 145.298311ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:53.65007Z","caller":"traceutil/trace.go:171","msg":"trace[853300980] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"145.815914ms","start":"2026-04-30T15:38:53.504227Z","end":"2026-04-30T15:38:53.650043Z","steps":["trace[853300980] 'process raft request' (duration: 145.361851ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:38:53.650067Z","caller":"traceutil/trace.go:171","msg":"trace[262860090] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"145.825304ms","start":"2026-04-30T15:38:53.504205Z","end":"2026-04-30T15:38:53.65003Z","steps":["trace[262860090] 'process raft request' (duration: 145.345091ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:39:01.846829Z","caller":"traceutil/trace.go:171","msg":"trace[669350159] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"137.078545ms","start":"2026-04-30T15:39:01.709727Z","end":"2026-04-30T15:39:01.846805Z","steps":["trace[669350159] 'read index received' (duration: 131.912027ms)","trace[669350159] 'applied index is now lower than readState.Index' (duration: 5.165507ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T15:39:01.84707Z","caller":"traceutil/trace.go:171","msg":"trace[1004893700] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"161.556228ms","start":"2026-04-30T15:39:01.685476Z","end":"2026-04-30T15:39:01.847032Z","steps":["trace[1004893700] 'process raft request' (duration: 156.056943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:39:01.847155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.565922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-30T15:39:01.847314Z","caller":"traceutil/trace.go:171","msg":"trace[749665504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1020; }","duration":"162.869509ms","start":"2026-04-30T15:39:01.684425Z","end":"2026-04-30T15:39:01.847294Z","steps":["trace[749665504] 'agreement among raft nodes before linearized reading' (duration: 162.47176ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:39:01.852052Z","caller":"traceutil/trace.go:171","msg":"trace[1549874541] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"138.315625ms","start":"2026-04-30T15:39:01.713725Z","end":"2026-04-30T15:39:01.85204Z","steps":["trace[1549874541] 'process raft request' (duration: 138.248643ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:40:06.330041Z","caller":"traceutil/trace.go:171","msg":"trace[446895781] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"101.022ms","start":"2026-04-30T15:40:06.228993Z","end":"2026-04-30T15:40:06.330015Z","steps":["trace[446895781] 'process raft request' (duration: 100.605863ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:40:36.50264Z","caller":"traceutil/trace.go:171","msg":"trace[1216696955] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"130.892807ms","start":"2026-04-30T15:40:36.371706Z","end":"2026-04-30T15:40:36.502599Z","steps":["trace[1216696955] 'process raft request' (duration: 56.23921ms)","trace[1216696955] 'compare' (duration: 74.469924ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T15:40:36.728809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.595821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T15:40:36.728963Z","caller":"traceutil/trace.go:171","msg":"trace[1002981986] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"220.402674ms","start":"2026-04-30T15:40:36.508534Z","end":"2026-04-30T15:40:36.728937Z","steps":["trace[1002981986] 'process raft request' (duration: 97.506787ms)","trace[1002981986] 'compare' (duration: 122.445069ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T15:40:37.432099Z","caller":"traceutil/trace.go:171","msg":"trace[1150192762] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"222.342943ms","start":"2026-04-30T15:40:37.209732Z","end":"2026-04-30T15:40:37.432075Z","steps":["trace[1150192762] 'process raft request' (duration: 222.067438ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T15:40:42.928856Z","caller":"traceutil/trace.go:171","msg":"trace[1991504657] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"121.939171ms","start":"2026-04-30T15:40:42.806873Z","end":"2026-04-30T15:40:42.928812Z","steps":["trace[1991504657] 'process raft request' (duration: 100.829001ms)","trace[1991504657] 'compare' (duration: 20.941328ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T15:40:44.981179Z","caller":"traceutil/trace.go:171","msg":"trace[1950444961] linearizableReadLoop","detail":"{readStateIndex:1463; appliedIndex:1462; }","duration":"128.6314ms","start":"2026-04-30T15:40:44.852526Z","end":"2026-04-30T15:40:44.981158Z","steps":["trace[1950444961] 'read index received' (duration: 128.507178ms)","trace[1950444961] 'applied index is now lower than readState.Index' (duration: 123.142µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T15:40:44.981308Z","caller":"traceutil/trace.go:171","msg":"trace[1792902421] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"139.375596ms","start":"2026-04-30T15:40:44.841914Z","end":"2026-04-30T15:40:44.98129Z","steps":["trace[1792902421] 'process raft request' (duration: 139.095792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T15:40:44.981494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.946004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/kubeadm:get-nodes\" ","response":"range_response_count:1 size:305"} {"level":"info","ts":"2026-04-30T15:40:44.981592Z","caller":"traceutil/trace.go:171","msg":"trace[971564274] range","detail":"{range_begin:/registry/clusterroles/kubeadm:get-nodes; range_end:; response_count:1; response_revision:1405; }","duration":"129.092916ms","start":"2026-04-30T15:40:44.852474Z","end":"2026-04-30T15:40:44.981567Z","steps":["trace[971564274] 'agreement among raft nodes before linearized reading' (duration: 128.912613ms)"],"step_count":1}