{"level":"warn","ts":"2026-02-27T01:13:24.105223Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T01:13:24.105303Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-02-27T01:13:24.105524Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T01:13:24.105542Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-02-27T01:13:24.10556Z","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-02-27T01:13:24.106503Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-02-27T01:13:24.106739Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-02-27T01:13:24.139352Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"31.75951ms"} {"level":"info","ts":"2026-02-27T01:13:24.169733Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-02-27T01:13:24.170124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T01:13:24.170302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-02-27T01:13:24.170434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T01:13:24.170526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-02-27T01:13:24.170663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-02-27T01:13:24.205103Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T01:13:24.213338Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T01:13:24.217181Z","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-02-27T01:13:24.237478Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T01:13:24.237825Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T01:13:24.238053Z","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-02-27T01:13:24.238138Z","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-02-27T01:13:24.238158Z","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-02-27T01:13:24.242525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-02-27T01:13:24.242838Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-02-27T01:13:24.243919Z","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-02-27T01:13:24.243989Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-02-27T01:13:24.244022Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-02-27T01:13:24.244198Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T01:13:24.244242Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T01:13:24.572016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T01:13:24.572144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T01:13:24.572198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-02-27T01:13:24.572235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-02-27T01:13:24.57228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-02-27T01:13:24.572331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-02-27T01:13:24.572372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-02-27T01:13:24.575648Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T01:13:24.575688Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T01:13:24.575942Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T01:13:24.576279Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T01:13:24.576344Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T01:13:24.576371Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T01:13:24.579165Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-02-27T01:13:24.579452Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T01:13:24.585879Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T01:13:24.586725Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T01:13:24.586769Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T01:13:26.224605Z","caller":"traceutil/trace.go:171","msg":"trace[486551387] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"161.848376ms","start":"2026-02-27T01:13:26.062722Z","end":"2026-02-27T01:13:26.22457Z","steps":["trace[486551387] 'process raft request' (duration: 85.645814ms)","trace[486551387] 'compare' (duration: 76.042196ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:13:26.224756Z","caller":"traceutil/trace.go:171","msg":"trace[1235404027] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"135.928724ms","start":"2026-02-27T01:13:26.088818Z","end":"2026-02-27T01:13:26.224746Z","steps":["trace[1235404027] 'process raft request' (duration: 135.862522ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:13:26.224835Z","caller":"traceutil/trace.go:171","msg":"trace[1633538066] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"158.818003ms","start":"2026-02-27T01:13:26.065978Z","end":"2026-02-27T01:13:26.224796Z","steps":["trace[1633538066] 'process raft request' (duration: 158.650597ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:13:26.22494Z","caller":"traceutil/trace.go:171","msg":"trace[496607434] linearizableReadLoop","detail":"{readStateIndex:30; appliedIndex:24; }","duration":"141.97586ms","start":"2026-02-27T01:13:26.082952Z","end":"2026-02-27T01:13:26.224928Z","steps":["trace[496607434] 'read index received' (duration: 65.425786ms)","trace[496607434] 'applied index is now lower than readState.Index' (duration: 76.549144ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:13:26.225038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.204224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-02-27T01:13:26.225091Z","caller":"traceutil/trace.go:171","msg":"trace[65143685] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:29; }","duration":"161.304707ms","start":"2026-02-27T01:13:26.063779Z","end":"2026-02-27T01:13:26.225083Z","steps":["trace[65143685] 'agreement among raft nodes before linearized reading' (duration: 161.180783ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:13:26.225088Z","caller":"traceutil/trace.go:171","msg":"trace[2090971341] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"159.61497ms","start":"2026-02-27T01:13:26.065456Z","end":"2026-02-27T01:13:26.225071Z","steps":["trace[2090971341] 'process raft request' (duration: 159.119423ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:13:26.225192Z","caller":"traceutil/trace.go:171","msg":"trace[179733574] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"159.646361ms","start":"2026-02-27T01:13:26.06552Z","end":"2026-02-27T01:13:26.225167Z","steps":["trace[179733574] 'process raft request' (duration: 159.089532ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:13:26.225179Z","caller":"traceutil/trace.go:171","msg":"trace[1091705089] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"159.64533ms","start":"2026-02-27T01:13:26.065412Z","end":"2026-02-27T01:13:26.225058Z","steps":["trace[1091705089] 'process raft request' (duration: 159.142923ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:13:26.225323Z","caller":"traceutil/trace.go:171","msg":"trace[1789381347] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"137.653703ms","start":"2026-02-27T01:13:26.08766Z","end":"2026-02-27T01:13:26.225314Z","steps":["trace[1789381347] 'process raft request' (duration: 136.992341ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:13:26.225509Z","caller":"traceutil/trace.go:171","msg":"trace[943082900] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"161.053309ms","start":"2026-02-27T01:13:26.064438Z","end":"2026-02-27T01:13:26.225492Z","steps":["trace[943082900] 'process raft request' (duration: 160.080716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:13:27.641467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.801559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-27T01:13:27.641652Z","caller":"traceutil/trace.go:171","msg":"trace[373344436] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"198.032036ms","start":"2026-02-27T01:13:27.443598Z","end":"2026-02-27T01:13:27.641631Z","steps":["trace[373344436] 'process raft request' (duration: 77.541464ms)","trace[373344436] 'compare' (duration: 119.704466ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:13:28.663834Z","caller":"traceutil/trace.go:171","msg":"trace[1369878778] linearizableReadLoop","detail":"{readStateIndex:172; appliedIndex:171; }","duration":"132.01391ms","start":"2026-02-27T01:13:28.531792Z","end":"2026-02-27T01:13:28.663806Z","steps":["trace[1369878778] 'read index received' (duration: 79.03588ms)","trace[1369878778] 'applied index is now lower than readState.Index' (duration: 52.97703ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:13:28.663917Z","caller":"traceutil/trace.go:171","msg":"trace[1549472904] transaction","detail":"{read_only:false; response_revision:169; number_of_response:1; }","duration":"144.28896ms","start":"2026-02-27T01:13:28.519598Z","end":"2026-02-27T01:13:28.663887Z","steps":["trace[1549472904] 'process raft request' (duration: 91.214777ms)","trace[1549472904] 'compare' (duration: 52.830405ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:13:28.664088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.761882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-27T01:13:28.664153Z","caller":"traceutil/trace.go:171","msg":"trace[51149842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:169; }","duration":"131.887966ms","start":"2026-02-27T01:13:28.532247Z","end":"2026-02-27T01:13:28.664135Z","steps":["trace[51149842] 'agreement among raft nodes before linearized reading' (duration: 131.728631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:13:28.664468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.658132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-27T01:13:28.66454Z","caller":"traceutil/trace.go:171","msg":"trace[1613744829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:169; }","duration":"132.764545ms","start":"2026-02-27T01:13:28.531758Z","end":"2026-02-27T01:13:28.664523Z","steps":["trace[1613744829] 'agreement among raft nodes before linearized reading' (duration: 132.154165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:13:29.29808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.066116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:13:29.298309Z","caller":"traceutil/trace.go:171","msg":"trace[2093886165] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"146.80268ms","start":"2026-02-27T01:13:29.151484Z","end":"2026-02-27T01:13:29.298286Z","steps":["trace[2093886165] 'process raft request' (duration: 29.425334ms)","trace[2093886165] 'compare' (duration: 116.910781ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:13:35.856456Z","caller":"traceutil/trace.go:171","msg":"trace[1041984996] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"126.009119ms","start":"2026-02-27T01:13:35.730422Z","end":"2026-02-27T01:13:35.856431Z","steps":["trace[1041984996] 'process raft request' (duration: 125.781112ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:13:37.910816Z","caller":"traceutil/trace.go:171","msg":"trace[196482672] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"130.147231ms","start":"2026-02-27T01:13:37.780645Z","end":"2026-02-27T01:13:37.910792Z","steps":["trace[196482672] 'process raft request' (duration: 129.960695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:13:38.460443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.632358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:13:38.460791Z","caller":"traceutil/trace.go:171","msg":"trace[490791468] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"246.605083ms","start":"2026-02-27T01:13:38.214156Z","end":"2026-02-27T01:13:38.460761Z","steps":["trace[490791468] 'process raft request' (duration: 136.315375ms)","trace[490791468] 'compare' (duration: 109.439592ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:13:41.404829Z","caller":"traceutil/trace.go:171","msg":"trace[1334591607] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:399; }","duration":"177.163663ms","start":"2026-02-27T01:13:41.227636Z","end":"2026-02-27T01:13:41.404799Z","steps":["trace[1334591607] 'read index received' (duration: 157.567648ms)","trace[1334591607] 'applied index is now lower than readState.Index' (duration: 19.594725ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:13:41.405217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.566716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-27T01:13:41.405296Z","caller":"traceutil/trace.go:171","msg":"trace[1383164645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:387; }","duration":"177.695329ms","start":"2026-02-27T01:13:41.22758Z","end":"2026-02-27T01:13:41.405276Z","steps":["trace[1383164645] 'agreement among raft nodes before linearized reading' (duration: 177.545335ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:13:41.405307Z","caller":"traceutil/trace.go:171","msg":"trace[575664875] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"375.272732ms","start":"2026-02-27T01:13:41.029772Z","end":"2026-02-27T01:13:41.405045Z","steps":["trace[575664875] 'process raft request' (duration: 355.414189ms)","trace[575664875] 'compare' (duration: 19.206944ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:13:41.405482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:13:41.029755Z","time spent":"375.640644ms","remote":"127.0.0.1:56018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4649,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:13:41.407497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.444682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:13:41.407667Z","caller":"traceutil/trace.go:171","msg":"trace[1018769016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:388; }","duration":"179.635987ms","start":"2026-02-27T01:13:41.228008Z","end":"2026-02-27T01:13:41.407644Z","steps":["trace[1018769016] 'agreement among raft nodes before linearized reading' (duration: 179.374149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:13:43.936582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.806242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:13:43.936668Z","caller":"traceutil/trace.go:171","msg":"trace[382566183] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"212.653941ms","start":"2026-02-27T01:13:43.724Z","end":"2026-02-27T01:13:43.936654Z","steps":["trace[382566183] 'process raft request' (duration: 59.714085ms)","trace[382566183] 'compare' (duration: 152.689079ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:13:43.938759Z","caller":"traceutil/trace.go:171","msg":"trace[831134210] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"209.041656ms","start":"2026-02-27T01:13:43.729708Z","end":"2026-02-27T01:13:43.93875Z","steps":["trace[831134210] 'process raft request' (duration: 209.010995ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:13:43.938901Z","caller":"traceutil/trace.go:171","msg":"trace[826833886] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"213.146176ms","start":"2026-02-27T01:13:43.725744Z","end":"2026-02-27T01:13:43.93889Z","steps":["trace[826833886] 'process raft request' (duration: 212.92458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:13:44.335344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.97397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:13:44.335437Z","caller":"traceutil/trace.go:171","msg":"trace[1257494365] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"108.783141ms","start":"2026-02-27T01:13:44.226639Z","end":"2026-02-27T01:13:44.335422Z","steps":["trace[1257494365] 'read index received' (duration: 30.681µs)","trace[1257494365] 'applied index is now lower than readState.Index' (duration: 108.75121ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:13:44.335514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.873274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:13:44.335548Z","caller":"traceutil/trace.go:171","msg":"trace[297639679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:425; }","duration":"108.932586ms","start":"2026-02-27T01:13:44.226605Z","end":"2026-02-27T01:13:44.335537Z","steps":["trace[297639679] 'agreement among raft nodes before linearized reading' (duration: 108.860334ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:13:44.335912Z","caller":"traceutil/trace.go:171","msg":"trace[1980529398] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"198.390035ms","start":"2026-02-27T01:13:44.137505Z","end":"2026-02-27T01:13:44.335895Z","steps":["trace[1980529398] 'process raft request' (duration: 68.688634ms)","trace[1980529398] 'compare' (duration: 128.889068ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:13:49.127873Z","caller":"traceutil/trace.go:171","msg":"trace[163235426] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"256.658785ms","start":"2026-02-27T01:13:48.871192Z","end":"2026-02-27T01:13:49.12785Z","steps":["trace[163235426] 'read index received' (duration: 256.50956ms)","trace[163235426] 'applied index is now lower than readState.Index' (duration: 148.305µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:13:49.128211Z","caller":"traceutil/trace.go:171","msg":"trace[496430725] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"578.779637ms","start":"2026-02-27T01:13:48.549421Z","end":"2026-02-27T01:13:49.1282Z","steps":["trace[496430725] 'process raft request' (duration: 578.280593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:13:49.128314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:13:48.549392Z","time spent":"578.856049ms","remote":"127.0.0.1:56102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:13:49.128441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.249061ms","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-02-27T01:13:49.128473Z","caller":"traceutil/trace.go:171","msg":"trace[1655945805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:435; }","duration":"257.309633ms","start":"2026-02-27T01:13:48.871153Z","end":"2026-02-27T01:13:49.128462Z","steps":["trace[1655945805] 'agreement among raft nodes before linearized reading' (duration: 257.257141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:13:49.561601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.911746ms","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-02-27T01:13:49.561736Z","caller":"traceutil/trace.go:171","msg":"trace[1637466541] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:435; }","duration":"358.06298ms","start":"2026-02-27T01:13:49.203653Z","end":"2026-02-27T01:13:49.561716Z","steps":["trace[1637466541] 'range keys from in-memory index tree' (duration: 357.896256ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:13:49.561902Z","caller":"traceutil/trace.go:171","msg":"trace[168130229] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"429.248056ms","start":"2026-02-27T01:13:49.132625Z","end":"2026-02-27T01:13:49.561873Z","steps":["trace[168130229] 'process raft request' (duration: 421.113658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:13:49.562052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:13:49.132607Z","time spent":"429.364429ms","remote":"127.0.0.1:56102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:13:49.56956Z","caller":"traceutil/trace.go:171","msg":"trace[433642151] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:451; }","duration":"332.570396ms","start":"2026-02-27T01:13:49.236973Z","end":"2026-02-27T01:13:49.569544Z","steps":["trace[433642151] 'read index received' (duration: 316.691131ms)","trace[433642151] 'applied index is now lower than readState.Index' (duration: 15.878605ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:13:49.569631Z","caller":"traceutil/trace.go:171","msg":"trace[480294121] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"436.037797ms","start":"2026-02-27T01:13:49.133573Z","end":"2026-02-27T01:13:49.56961Z","steps":["trace[480294121] 'process raft request' (duration: 435.854162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:13:49.56968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.575317ms","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-02-27T01:13:49.569699Z","caller":"traceutil/trace.go:171","msg":"trace[473173833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:437; }","duration":"122.644519ms","start":"2026-02-27T01:13:49.447048Z","end":"2026-02-27T01:13:49.569692Z","steps":["trace[473173833] 'agreement among raft nodes before linearized reading' (duration: 122.587517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:13:49.56972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.353621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-27T01:13:49.569721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.74083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:13:49.569758Z","caller":"traceutil/trace.go:171","msg":"trace[1339964130] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:437; }","duration":"107.428443ms","start":"2026-02-27T01:13:49.462319Z","end":"2026-02-27T01:13:49.569747Z","steps":["trace[1339964130] 'agreement among raft nodes before linearized reading' (duration: 107.35078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:13:49.569748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:13:49.133556Z","time spent":"436.122539ms","remote":"127.0.0.1:56102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:13:49.569778Z","caller":"traceutil/trace.go:171","msg":"trace[976000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:437; }","duration":"332.820253ms","start":"2026-02-27T01:13:49.236936Z","end":"2026-02-27T01:13:49.569756Z","steps":["trace[976000] 'agreement among raft nodes before linearized reading' (duration: 332.693889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:13:49.569812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:13:49.236912Z","time spent":"332.892784ms","remote":"127.0.0.1:55842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T01:13:58.09318Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831408696914857538,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T01:13:58.594426Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831408696914857538,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T01:13:58.945202Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.362642149s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T01:13:58.945698Z","caller":"traceutil/trace.go:171","msg":"trace[360268773] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"1.353481288s","start":"2026-02-27T01:13:57.592191Z","end":"2026-02-27T01:13:58.945672Z","steps":["trace[360268773] 'read index received' (duration: 1.353272532s)","trace[360268773] 'applied index is now lower than readState.Index' (duration: 207.896µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:13:58.945758Z","caller":"traceutil/trace.go:171","msg":"trace[144864939] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"1.363270316s","start":"2026-02-27T01:13:57.582453Z","end":"2026-02-27T01:13:58.945724Z","steps":["trace[144864939] 'process raft request' (duration: 1.363069721s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:13:58.945835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346801201s","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-02-27T01:13:58.945874Z","caller":"traceutil/trace.go:171","msg":"trace[419739698] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:489; }","duration":"1.346887864s","start":"2026-02-27T01:13:57.598973Z","end":"2026-02-27T01:13:58.945861Z","steps":["trace[419739698] 'agreement among raft nodes before linearized reading' (duration: 1.346798311s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:13:58.945911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:13:57.598955Z","time spent":"1.346947655s","remote":"127.0.0.1:56102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T01:13:58.945919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:13:57.582436Z","time spent":"1.363400859s","remote":"127.0.0.1:56016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:13:58.94591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.803617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:13:58.946089Z","caller":"traceutil/trace.go:171","msg":"trace[1039851503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:489; }","duration":"717.943081ms","start":"2026-02-27T01:13:58.228037Z","end":"2026-02-27T01:13:58.94598Z","steps":["trace[1039851503] 'agreement among raft nodes before linearized reading' (duration: 717.788867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:13:58.946096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346826501s","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-02-27T01:13:58.946133Z","caller":"traceutil/trace.go:171","msg":"trace[1923867809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:489; }","duration":"1.346882403s","start":"2026-02-27T01:13:57.599239Z","end":"2026-02-27T01:13:58.946121Z","steps":["trace[1923867809] 'agreement among raft nodes before linearized reading' (duration: 1.346804251s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:13:58.946169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:13:57.599227Z","time spent":"1.346936194s","remote":"127.0.0.1:56102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T01:13:58.946134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:13:58.228015Z","time spent":"718.111334ms","remote":"127.0.0.1:55842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T01:13:58.946257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.354050592s","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-02-27T01:13:58.946292Z","caller":"traceutil/trace.go:171","msg":"trace[2115001555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:489; }","duration":"1.354157605s","start":"2026-02-27T01:13:57.592124Z","end":"2026-02-27T01:13:58.946281Z","steps":["trace[2115001555] 'agreement among raft nodes before linearized reading' (duration: 1.353642041s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:13:58.946367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:13:57.592093Z","time spent":"1.354267088s","remote":"127.0.0.1:56102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T01:13:59.065916Z","caller":"traceutil/trace.go:171","msg":"trace[1011774362] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"115.6325ms","start":"2026-02-27T01:13:58.950257Z","end":"2026-02-27T01:13:59.06589Z","steps":["trace[1011774362] 'process raft request' (duration: 57.23596ms)","trace[1011774362] 'compare' (duration: 58.255557ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:13:59.070273Z","caller":"traceutil/trace.go:171","msg":"trace[294071967] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"117.550581ms","start":"2026-02-27T01:13:58.952685Z","end":"2026-02-27T01:13:59.070236Z","steps":["trace[294071967] 'process raft request' (duration: 117.491899ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:13:59.075093Z","caller":"traceutil/trace.go:171","msg":"trace[496366536] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"119.088101ms","start":"2026-02-27T01:13:58.951574Z","end":"2026-02-27T01:13:59.070662Z","steps":["trace[496366536] 'process raft request' (duration: 118.400373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:13:59.364472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.195599ms","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-02-27T01:13:59.36453Z","caller":"traceutil/trace.go:171","msg":"trace[277628355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:492; }","duration":"143.301542ms","start":"2026-02-27T01:13:59.221214Z","end":"2026-02-27T01:13:59.364516Z","steps":["trace[277628355] 'range keys from in-memory index tree' (duration: 143.070866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:13:59.364509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.741827ms","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-02-27T01:13:59.364655Z","caller":"traceutil/trace.go:171","msg":"trace[93388601] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:492; }","duration":"160.912101ms","start":"2026-02-27T01:13:59.20372Z","end":"2026-02-27T01:13:59.364632Z","steps":["trace[93388601] 'range keys from in-memory index tree' (duration: 160.715976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:14:05.189043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.146519ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-27T01:14:05.18915Z","caller":"traceutil/trace.go:171","msg":"trace[1711193485] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"101.288941ms","start":"2026-02-27T01:14:05.087843Z","end":"2026-02-27T01:14:05.189132Z","steps":["trace[1711193485] 'read index received' (duration: 45.281µs)","trace[1711193485] 'applied index is now lower than readState.Index' (duration: 101.24199ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:14:05.189335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.491176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-27T01:14:05.189434Z","caller":"traceutil/trace.go:171","msg":"trace[2125719274] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"101.630349ms","start":"2026-02-27T01:14:05.087784Z","end":"2026-02-27T01:14:05.189414Z","steps":["trace[2125719274] 'agreement among raft nodes before linearized reading' (duration: 101.418704ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:14:05.39526Z","caller":"traceutil/trace.go:171","msg":"trace[942282873] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"202.035368ms","start":"2026-02-27T01:14:05.193197Z","end":"2026-02-27T01:14:05.395232Z","steps":["trace[942282873] 'process raft request' (duration: 201.785992ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:14:05.395473Z","caller":"traceutil/trace.go:171","msg":"trace[1097120329] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"196.188772ms","start":"2026-02-27T01:14:05.199274Z","end":"2026-02-27T01:14:05.395463Z","steps":["trace[1097120329] 'process raft request' (duration: 195.885744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:14:06.252705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.695791ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:14:06.252934Z","caller":"traceutil/trace.go:171","msg":"trace[1323158611] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"853.181357ms","start":"2026-02-27T01:14:05.39973Z","end":"2026-02-27T01:14:06.252911Z","steps":["trace[1323158611] 'process raft request' (duration: 95.199828ms)","trace[1323158611] 'compare' (duration: 757.2189ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:14:06.25301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:14:05.399709Z","time spent":"853.268859ms","remote":"127.0.0.1:56102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:14:07.704134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.945484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-27T01:14:07.704189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.067939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-27T01:14:07.704224Z","caller":"traceutil/trace.go:171","msg":"trace[1654408576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:534; }","duration":"304.083988ms","start":"2026-02-27T01:14:07.400122Z","end":"2026-02-27T01:14:07.704206Z","steps":["trace[1654408576] 'range keys from in-memory index tree' (duration: 303.73126ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:14:07.70425Z","caller":"traceutil/trace.go:171","msg":"trace[512709291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:534; }","duration":"433.181341ms","start":"2026-02-27T01:14:07.271052Z","end":"2026-02-27T01:14:07.704234Z","steps":["trace[512709291] 'range keys from in-memory index tree' (duration: 432.853074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:14:07.704265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:14:07.400101Z","time spent":"304.15572ms","remote":"127.0.0.1:56102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T01:14:07.704296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:14:07.271029Z","time spent":"433.255584ms","remote":"127.0.0.1:56102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T01:14:07.704371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.787741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:14:07.704414Z","caller":"traceutil/trace.go:171","msg":"trace[591381055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"475.868094ms","start":"2026-02-27T01:14:07.228533Z","end":"2026-02-27T01:14:07.704401Z","steps":["trace[591381055] 'range keys from in-memory index tree' (duration: 475.704099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:14:07.704444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:14:07.228511Z","time spent":"475.925495ms","remote":"127.0.0.1:55842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T01:14:07.705318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.440818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-27T01:14:07.705475Z","caller":"traceutil/trace.go:171","msg":"trace[1017402265] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:534; }","duration":"434.055533ms","start":"2026-02-27T01:14:07.271394Z","end":"2026-02-27T01:14:07.70545Z","steps":["trace[1017402265] 'range keys from in-memory index tree' (duration: 433.230632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:14:07.705542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:14:07.271381Z","time spent":"434.139615ms","remote":"127.0.0.1:56102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T01:14:08.757819Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831408696914857640,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T01:14:09.153798Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.445221577s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T01:14:09.154132Z","caller":"traceutil/trace.go:171","msg":"trace[961294459] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.445639607s","start":"2026-02-27T01:14:07.708469Z","end":"2026-02-27T01:14:09.154109Z","steps":["trace[961294459] 'process raft request' (duration: 1.445528144s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:14:09.154265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:14:07.708451Z","time spent":"1.445736429s","remote":"127.0.0.1:56102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:14:09.1667Z","caller":"traceutil/trace.go:171","msg":"trace[673755894] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:555; }","duration":"909.587316ms","start":"2026-02-27T01:14:08.25708Z","end":"2026-02-27T01:14:09.166667Z","steps":["trace[673755894] 'read index received' (duration: 897.159714ms)","trace[673755894] 'applied index is now lower than readState.Index' (duration: 12.426362ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:14:09.166726Z","caller":"traceutil/trace.go:171","msg":"trace[985861112] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.458237373s","start":"2026-02-27T01:14:07.708471Z","end":"2026-02-27T01:14:09.166708Z","steps":["trace[985861112] 'process raft request' (duration: 1.458007858s)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:14:09.166826Z","caller":"traceutil/trace.go:171","msg":"trace[477883689] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.457802492s","start":"2026-02-27T01:14:07.708982Z","end":"2026-02-27T01:14:09.166784Z","steps":["trace[477883689] 'process raft request' (duration: 1.457642508s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:14:09.166875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:14:07.708455Z","time spent":"1.458333235s","remote":"127.0.0.1:56102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:14:09.166947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.856392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-27T01:14:09.166969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:14:07.708968Z","time spent":"1.457916495s","remote":"127.0.0.1:56102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:14:09.167002Z","caller":"traceutil/trace.go:171","msg":"trace[2045238437] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"909.994696ms","start":"2026-02-27T01:14:08.256995Z","end":"2026-02-27T01:14:09.16699Z","steps":["trace[2045238437] 'agreement among raft nodes before linearized reading' (duration: 909.794441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:14:09.167031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:14:08.25697Z","time spent":"910.056128ms","remote":"127.0.0.1:56102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-27T01:14:14.674206Z","caller":"traceutil/trace.go:171","msg":"trace[1171458236] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"177.2405ms","start":"2026-02-27T01:14:14.496939Z","end":"2026-02-27T01:14:14.674179Z","steps":["trace[1171458236] 'read index received' (duration: 173.044291ms)","trace[1171458236] 'applied index is now lower than readState.Index' (duration: 4.195369ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:14:14.67426Z","caller":"traceutil/trace.go:171","msg":"trace[1595715788] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"265.853439ms","start":"2026-02-27T01:14:14.40838Z","end":"2026-02-27T01:14:14.674234Z","steps":["trace[1595715788] 'process raft request' (duration: 261.926556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:14:14.674567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.605818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-27T01:14:14.674617Z","caller":"traceutil/trace.go:171","msg":"trace[1061293287] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:609; }","duration":"177.727001ms","start":"2026-02-27T01:14:14.496876Z","end":"2026-02-27T01:14:14.674603Z","steps":["trace[1061293287] 'agreement among raft nodes before linearized reading' (duration: 177.403463ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:15:33.841406Z","caller":"traceutil/trace.go:171","msg":"trace[1187938693] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"116.324988ms","start":"2026-02-27T01:15:33.725019Z","end":"2026-02-27T01:15:33.841344Z","steps":["trace[1187938693] 'process raft request' (duration: 97.167852ms)","trace[1187938693] 'compare' (duration: 18.834181ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:15:33.841618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.086857ms","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-02-27T01:15:33.841696Z","caller":"traceutil/trace.go:171","msg":"trace[1957147774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:964; }","duration":"115.228379ms","start":"2026-02-27T01:15:33.726448Z","end":"2026-02-27T01:15:33.841676Z","steps":["trace[1957147774] 'agreement among raft nodes before linearized reading' (duration: 114.944074ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:15:33.841239Z","caller":"traceutil/trace.go:171","msg":"trace[1777302968] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"114.733251ms","start":"2026-02-27T01:15:33.72647Z","end":"2026-02-27T01:15:33.841204Z","steps":["trace[1777302968] 'read index received' (duration: 95.729578ms)","trace[1777302968] 'applied index is now lower than readState.Index' (duration: 19.001883ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:15:33.863303Z","caller":"traceutil/trace.go:171","msg":"trace[228484773] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"135.190509ms","start":"2026-02-27T01:15:33.728082Z","end":"2026-02-27T01:15:33.863272Z","steps":["trace[228484773] 'process raft request' (duration: 135.062007ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:15:34.313015Z","caller":"traceutil/trace.go:171","msg":"trace[1211864485] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"319.121893ms","start":"2026-02-27T01:15:33.993834Z","end":"2026-02-27T01:15:34.312956Z","steps":["trace[1211864485] 'process raft request' (duration: 318.823968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:15:34.313341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:15:33.993805Z","time spent":"319.407868ms","remote":"127.0.0.1:56016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5572,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}