{"level":"warn","ts":"2026-04-20T11:55:11.375483Z","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-20T11:55:11.375588Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.163: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.163:2380","--initial-cluster=instance=https://199.19.213.163:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.163:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.163: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-20T11:55:11.375707Z","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-20T11:55:11.375725Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.163:2380"]} {"level":"info","ts":"2026-04-20T11:55:11.375777Z","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-20T11:55:11.376414Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"]} {"level":"info","ts":"2026-04-20T11:55:11.376541Z","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.163:2380"],"listen-peer-urls":["https://199.19.213.163:2380"],"advertise-client-urls":["https://199.19.213.163:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.163: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-20T11:55:11.383875Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.035304ms"} {"level":"info","ts":"2026-04-20T11:55:11.39152Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e7941a908d261618","cluster-id":"5b6cb15db6ea8c10"} {"level":"info","ts":"2026-04-20T11:55:11.391677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T11:55:11.391723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became follower at term 0"} {"level":"info","ts":"2026-04-20T11:55:11.391752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e7941a908d261618 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T11:55:11.391769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became follower at term 1"} {"level":"info","ts":"2026-04-20T11:55:11.391836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=(16686991727460226584)"} {"level":"warn","ts":"2026-04-20T11:55:11.399869Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T11:55:11.401961Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T11:55:11.403272Z","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-20T11:55:11.406614Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e7941a908d261618","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T11:55:11.406836Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e7941a908d261618","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T11:55:11.406981Z","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-20T11:55:11.407045Z","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-20T11:55:11.407056Z","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-20T11:55:11.40874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=(16686991727460226584)"} {"level":"info","ts":"2026-04-20T11:55:11.40915Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5b6cb15db6ea8c10","local-member-id":"e7941a908d261618","added-peer-id":"e7941a908d261618","added-peer-peer-urls":["https://199.19.213.163:2380"]} {"level":"info","ts":"2026-04-20T11:55:11.411409Z","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-20T11:55:11.411574Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.163:2380"} {"level":"info","ts":"2026-04-20T11:55:11.411679Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.163:2380"} {"level":"info","ts":"2026-04-20T11:55:11.412049Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e7941a908d261618","initial-advertise-peer-urls":["https://199.19.213.163:2380"],"listen-peer-urls":["https://199.19.213.163:2380"],"advertise-client-urls":["https://199.19.213.163:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T11:55:11.412126Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T11:55:12.39296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T11:55:12.393047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T11:55:12.393101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 received MsgPreVoteResp from e7941a908d261618 at term 1"} {"level":"info","ts":"2026-04-20T11:55:12.393127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became candidate at term 2"} {"level":"info","ts":"2026-04-20T11:55:12.393157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 received MsgVoteResp from e7941a908d261618 at term 2"} {"level":"info","ts":"2026-04-20T11:55:12.393195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became leader at term 2"} {"level":"info","ts":"2026-04-20T11:55:12.393225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e7941a908d261618 elected leader e7941a908d261618 at term 2"} {"level":"info","ts":"2026-04-20T11:55:12.394536Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e7941a908d261618","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.163:2379]}","request-path":"/0/members/e7941a908d261618/attributes","cluster-id":"5b6cb15db6ea8c10","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T11:55:12.394536Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T11:55:12.394576Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T11:55:12.394649Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:55:12.394864Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T11:55:12.394924Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T11:55:12.396089Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5b6cb15db6ea8c10","local-member-id":"e7941a908d261618","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:55:12.396242Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:55:12.396289Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:55:12.396832Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T11:55:12.398181Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.163:2379"} {"level":"info","ts":"2026-04-20T11:55:15.266687Z","caller":"traceutil/trace.go:171","msg":"trace[1433318690] transaction","detail":"{read_only:false; response_revision:156; number_of_response:1; }","duration":"237.710766ms","start":"2026-04-20T11:55:15.028927Z","end":"2026-04-20T11:55:15.266638Z","steps":["trace[1433318690] 'process raft request' (duration: 160.460195ms)","trace[1433318690] 'compare' (duration: 77.062617ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:55:16.414421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.582646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T11:55:16.414743Z","caller":"traceutil/trace.go:171","msg":"trace[1387846354] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"860.596723ms","start":"2026-04-20T11:55:15.55413Z","end":"2026-04-20T11:55:16.414727Z","steps":["trace[1387846354] 'process raft request' (duration: 860.448731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:16.414823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:15.554112Z","time spent":"860.670654ms","remote":"127.0.0.1:41712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":720,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T11:55:16.414665Z","caller":"traceutil/trace.go:171","msg":"trace[1302424273] linearizableReadLoop","detail":"{readStateIndex:209; appliedIndex:207; }","duration":"557.283539ms","start":"2026-04-20T11:55:15.857358Z","end":"2026-04-20T11:55:16.414641Z","steps":["trace[1302424273] 'read index received' (duration: 89.082µs)","trace[1302424273] 'applied index is now lower than readState.Index' (duration: 557.193357ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:55:16.41499Z","caller":"traceutil/trace.go:171","msg":"trace[721277277] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"861.973149ms","start":"2026-04-20T11:55:15.552946Z","end":"2026-04-20T11:55:16.414919Z","steps":["trace[721277277] 'process raft request' (duration: 119.305752ms)","trace[721277277] 'compare' (duration: 740.987925ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:55:16.415166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.827288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T11:55:16.415227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:15.552934Z","time spent":"862.175503ms","remote":"127.0.0.1:39802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T11:55:16.415224Z","caller":"traceutil/trace.go:171","msg":"trace[715534172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:205; }","duration":"557.95348ms","start":"2026-04-20T11:55:15.857258Z","end":"2026-04-20T11:55:16.415212Z","steps":["trace[715534172] 'agreement among raft nodes before linearized reading' (duration: 557.794957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:16.415395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:15.857143Z","time spent":"558.241346ms","remote":"127.0.0.1:39574","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-20T11:55:17.276395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.630103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T11:55:17.276559Z","caller":"traceutil/trace.go:171","msg":"trace[2143932388] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:210; }","duration":"742.115517ms","start":"2026-04-20T11:55:16.534422Z","end":"2026-04-20T11:55:17.276537Z","steps":["trace[2143932388] 'read index received' (duration: 285.168929ms)","trace[2143932388] 'applied index is now lower than readState.Index' (duration: 456.945118ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:55:17.276766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.304102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:55:17.276849Z","caller":"traceutil/trace.go:171","msg":"trace[1379779604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:207; }","duration":"742.484525ms","start":"2026-04-20T11:55:16.534349Z","end":"2026-04-20T11:55:17.276833Z","steps":["trace[1379779604] 'agreement among raft nodes before linearized reading' (duration: 742.25322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:17.276894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:16.534323Z","time spent":"742.562136ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T11:55:17.276709Z","caller":"traceutil/trace.go:171","msg":"trace[396015325] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"768.773124ms","start":"2026-04-20T11:55:16.507825Z","end":"2026-04-20T11:55:17.276598Z","steps":["trace[396015325] 'process raft request' (duration: 311.846417ms)","trace[396015325] 'compare' (duration: 456.427948ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:55:17.27701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.492956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T11:55:17.27703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.414833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T11:55:17.27709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:16.507804Z","time spent":"769.178872ms","remote":"127.0.0.1:41712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T11:55:17.277125Z","caller":"traceutil/trace.go:171","msg":"trace[1697487834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:207; }","duration":"358.579216ms","start":"2026-04-20T11:55:16.918522Z","end":"2026-04-20T11:55:17.277102Z","steps":["trace[1697487834] 'agreement among raft nodes before linearized reading' (duration: 358.432543ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:17.277125Z","caller":"traceutil/trace.go:171","msg":"trace[674110247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:207; }","duration":"419.66205ms","start":"2026-04-20T11:55:16.857433Z","end":"2026-04-20T11:55:17.277095Z","steps":["trace[674110247] 'agreement among raft nodes before linearized reading' (duration: 419.471466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:17.277186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:16.857406Z","time spent":"419.766481ms","remote":"127.0.0.1:39574","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-20T11:55:17.277186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:16.91849Z","time spent":"358.683808ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T11:55:19.830281Z","caller":"traceutil/trace.go:171","msg":"trace[454164677] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"248.858746ms","start":"2026-04-20T11:55:19.581385Z","end":"2026-04-20T11:55:19.830243Z","steps":["trace[454164677] 'process raft request' (duration: 248.628752ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:19.830249Z","caller":"traceutil/trace.go:171","msg":"trace[993506067] transaction","detail":"{read_only:false; number_of_response:0; response_revision:303; }","duration":"249.668762ms","start":"2026-04-20T11:55:19.580525Z","end":"2026-04-20T11:55:19.830194Z","steps":["trace[993506067] 'process raft request' (duration: 249.392637ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:19.830428Z","caller":"traceutil/trace.go:171","msg":"trace[1755097956] transaction","detail":"{read_only:false; number_of_response:0; response_revision:304; }","duration":"248.481989ms","start":"2026-04-20T11:55:19.58193Z","end":"2026-04-20T11:55:19.830412Z","steps":["trace[1755097956] 'process raft request' (duration: 248.229225ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:19.830324Z","caller":"traceutil/trace.go:171","msg":"trace[2036221696] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"251.634539ms","start":"2026-04-20T11:55:19.578617Z","end":"2026-04-20T11:55:19.830251Z","steps":["trace[2036221696] 'process raft request' (duration: 218.463919ms)","trace[2036221696] 'compare' (duration: 26.79762ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:55:19.830599Z","caller":"traceutil/trace.go:171","msg":"trace[1800758493] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"247.586573ms","start":"2026-04-20T11:55:19.582944Z","end":"2026-04-20T11:55:19.830531Z","steps":["trace[1800758493] 'process raft request' (duration: 247.276607ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:19.830702Z","caller":"traceutil/trace.go:171","msg":"trace[911916838] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"247.556593ms","start":"2026-04-20T11:55:19.583112Z","end":"2026-04-20T11:55:19.830669Z","steps":["trace[911916838] 'process raft request' (duration: 247.306917ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:19.830769Z","caller":"traceutil/trace.go:171","msg":"trace[1885439139] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:308; }","duration":"184.172967ms","start":"2026-04-20T11:55:19.646539Z","end":"2026-04-20T11:55:19.830712Z","steps":["trace[1885439139] 'read index received' (duration: 150.585648ms)","trace[1885439139] 'applied index is now lower than readState.Index' (duration: 33.585239ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:55:19.830807Z","caller":"traceutil/trace.go:171","msg":"trace[972034870] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"180.876593ms","start":"2026-04-20T11:55:19.649893Z","end":"2026-04-20T11:55:19.83077Z","steps":["trace[972034870] 'process raft request' (duration: 180.615058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:19.831182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.604585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"warn","ts":"2026-04-20T11:55:19.831456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.122816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-20T11:55:19.831508Z","caller":"traceutil/trace.go:171","msg":"trace[710243689] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:307; }","duration":"183.196778ms","start":"2026-04-20T11:55:19.648287Z","end":"2026-04-20T11:55:19.831484Z","steps":["trace[710243689] 'agreement among raft nodes before linearized reading' (duration: 182.642458ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:19.831859Z","caller":"traceutil/trace.go:171","msg":"trace[1266220029] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:307; }","duration":"184.820088ms","start":"2026-04-20T11:55:19.646504Z","end":"2026-04-20T11:55:19.831324Z","steps":["trace[1266220029] 'agreement among raft nodes before linearized reading' (duration: 184.39921ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:43.577025Z","caller":"traceutil/trace.go:171","msg":"trace[1850602386] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"129.599728ms","start":"2026-04-20T11:55:43.447387Z","end":"2026-04-20T11:55:43.576986Z","steps":["trace[1850602386] 'process raft request' (duration: 96.600329ms)","trace[1850602386] 'compare' (duration: 32.752194ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:55:46.193423Z","caller":"traceutil/trace.go:171","msg":"trace[1240661048] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"123.40425ms","start":"2026-04-20T11:55:46.069989Z","end":"2026-04-20T11:55:46.193394Z","steps":["trace[1240661048] 'process raft request' (duration: 123.233296ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:51.762755Z","caller":"traceutil/trace.go:171","msg":"trace[285181631] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:513; }","duration":"139.336561ms","start":"2026-04-20T11:55:51.623386Z","end":"2026-04-20T11:55:51.762722Z","steps":["trace[285181631] 'read index received' (duration: 139.194779ms)","trace[285181631] 'applied index is now lower than readState.Index' (duration: 140.522µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:55:51.762922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.515365ms","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-20T11:55:51.762882Z","caller":"traceutil/trace.go:171","msg":"trace[698505796] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"217.426629ms","start":"2026-04-20T11:55:51.545409Z","end":"2026-04-20T11:55:51.762836Z","steps":["trace[698505796] 'process raft request' (duration: 217.121323ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:51.762963Z","caller":"traceutil/trace.go:171","msg":"trace[1514617526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:497; }","duration":"139.647008ms","start":"2026-04-20T11:55:51.623305Z","end":"2026-04-20T11:55:51.762952Z","steps":["trace[1514617526] 'agreement among raft nodes before linearized reading' (duration: 139.543876ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:54.444678Z","caller":"traceutil/trace.go:171","msg":"trace[16976817] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"215.889541ms","start":"2026-04-20T11:55:54.228736Z","end":"2026-04-20T11:55:54.444626Z","steps":["trace[16976817] 'process raft request' (duration: 215.616035ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:54.728964Z","caller":"traceutil/trace.go:171","msg":"trace[1226487099] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"394.579841ms","start":"2026-04-20T11:55:54.334324Z","end":"2026-04-20T11:55:54.728904Z","steps":["trace[1226487099] 'process raft request' (duration: 359.104605ms)","trace[1226487099] 'compare' (duration: 35.21036ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:55:54.72924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:54.334293Z","time spent":"394.793975ms","remote":"127.0.0.1:39642","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-20T11:55:54.729351Z","caller":"traceutil/trace.go:171","msg":"trace[1209372910] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"355.836344ms","start":"2026-04-20T11:55:54.373489Z","end":"2026-04-20T11:55:54.729326Z","steps":["trace[1209372910] 'process raft request' (duration: 355.479717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:54.729445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:54.373449Z","time spent":"355.955085ms","remote":"127.0.0.1:39642","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-20T11:55:54.729492Z","caller":"traceutil/trace.go:171","msg":"trace[291093749] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"374.836935ms","start":"2026-04-20T11:55:54.354626Z","end":"2026-04-20T11:55:54.729463Z","steps":["trace[291093749] 'process raft request' (duration: 374.205573ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:54.729536Z","caller":"traceutil/trace.go:171","msg":"trace[1311622710] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"372.863417ms","start":"2026-04-20T11:55:54.356658Z","end":"2026-04-20T11:55:54.729522Z","steps":["trace[1311622710] 'process raft request' (duration: 372.237636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:54.729582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:54.354611Z","time spent":"374.928947ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T11:55:54.729672Z","caller":"traceutil/trace.go:171","msg":"trace[1252566684] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"355.323864ms","start":"2026-04-20T11:55:54.374338Z","end":"2026-04-20T11:55:54.729661Z","steps":["trace[1252566684] 'process raft request' (duration: 354.686992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:54.729714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:54.374325Z","time spent":"355.366744ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T11:55:54.729726Z","caller":"traceutil/trace.go:171","msg":"trace[1615831781] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"327.091846ms","start":"2026-04-20T11:55:54.402597Z","end":"2026-04-20T11:55:54.729689Z","steps":["trace[1615831781] 'process raft request' (duration: 326.906473ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:54.729688Z","caller":"traceutil/trace.go:171","msg":"trace[233086523] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:556; }","duration":"309.509941ms","start":"2026-04-20T11:55:54.42014Z","end":"2026-04-20T11:55:54.72965Z","steps":["trace[233086523] 'read index received' (duration: 24.431826ms)","trace[233086523] 'applied index is now lower than readState.Index' (duration: 285.076855ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:55:54.729796Z","caller":"traceutil/trace.go:171","msg":"trace[1784531598] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"347.925022ms","start":"2026-04-20T11:55:54.381853Z","end":"2026-04-20T11:55:54.729778Z","steps":["trace[1784531598] 'process raft request' (duration: 347.394152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:54.72984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:54.402582Z","time spent":"327.183648ms","remote":"127.0.0.1:39642","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-20T11:55:54.729837Z","caller":"traceutil/trace.go:171","msg":"trace[821457279] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"309.98375ms","start":"2026-04-20T11:55:54.419832Z","end":"2026-04-20T11:55:54.729816Z","steps":["trace[821457279] 'process raft request' (duration: 309.753936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:54.729903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:54.381818Z","time spent":"348.029424ms","remote":"127.0.0.1:39642","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-20T11:55:54.729914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:54.419792Z","time spent":"310.087732ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T11:55:54.729935Z","caller":"traceutil/trace.go:171","msg":"trace[1505192995] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"353.093991ms","start":"2026-04-20T11:55:54.37683Z","end":"2026-04-20T11:55:54.729924Z","steps":["trace[1505192995] 'process raft request' (duration: 352.259166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:54.729944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.763776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:55:54.729985Z","caller":"traceutil/trace.go:171","msg":"trace[1582108562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"309.886838ms","start":"2026-04-20T11:55:54.420087Z","end":"2026-04-20T11:55:54.729974Z","steps":["trace[1582108562] 'agreement among raft nodes before linearized reading' (duration: 309.748826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:54.729986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:54.376823Z","time spent":"353.137392ms","remote":"127.0.0.1:39642","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-20T11:55:54.729722Z","caller":"traceutil/trace.go:171","msg":"trace[236436694] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"346.968915ms","start":"2026-04-20T11:55:54.38271Z","end":"2026-04-20T11:55:54.729679Z","steps":["trace[236436694] 'process raft request' (duration: 346.69647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:54.730027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:54.42006Z","time spent":"309.957519ms","remote":"127.0.0.1:39574","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-20T11:55:54.730039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:54.382697Z","time spent":"347.320661ms","remote":"127.0.0.1:39642","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-20T11:55:54.730097Z","caller":"traceutil/trace.go:171","msg":"trace[455958454] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"348.234368ms","start":"2026-04-20T11:55:54.381839Z","end":"2026-04-20T11:55:54.730074Z","steps":["trace[455958454] 'process raft request' (duration: 347.340872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:54.730184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:54.356649Z","time spent":"372.909418ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T11:55:54.730224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:54.381826Z","time spent":"348.35081ms","remote":"127.0.0.1:39642","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-20T11:55:54.740926Z","caller":"traceutil/trace.go:171","msg":"trace[785646327] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"233.412423ms","start":"2026-04-20T11:55:54.507461Z","end":"2026-04-20T11:55:54.740873Z","steps":["trace[785646327] 'process raft request' (duration: 232.242031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:54.741406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.646493ms","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-20T11:55:54.741447Z","caller":"traceutil/trace.go:171","msg":"trace[1642424453] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:550; }","duration":"184.751956ms","start":"2026-04-20T11:55:54.556684Z","end":"2026-04-20T11:55:54.741436Z","steps":["trace[1642424453] 'agreement among raft nodes before linearized reading' (duration: 184.620953ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:59.929299Z","caller":"traceutil/trace.go:171","msg":"trace[1236639858] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"122.152805ms","start":"2026-04-20T11:55:59.807108Z","end":"2026-04-20T11:55:59.92926Z","steps":["trace[1236639858] 'process raft request' (duration: 121.888401ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:00.029194Z","caller":"traceutil/trace.go:171","msg":"trace[1987828144] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"129.319101ms","start":"2026-04-20T11:55:59.899835Z","end":"2026-04-20T11:56:00.029154Z","steps":["trace[1987828144] 'read index received' (duration: 29.221247ms)","trace[1987828144] 'applied index is now lower than readState.Index' (duration: 100.096654ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:00.0293Z","caller":"traceutil/trace.go:171","msg":"trace[2082899865] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"135.122262ms","start":"2026-04-20T11:55:59.894146Z","end":"2026-04-20T11:56:00.029268Z","steps":["trace[2082899865] 'process raft request' (duration: 129.122668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:00.02943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.567206ms","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-20T11:56:00.029468Z","caller":"traceutil/trace.go:171","msg":"trace[1882565707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"129.645417ms","start":"2026-04-20T11:55:59.89981Z","end":"2026-04-20T11:56:00.029455Z","steps":["trace[1882565707] 'agreement among raft nodes before linearized reading' (duration: 129.471964ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:01.492514Z","caller":"traceutil/trace.go:171","msg":"trace[1212221725] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"487.164284ms","start":"2026-04-20T11:56:01.005267Z","end":"2026-04-20T11:56:01.492431Z","steps":["trace[1212221725] 'process raft request' (duration: 486.511432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:01.493012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:01.00521Z","time spent":"487.425849ms","remote":"127.0.0.1:39696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T11:56:02.072833Z","caller":"traceutil/trace.go:171","msg":"trace[722031419] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"139.046617ms","start":"2026-04-20T11:56:01.933649Z","end":"2026-04-20T11:56:02.072696Z","steps":["trace[722031419] 'read index received' (duration: 136.668662ms)","trace[722031419] 'applied index is now lower than readState.Index' (duration: 2.374545ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:56:02.073147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.456765ms","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-20T11:56:02.073199Z","caller":"traceutil/trace.go:171","msg":"trace[1032639180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"139.579007ms","start":"2026-04-20T11:56:01.933597Z","end":"2026-04-20T11:56:02.073176Z","steps":["trace[1032639180] 'agreement among raft nodes before linearized reading' (duration: 139.338453ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:02.073322Z","caller":"traceutil/trace.go:171","msg":"trace[391325911] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"377.648879ms","start":"2026-04-20T11:56:01.695622Z","end":"2026-04-20T11:56:02.073271Z","steps":["trace[391325911] 'process raft request' (duration: 375.08565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:02.073491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:01.695575Z","time spent":"377.824952ms","remote":"127.0.0.1:39802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:56:02.899958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.49861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T11:56:02.900179Z","caller":"traceutil/trace.go:171","msg":"trace[1952301493] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:669; }","duration":"801.639009ms","start":"2026-04-20T11:56:02.09852Z","end":"2026-04-20T11:56:02.900159Z","steps":["trace[1952301493] 'read index received' (duration: 14.478975ms)","trace[1952301493] 'applied index is now lower than readState.Index' (duration: 787.158974ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:02.900266Z","caller":"traceutil/trace.go:171","msg":"trace[754090684] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"820.302785ms","start":"2026-04-20T11:56:02.079921Z","end":"2026-04-20T11:56:02.900224Z","steps":["trace[754090684] 'process raft request' (duration: 820.172572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:02.900386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.861493ms","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-20T11:56:02.900345Z","caller":"traceutil/trace.go:171","msg":"trace[1832519731] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"833.283532ms","start":"2026-04-20T11:56:02.067001Z","end":"2026-04-20T11:56:02.900285Z","steps":["trace[1832519731] 'process raft request' (duration: 46.330102ms)","trace[1832519731] 'compare' (duration: 786.086233ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:02.900428Z","caller":"traceutil/trace.go:171","msg":"trace[4627425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"801.929945ms","start":"2026-04-20T11:56:02.098487Z","end":"2026-04-20T11:56:02.900417Z","steps":["trace[4627425] 'agreement among raft nodes before linearized reading' (duration: 801.779762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:02.900507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:02.098466Z","time spent":"802.031347ms","remote":"127.0.0.1:39860","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-20T11:56:02.9005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:02.079895Z","time spent":"820.471127ms","remote":"127.0.0.1:39860","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-20T11:56:02.900578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:02.066958Z","time spent":"833.485165ms","remote":"127.0.0.1:39696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:56:02.90053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.352474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:56:02.90064Z","caller":"traceutil/trace.go:171","msg":"trace[61481981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"479.61166ms","start":"2026-04-20T11:56:02.421019Z","end":"2026-04-20T11:56:02.90063Z","steps":["trace[61481981] 'agreement among raft nodes before linearized reading' (duration: 479.395836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:02.900671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:02.420988Z","time spent":"479.675301ms","remote":"127.0.0.1:39574","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-20T11:56:02.900801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.751482ms","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-20T11:56:02.900893Z","caller":"traceutil/trace.go:171","msg":"trace[2076140602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"355.867813ms","start":"2026-04-20T11:56:02.544993Z","end":"2026-04-20T11:56:02.900861Z","steps":["trace[2076140602] 'agreement among raft nodes before linearized reading' (duration: 355.585788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:02.900949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:02.544969Z","time spent":"355.968756ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T11:56:03.190125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.607994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T11:56:03.190391Z","caller":"traceutil/trace.go:171","msg":"trace[1716752827] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"232.862373ms","start":"2026-04-20T11:56:02.957507Z","end":"2026-04-20T11:56:03.190369Z","steps":["trace[1716752827] 'process raft request' (duration: 232.810672ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:03.190461Z","caller":"traceutil/trace.go:171","msg":"trace[603983380] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"284.546347ms","start":"2026-04-20T11:56:02.90582Z","end":"2026-04-20T11:56:03.190367Z","steps":["trace[603983380] 'process raft request' (duration: 284.420374ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:03.190553Z","caller":"traceutil/trace.go:171","msg":"trace[201614685] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"284.536546ms","start":"2026-04-20T11:56:02.905961Z","end":"2026-04-20T11:56:03.190497Z","steps":["trace[201614685] 'process raft request' (duration: 284.329312ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:03.190711Z","caller":"traceutil/trace.go:171","msg":"trace[1720315302] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"285.018955ms","start":"2026-04-20T11:56:02.905679Z","end":"2026-04-20T11:56:03.190698Z","steps":["trace[1720315302] 'process raft request' (duration: 98.730689ms)","trace[1720315302] 'compare' (duration: 185.372548ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:56:03.332862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.484613ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T11:56:03.333073Z","caller":"traceutil/trace.go:171","msg":"trace[1708301955] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"138.418585ms","start":"2026-04-20T11:56:03.19463Z","end":"2026-04-20T11:56:03.333048Z","steps":["trace[1708301955] 'read index received' (duration: 37.411µs)","trace[1708301955] 'applied index is now lower than readState.Index' (duration: 138.379324ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:56:03.333338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.672069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-20T11:56:03.333514Z","caller":"traceutil/trace.go:171","msg":"trace[2106241196] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:655; }","duration":"138.905874ms","start":"2026-04-20T11:56:03.194584Z","end":"2026-04-20T11:56:03.33349Z","steps":["trace[2106241196] 'agreement among raft nodes before linearized reading' (duration: 138.604998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:04.948238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.317517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:56:04.948378Z","caller":"traceutil/trace.go:171","msg":"trace[246090292] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:687; }","duration":"186.557341ms","start":"2026-04-20T11:56:04.76179Z","end":"2026-04-20T11:56:04.948347Z","steps":["trace[246090292] 'range keys from in-memory index tree' (duration: 186.220485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:19.827485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.526516ms","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-20T11:56:19.827613Z","caller":"traceutil/trace.go:171","msg":"trace[1384192482] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:814; }","duration":"497.722461ms","start":"2026-04-20T11:56:19.329864Z","end":"2026-04-20T11:56:19.827586Z","steps":["trace[1384192482] 'range keys from in-memory index tree' (duration: 497.280092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:19.827669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:19.329845Z","time spent":"497.812922ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T11:56:19.827777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.176791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T11:56:19.82785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.425635ms","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-20T11:56:19.827873Z","caller":"traceutil/trace.go:171","msg":"trace[1962402155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:814; }","duration":"406.292393ms","start":"2026-04-20T11:56:19.421559Z","end":"2026-04-20T11:56:19.827851Z","steps":["trace[1962402155] 'range keys from in-memory index tree' (duration: 406.002587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:19.82788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.051766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:56:19.827998Z","caller":"traceutil/trace.go:171","msg":"trace[840869570] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:814; }","duration":"555.25929ms","start":"2026-04-20T11:56:19.272713Z","end":"2026-04-20T11:56:19.827972Z","steps":["trace[840869570] 'count revisions from in-memory index tree' (duration: 554.919804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:19.82799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.551251ms","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-20T11:56:19.827924Z","caller":"traceutil/trace.go:171","msg":"trace[1616794427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:814; }","duration":"777.567998ms","start":"2026-04-20T11:56:19.050338Z","end":"2026-04-20T11:56:19.827906Z","steps":["trace[1616794427] 'range keys from in-memory index tree' (duration: 777.18593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:19.828057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:19.272674Z","time spent":"555.371062ms","remote":"127.0.0.1:41736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-04-20T11:56:19.828093Z","caller":"traceutil/trace.go:171","msg":"trace[102552167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:814; }","duration":"549.723654ms","start":"2026-04-20T11:56:19.278348Z","end":"2026-04-20T11:56:19.828072Z","steps":["trace[102552167] 'range keys from in-memory index tree' (duration: 549.282906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:19.828109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:19.05031Z","time spent":"777.780932ms","remote":"127.0.0.1:39860","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-20T11:56:19.828143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:19.278313Z","time spent":"549.819986ms","remote":"127.0.0.1:39860","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-20T11:56:19.828434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.781801ms","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-20T11:56:19.828526Z","caller":"traceutil/trace.go:171","msg":"trace[171896322] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:814; }","duration":"497.900693ms","start":"2026-04-20T11:56:19.330612Z","end":"2026-04-20T11:56:19.828513Z","steps":["trace[171896322] 'range keys from in-memory index tree' (duration: 496.798561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:19.828571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:19.330601Z","time spent":"497.961964ms","remote":"127.0.0.1:39860","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-20T11:56:19.827922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:19.421479Z","time spent":"406.434987ms","remote":"127.0.0.1:39574","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-20T11:56:27.701393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.829706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T11:56:27.701572Z","caller":"traceutil/trace.go:171","msg":"trace[255339240] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"127.455235ms","start":"2026-04-20T11:56:27.574095Z","end":"2026-04-20T11:56:27.70155Z","steps":["trace[255339240] 'process raft request' (duration: 25.352704ms)","trace[255339240] 'compare' (duration: 101.625513ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:30.272995Z","caller":"traceutil/trace.go:171","msg":"trace[2044720487] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"276.794309ms","start":"2026-04-20T11:56:29.99615Z","end":"2026-04-20T11:56:30.272944Z","steps":["trace[2044720487] 'read index received' (duration: 276.620896ms)","trace[2044720487] 'applied index is now lower than readState.Index' (duration: 171.113µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:30.27333Z","caller":"traceutil/trace.go:171","msg":"trace[954591448] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"332.566505ms","start":"2026-04-20T11:56:29.940695Z","end":"2026-04-20T11:56:30.273261Z","steps":["trace[954591448] 'process raft request' (duration: 332.001084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:30.27353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.35563ms","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-20T11:56:30.273636Z","caller":"traceutil/trace.go:171","msg":"trace[368027369] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:933; }","duration":"277.507202ms","start":"2026-04-20T11:56:29.996101Z","end":"2026-04-20T11:56:30.273608Z","steps":["trace[368027369] 'agreement among raft nodes before linearized reading' (duration: 277.216277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:30.273641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:29.940667Z","time spent":"332.81404ms","remote":"127.0.0.1:39860","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-20T11:56:30.276837Z","caller":"traceutil/trace.go:171","msg":"trace[521030164] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"231.84264ms","start":"2026-04-20T11:56:30.044954Z","end":"2026-04-20T11:56:30.276796Z","steps":["trace[521030164] 'process raft request' (duration: 231.617245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:30.276916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.668786ms","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-20T11:56:30.276953Z","caller":"traceutil/trace.go:171","msg":"trace[1933133859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"277.779038ms","start":"2026-04-20T11:56:29.999163Z","end":"2026-04-20T11:56:30.276942Z","steps":["trace[1933133859] 'agreement among raft nodes before linearized reading' (duration: 277.644926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:30.277035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.940811ms","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-20T11:56:30.277143Z","caller":"traceutil/trace.go:171","msg":"trace[789520426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:934; }","duration":"278.120155ms","start":"2026-04-20T11:56:29.998999Z","end":"2026-04-20T11:56:30.277119Z","steps":["trace[789520426] 'agreement among raft nodes before linearized reading' (duration: 277.751107ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:30.948062Z","caller":"traceutil/trace.go:171","msg":"trace[1842690633] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"112.096362ms","start":"2026-04-20T11:56:30.835939Z","end":"2026-04-20T11:56:30.948035Z","steps":["trace[1842690633] 'process raft request' (duration: 111.923269ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:34.613594Z","caller":"traceutil/trace.go:171","msg":"trace[1021338935] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"316.275703ms","start":"2026-04-20T11:56:34.297293Z","end":"2026-04-20T11:56:34.613569Z","steps":["trace[1021338935] 'read index received' (duration: 316.030899ms)","trace[1021338935] 'applied index is now lower than readState.Index' (duration: 243.444µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:34.61377Z","caller":"traceutil/trace.go:171","msg":"trace[2048980972] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"341.014216ms","start":"2026-04-20T11:56:34.272721Z","end":"2026-04-20T11:56:34.613735Z","steps":["trace[2048980972] 'process raft request' (duration: 340.67875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:34.613842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.532878ms","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-20T11:56:34.613886Z","caller":"traceutil/trace.go:171","msg":"trace[486465738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:947; }","duration":"316.619299ms","start":"2026-04-20T11:56:34.297255Z","end":"2026-04-20T11:56:34.613875Z","steps":["trace[486465738] 'agreement among raft nodes before linearized reading' (duration: 316.462056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:34.613927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:34.297243Z","time spent":"316.676541ms","remote":"127.0.0.1:39860","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-20T11:56:34.613945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.12851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:56:34.614071Z","caller":"traceutil/trace.go:171","msg":"trace[1402900822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:947; }","duration":"193.297103ms","start":"2026-04-20T11:56:34.42076Z","end":"2026-04-20T11:56:34.614057Z","steps":["trace[1402900822] 'agreement among raft nodes before linearized reading' (duration: 193.055009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:34.61399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.677786ms","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-20T11:56:34.614246Z","caller":"traceutil/trace.go:171","msg":"trace[2067786196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:947; }","duration":"310.983062ms","start":"2026-04-20T11:56:34.303212Z","end":"2026-04-20T11:56:34.614195Z","steps":["trace[2067786196] 'agreement among raft nodes before linearized reading' (duration: 310.565784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:34.614238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.172259ms","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-04-20T11:56:34.614265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.937602ms","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-20T11:56:34.614318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:34.303164Z","time spent":"311.138265ms","remote":"127.0.0.1:39860","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-20T11:56:34.614392Z","caller":"traceutil/trace.go:171","msg":"trace[1637033423] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:947; }","duration":"308.336581ms","start":"2026-04-20T11:56:34.30599Z","end":"2026-04-20T11:56:34.614327Z","steps":["trace[1637033423] 'agreement among raft nodes before linearized reading' (duration: 307.970285ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:34.614428Z","caller":"traceutil/trace.go:171","msg":"trace[119707765] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:947; }","duration":"309.162658ms","start":"2026-04-20T11:56:34.305234Z","end":"2026-04-20T11:56:34.614396Z","steps":["trace[119707765] 'agreement among raft nodes before linearized reading' (duration: 308.752039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:34.614457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:34.30598Z","time spent":"308.466434ms","remote":"127.0.0.1:39860","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-20T11:56:34.614487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:34.305219Z","time spent":"309.259139ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T11:56:34.614603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:34.27269Z","time spent":"341.167229ms","remote":"127.0.0.1:39802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T11:56:57.097282Z","caller":"traceutil/trace.go:171","msg":"trace[919711566] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1068; }","duration":"306.415675ms","start":"2026-04-20T11:56:56.79083Z","end":"2026-04-20T11:56:57.097246Z","steps":["trace[919711566] 'read index received' (duration: 306.285622ms)","trace[919711566] 'applied index is now lower than readState.Index' (duration: 129.003µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:57.097438Z","caller":"traceutil/trace.go:171","msg":"trace[903968862] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"315.852774ms","start":"2026-04-20T11:56:56.781572Z","end":"2026-04-20T11:56:57.097425Z","steps":["trace[903968862] 'process raft request' (duration: 315.496027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:57.097599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:56.781529Z","time spent":"315.944766ms","remote":"127.0.0.1:39860","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-20T11:56:57.097757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.308819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T11:56:57.097769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.922415ms","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-20T11:56:57.097841Z","caller":"traceutil/trace.go:171","msg":"trace[790907374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1034; }","duration":"299.419321ms","start":"2026-04-20T11:56:56.798401Z","end":"2026-04-20T11:56:57.09782Z","steps":["trace[790907374] 'agreement among raft nodes before linearized reading' (duration: 299.199316ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:57.097854Z","caller":"traceutil/trace.go:171","msg":"trace[1200680299] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1034; }","duration":"307.047028ms","start":"2026-04-20T11:56:56.790784Z","end":"2026-04-20T11:56:57.097831Z","steps":["trace[1200680299] 'agreement among raft nodes before linearized reading' (duration: 306.801782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:57.09789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:56.79076Z","time spent":"307.124008ms","remote":"127.0.0.1:39860","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-20T11:56:57.098078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.151886ms","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-20T11:56:57.098119Z","caller":"traceutil/trace.go:171","msg":"trace[1694631088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1034; }","duration":"299.205768ms","start":"2026-04-20T11:56:56.7989Z","end":"2026-04-20T11:56:57.098106Z","steps":["trace[1694631088] 'agreement among raft nodes before linearized reading' (duration: 299.069195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:57.605988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.412984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T11:56:57.606136Z","caller":"traceutil/trace.go:171","msg":"trace[275692546] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"503.189395ms","start":"2026-04-20T11:56:57.102923Z","end":"2026-04-20T11:56:57.606112Z","steps":["trace[275692546] 'process raft request' (duration: 72.567427ms)","trace[275692546] 'compare' (duration: 430.22274ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:56:57.606197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:57.102798Z","time spent":"503.374619ms","remote":"127.0.0.1:39860","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-20T11:56:57.681029Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592195824943278258,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T11:56:58.487256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.903864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T11:56:58.487438Z","caller":"traceutil/trace.go:171","msg":"trace[191733011] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"1.383302281s","start":"2026-04-20T11:56:57.104122Z","end":"2026-04-20T11:56:58.487424Z","steps":["trace[191733011] 'process raft request' (duration: 1.38321507s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:58.487504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:57.104106Z","time spent":"1.383364172s","remote":"127.0.0.1:39860","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-20T11:56:58.487493Z","caller":"traceutil/trace.go:171","msg":"trace[1002938944] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1070; }","duration":"1.307234648s","start":"2026-04-20T11:56:57.180231Z","end":"2026-04-20T11:56:58.487466Z","steps":["trace[1002938944] 'read index received' (duration: 787.061689ms)","trace[1002938944] 'applied index is now lower than readState.Index' (duration: 520.171649ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:58.487666Z","caller":"traceutil/trace.go:171","msg":"trace[317662805] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"1.383685458s","start":"2026-04-20T11:56:57.103971Z","end":"2026-04-20T11:56:58.487657Z","steps":["trace[317662805] 'process raft request' (duration: 863.309705ms)","trace[317662805] 'compare' (duration: 519.726211ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:56:58.487702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:57.103953Z","time spent":"1.383730449s","remote":"127.0.0.1:39860","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-20T11:56:58.487729Z","caller":"traceutil/trace.go:171","msg":"trace[1952717017] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"1.224521137s","start":"2026-04-20T11:56:57.263195Z","end":"2026-04-20T11:56:58.487716Z","steps":["trace[1952717017] 'process raft request' (duration: 1.224201251s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:58.487835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:57.263166Z","time spent":"1.224590497s","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:56:58.487939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068077768s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:56:58.487961Z","caller":"traceutil/trace.go:171","msg":"trace[1129631006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1038; }","duration":"1.068141558s","start":"2026-04-20T11:56:57.419813Z","end":"2026-04-20T11:56:58.487954Z","steps":["trace[1129631006] 'agreement among raft nodes before linearized reading' (duration: 1.068081537s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:58.487982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:57.419787Z","time spent":"1.06819093s","remote":"127.0.0.1:39574","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-20T11:56:58.488121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307891591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T11:56:58.488149Z","caller":"traceutil/trace.go:171","msg":"trace[302951927] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1038; }","duration":"1.307952112s","start":"2026-04-20T11:56:57.180181Z","end":"2026-04-20T11:56:58.488133Z","steps":["trace[302951927] 'agreement among raft nodes before linearized reading' (duration: 1.307878991s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:58.488167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:57.180156Z","time spent":"1.308007483s","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T11:56:58.488274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.775701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.163\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T11:56:58.48831Z","caller":"traceutil/trace.go:171","msg":"trace[396315805] range","detail":"{range_begin:/registry/masterleases/199.19.213.163; range_end:; response_count:1; response_revision:1038; }","duration":"923.885543ms","start":"2026-04-20T11:56:57.564414Z","end":"2026-04-20T11:56:58.488299Z","steps":["trace[396315805] 'agreement among raft nodes before linearized reading' (duration: 923.76596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:58.488338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:57.564377Z","time spent":"923.955444ms","remote":"127.0.0.1:39630","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.163\" "} {"level":"warn","ts":"2026-04-20T11:56:58.488417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.170387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T11:56:58.488451Z","caller":"traceutil/trace.go:171","msg":"trace[1798778071] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1038; }","duration":"645.243999ms","start":"2026-04-20T11:56:57.843199Z","end":"2026-04-20T11:56:58.488443Z","steps":["trace[1798778071] 'agreement among raft nodes before linearized reading' (duration: 645.172758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:58.488488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:57.843175Z","time spent":"645.30721ms","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-04-20T11:57:22.155237Z","caller":"traceutil/trace.go:171","msg":"trace[842077892] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"394.981874ms","start":"2026-04-20T11:57:21.760218Z","end":"2026-04-20T11:57:22.1552Z","steps":["trace[842077892] 'process raft request' (duration: 394.683468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:22.155526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:57:21.760201Z","time spent":"395.156317ms","remote":"127.0.0.1:39860","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-20T11:57:22.35156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.142204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:57:22.351655Z","caller":"traceutil/trace.go:171","msg":"trace[836011946] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1114; }","duration":"101.315267ms","start":"2026-04-20T11:57:22.25032Z","end":"2026-04-20T11:57:22.351635Z","steps":["trace[836011946] 'count revisions from in-memory index tree' (duration: 100.972641ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:23.635178Z","caller":"traceutil/trace.go:171","msg":"trace[1342627614] linearizableReadLoop","detail":"{readStateIndex:1161; appliedIndex:1160; }","duration":"215.694595ms","start":"2026-04-20T11:57:23.419455Z","end":"2026-04-20T11:57:23.63515Z","steps":["trace[1342627614] 'read index received' (duration: 215.304608ms)","trace[1342627614] 'applied index is now lower than readState.Index' (duration: 388.757µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:57:23.635356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.872179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:57:23.635398Z","caller":"traceutil/trace.go:171","msg":"trace[326772268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1120; }","duration":"215.978631ms","start":"2026-04-20T11:57:23.419409Z","end":"2026-04-20T11:57:23.635387Z","steps":["trace[326772268] 'agreement among raft nodes before linearized reading' (duration: 215.852738ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:23.635719Z","caller":"traceutil/trace.go:171","msg":"trace[1506351424] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"406.917562ms","start":"2026-04-20T11:57:23.228557Z","end":"2026-04-20T11:57:23.635475Z","steps":["trace[1506351424] 'process raft request' (duration: 406.358952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:23.6363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:57:23.228538Z","time spent":"407.652457ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T11:57:24.762365Z","caller":"traceutil/trace.go:171","msg":"trace[1252514820] linearizableReadLoop","detail":"{readStateIndex:1163; appliedIndex:1162; }","duration":"106.09913ms","start":"2026-04-20T11:57:24.656226Z","end":"2026-04-20T11:57:24.762325Z","steps":["trace[1252514820] 'read index received' (duration: 105.638521ms)","trace[1252514820] 'applied index is now lower than readState.Index' (duration: 458.889µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:57:24.762495Z","caller":"traceutil/trace.go:171","msg":"trace[1147466388] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"123.149665ms","start":"2026-04-20T11:57:24.639244Z","end":"2026-04-20T11:57:24.762394Z","steps":["trace[1147466388] 'process raft request' (duration: 122.770938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:24.762775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.515667ms","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-20T11:57:24.76283Z","caller":"traceutil/trace.go:171","msg":"trace[1377926700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1122; }","duration":"106.64247ms","start":"2026-04-20T11:57:24.656172Z","end":"2026-04-20T11:57:24.762814Z","steps":["trace[1377926700] 'agreement among raft nodes before linearized reading' (duration: 106.402805ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:24.959344Z","caller":"traceutil/trace.go:171","msg":"trace[1402262018] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"190.004805ms","start":"2026-04-20T11:57:24.769255Z","end":"2026-04-20T11:57:24.95926Z","steps":["trace[1402262018] 'process raft request' (duration: 176.155139ms)","trace[1402262018] 'compare' (duration: 13.354446ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:57:24.959361Z","caller":"traceutil/trace.go:171","msg":"trace[1695819462] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"189.910503ms","start":"2026-04-20T11:57:24.769406Z","end":"2026-04-20T11:57:24.959317Z","steps":["trace[1695819462] 'process raft request' (duration: 189.76207ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:42.467962Z","caller":"traceutil/trace.go:171","msg":"trace[286145] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1284; }","duration":"198.525347ms","start":"2026-04-20T11:57:42.26942Z","end":"2026-04-20T11:57:42.467945Z","steps":["trace[286145] 'read index received' (duration: 198.369994ms)","trace[286145] 'applied index is now lower than readState.Index' (duration: 154.453µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:57:42.468031Z","caller":"traceutil/trace.go:171","msg":"trace[267529398] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"621.362224ms","start":"2026-04-20T11:57:41.846653Z","end":"2026-04-20T11:57:42.468016Z","steps":["trace[267529398] 'process raft request' (duration: 621.190181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:42.468116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:57:41.846622Z","time spent":"621.434895ms","remote":"127.0.0.1:39860","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-20T11:57:42.468134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.71271ms","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-20T11:57:42.468169Z","caller":"traceutil/trace.go:171","msg":"trace[260399156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1239; }","duration":"198.788842ms","start":"2026-04-20T11:57:42.269373Z","end":"2026-04-20T11:57:42.468162Z","steps":["trace[260399156] 'agreement among raft nodes before linearized reading' (duration: 198.656849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:59.299193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.32181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-20T11:57:59.299283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.183995ms","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-20T11:57:59.299315Z","caller":"traceutil/trace.go:171","msg":"trace[1974447146] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1317; }","duration":"157.503263ms","start":"2026-04-20T11:57:59.141786Z","end":"2026-04-20T11:57:59.299289Z","steps":["trace[1974447146] 'range keys from in-memory index tree' (duration: 157.053464ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:59.299368Z","caller":"traceutil/trace.go:171","msg":"trace[575057245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1317; }","duration":"158.308228ms","start":"2026-04-20T11:57:59.141036Z","end":"2026-04-20T11:57:59.299345Z","steps":["trace[575057245] 'range keys from in-memory index tree' (duration: 158.019722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:58:05.745273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.081249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-20T11:58:05.74536Z","caller":"traceutil/trace.go:171","msg":"trace[658255732] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1378; }","duration":"151.232472ms","start":"2026-04-20T11:58:05.594105Z","end":"2026-04-20T11:58:05.745338Z","steps":["trace[658255732] 'range keys from in-memory index tree' (duration: 150.908816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:58:05.745382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.395595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:58:05.74549Z","caller":"traceutil/trace.go:171","msg":"trace[1734543592] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1378; }","duration":"140.556568ms","start":"2026-04-20T11:58:05.604907Z","end":"2026-04-20T11:58:05.745463Z","steps":["trace[1734543592] 'count revisions from in-memory index tree' (duration: 140.289364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:58:05.745692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.511675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T11:58:05.745739Z","caller":"traceutil/trace.go:171","msg":"trace[1900815142] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1378; }","duration":"188.598127ms","start":"2026-04-20T11:58:05.557126Z","end":"2026-04-20T11:58:05.745724Z","steps":["trace[1900815142] 'range keys from in-memory index tree' (duration: 188.173949ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:58:06.245657Z","caller":"traceutil/trace.go:171","msg":"trace[776406941] linearizableReadLoop","detail":"{readStateIndex:1431; appliedIndex:1430; }","duration":"276.871576ms","start":"2026-04-20T11:58:05.96857Z","end":"2026-04-20T11:58:06.245442Z","steps":["trace[776406941] 'read index received' (duration: 276.53547ms)","trace[776406941] 'applied index is now lower than readState.Index' (duration: 334.906µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:58:06.245751Z","caller":"traceutil/trace.go:171","msg":"trace[564665142] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"493.801513ms","start":"2026-04-20T11:58:05.751894Z","end":"2026-04-20T11:58:06.245695Z","steps":["trace[564665142] 'process raft request' (duration: 493.296174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:58:06.245904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.881383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:58:06.245972Z","caller":"traceutil/trace.go:171","msg":"trace[108396552] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1380; }","duration":"233.036946ms","start":"2026-04-20T11:58:06.012921Z","end":"2026-04-20T11:58:06.245958Z","steps":["trace[108396552] 'agreement among raft nodes before linearized reading' (duration: 232.903174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:58:06.246027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:58:05.751878Z","time spent":"494.007577ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:58:06.246019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.408545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-04-20T11:58:06.24611Z","caller":"traceutil/trace.go:171","msg":"trace[461188685] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1380; }","duration":"277.593249ms","start":"2026-04-20T11:58:05.968494Z","end":"2026-04-20T11:58:06.246088Z","steps":["trace[461188685] 'agreement among raft nodes before linearized reading' (duration: 277.267663ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:58:06.38211Z","caller":"traceutil/trace.go:171","msg":"trace[1360573713] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"128.455647ms","start":"2026-04-20T11:58:06.253608Z","end":"2026-04-20T11:58:06.382064Z","steps":["trace[1360573713] 'process raft request' (duration: 82.845104ms)","trace[1360573713] 'compare' (duration: 45.462389ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:58:07.134359Z","caller":"traceutil/trace.go:171","msg":"trace[217278240] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"115.347386ms","start":"2026-04-20T11:58:07.018993Z","end":"2026-04-20T11:58:07.134341Z","steps":["trace[217278240] 'process raft request' (duration: 115.216994ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:58:07.477569Z","caller":"traceutil/trace.go:171","msg":"trace[293700501] linearizableReadLoop","detail":"{readStateIndex:1438; appliedIndex:1437; }","duration":"241.924036ms","start":"2026-04-20T11:58:07.235617Z","end":"2026-04-20T11:58:07.477541Z","steps":["trace[293700501] 'read index received' (duration: 191.188896ms)","trace[293700501] 'applied index is now lower than readState.Index' (duration: 50.73384ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:58:07.477934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.292783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:58:07.477979Z","caller":"traceutil/trace.go:171","msg":"trace[211282716] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1387; }","duration":"242.357504ms","start":"2026-04-20T11:58:07.235609Z","end":"2026-04-20T11:58:07.477967Z","steps":["trace[211282716] 'agreement among raft nodes before linearized reading' (duration: 242.226012ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:58:07.478335Z","caller":"traceutil/trace.go:171","msg":"trace[1729831048] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"320.064711ms","start":"2026-04-20T11:58:07.158239Z","end":"2026-04-20T11:58:07.478304Z","steps":["trace[1729831048] 'process raft request' (duration: 268.619187ms)","trace[1729831048] 'compare' (duration: 50.528657ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:58:07.479074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:58:07.158223Z","time spent":"320.380437ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:58:07.861287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.34915ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-20T12:01:28.683795Z","caller":"traceutil/trace.go:171","msg":"trace[225833833] transaction","detail":"{read_only:false; response_revision:2438; number_of_response:1; }","duration":"158.417304ms","start":"2026-04-20T12:01:28.525343Z","end":"2026-04-20T12:01:28.683761Z","steps":["trace[225833833] 'process raft request' (duration: 62.147836ms)","trace[225833833] 'compare' (duration: 95.965452ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:01:28.684147Z","caller":"traceutil/trace.go:171","msg":"trace[522843009] transaction","detail":"{read_only:false; response_revision:2439; number_of_response:1; }","duration":"157.345593ms","start":"2026-04-20T12:01:28.526787Z","end":"2026-04-20T12:01:28.684132Z","steps":["trace[522843009] 'process raft request' (duration: 156.823013ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:28.684302Z","caller":"traceutil/trace.go:171","msg":"trace[1678864823] linearizableReadLoop","detail":"{readStateIndex:2532; appliedIndex:2530; }","duration":"156.714891ms","start":"2026-04-20T12:01:28.527472Z","end":"2026-04-20T12:01:28.684187Z","steps":["trace[1678864823] 'read index received' (duration: 60.129907ms)","trace[1678864823] 'applied index is now lower than readState.Index' (duration: 96.582624ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:01:28.684579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.094689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-04-20T12:01:28.684647Z","caller":"traceutil/trace.go:171","msg":"trace[1647860857] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2439; }","duration":"157.202421ms","start":"2026-04-20T12:01:28.527424Z","end":"2026-04-20T12:01:28.684627Z","steps":["trace[1647860857] 'agreement among raft nodes before linearized reading' (duration: 156.874034ms)"],"step_count":1}