{"level":"warn","ts":"2026-05-05T22:10:13.455986Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T22:10:13.456128Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-05T22:10:13.456312Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T22:10:13.456342Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-05-05T22:10:13.456375Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T22:10:13.457011Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-05-05T22:10:13.457118Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-05T22:10:13.461494Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.123539ms"} {"level":"info","ts":"2026-05-05T22:10:13.472782Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-05-05T22:10:13.472889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T22:10:13.472934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-05-05T22:10:13.472967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T22:10:13.472995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-05-05T22:10:13.473051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-05-05T22:10:13.483749Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T22:10:13.485075Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T22:10:13.486185Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-05T22:10:13.488596Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T22:10:13.488673Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T22:10:13.488891Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T22:10:13.488936Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T22:10:13.488944Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T22:10:13.489477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-05-05T22:10:13.489543Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-05-05T22:10:13.494074Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T22:10:13.494221Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T22:10:13.494245Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T22:10:13.494314Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-05-05T22:10:13.494324Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-05-05T22:10:14.073646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T22:10:14.073709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T22:10:14.073754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-05-05T22:10:14.073773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-05-05T22:10:14.073785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-05-05T22:10:14.073805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-05-05T22:10:14.073821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-05-05T22:10:14.075591Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T22:10:14.075592Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T22:10:14.075619Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:10:14.075989Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T22:10:14.076825Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:10:14.076948Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:10:14.076988Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:10:14.07869Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-05-05T22:10:14.07944Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T22:10:14.07953Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T22:10:14.079686Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T22:10:16.569396Z","caller":"traceutil/trace.go:171","msg":"trace[1880875531] transaction","detail":"{read_only:false; response_revision:127; number_of_response:1; }","duration":"135.321483ms","start":"2026-05-05T22:10:16.434036Z","end":"2026-05-05T22:10:16.569357Z","steps":["trace[1880875531] 'process raft request' (duration: 60.147708ms)","trace[1880875531] 'compare' (duration: 74.948308ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:10:22.739662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.418385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:10:22.739798Z","caller":"traceutil/trace.go:171","msg":"trace[571688489] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:356; }","duration":"143.009228ms","start":"2026-05-05T22:10:22.59677Z","end":"2026-05-05T22:10:22.73978Z","steps":["trace[571688489] 'read index received' (duration: 38.843739ms)","trace[571688489] 'applied index is now lower than readState.Index' (duration: 104.164169ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:10:22.739875Z","caller":"traceutil/trace.go:171","msg":"trace[1720385547] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"145.639419ms","start":"2026-05-05T22:10:22.594226Z","end":"2026-05-05T22:10:22.739866Z","steps":["trace[1720385547] 'process raft request' (duration: 41.356096ms)","trace[1720385547] 'compare' (duration: 103.274312ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:10:22.740097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.308163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-05T22:10:22.740186Z","caller":"traceutil/trace.go:171","msg":"trace[1843015588] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:345; }","duration":"143.431829ms","start":"2026-05-05T22:10:22.596737Z","end":"2026-05-05T22:10:22.740169Z","steps":["trace[1843015588] 'agreement among raft nodes before linearized reading' (duration: 143.258927ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:10:23.220763Z","caller":"traceutil/trace.go:171","msg":"trace[146988120] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"157.884136ms","start":"2026-05-05T22:10:23.062834Z","end":"2026-05-05T22:10:23.220719Z","steps":["trace[146988120] 'process raft request' (duration: 109.341499ms)","trace[146988120] 'compare' (duration: 48.27648ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:10:23.221042Z","caller":"traceutil/trace.go:171","msg":"trace[479339840] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:363; }","duration":"123.536119ms","start":"2026-05-05T22:10:23.097487Z","end":"2026-05-05T22:10:23.221023Z","steps":["trace[479339840] 'read index received' (duration: 20.402684ms)","trace[479339840] 'applied index is now lower than readState.Index' (duration: 103.132325ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:10:23.221234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.713822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-05T22:10:23.221274Z","caller":"traceutil/trace.go:171","msg":"trace[67523706] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:355; }","duration":"123.804588ms","start":"2026-05-05T22:10:23.09746Z","end":"2026-05-05T22:10:23.221265Z","steps":["trace[67523706] 'agreement among raft nodes before linearized reading' (duration: 123.701313ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:10:23.221218Z","caller":"traceutil/trace.go:171","msg":"trace[1457261078] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"157.054637ms","start":"2026-05-05T22:10:23.06412Z","end":"2026-05-05T22:10:23.221175Z","steps":["trace[1457261078] 'process raft request' (duration: 156.534721ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:10:23.221245Z","caller":"traceutil/trace.go:171","msg":"trace[1895244172] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"154.391605ms","start":"2026-05-05T22:10:23.066792Z","end":"2026-05-05T22:10:23.221184Z","steps":["trace[1895244172] 'process raft request' (duration: 154.171354ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:10:23.222015Z","caller":"traceutil/trace.go:171","msg":"trace[1641604672] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"156.343989ms","start":"2026-05-05T22:10:23.064745Z","end":"2026-05-05T22:10:23.221089Z","steps":["trace[1641604672] 'process raft request' (duration: 156.101671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:23.436498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.77268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:10:23.436799Z","caller":"traceutil/trace.go:171","msg":"trace[1133179052] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"208.283675ms","start":"2026-05-05T22:10:23.228473Z","end":"2026-05-05T22:10:23.436757Z","steps":["trace[1133179052] 'process raft request' (duration: 101.857056ms)","trace[1133179052] 'compare' (duration: 105.607658ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:10:23.437052Z","caller":"traceutil/trace.go:171","msg":"trace[1412993474] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"206.237923ms","start":"2026-05-05T22:10:23.230774Z","end":"2026-05-05T22:10:23.437012Z","steps":["trace[1412993474] 'process raft request' (duration: 205.87355ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:10:23.437142Z","caller":"traceutil/trace.go:171","msg":"trace[916695246] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:367; }","duration":"207.876385ms","start":"2026-05-05T22:10:23.229241Z","end":"2026-05-05T22:10:23.437117Z","steps":["trace[916695246] 'read index received' (duration: 7.30782ms)","trace[916695246] 'applied index is now lower than readState.Index' (duration: 200.566945ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:10:23.437305Z","caller":"traceutil/trace.go:171","msg":"trace[75564806] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"206.347748ms","start":"2026-05-05T22:10:23.230929Z","end":"2026-05-05T22:10:23.437277Z","steps":["trace[75564806] 'process raft request' (duration: 205.818553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:23.438365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.01617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-05-05T22:10:23.437402Z","caller":"traceutil/trace.go:171","msg":"trace[526937502] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"205.197323ms","start":"2026-05-05T22:10:23.232189Z","end":"2026-05-05T22:10:23.437386Z","steps":["trace[526937502] 'process raft request' (duration: 204.714876ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:10:23.438476Z","caller":"traceutil/trace.go:171","msg":"trace[831175463] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:360; }","duration":"209.142475ms","start":"2026-05-05T22:10:23.229318Z","end":"2026-05-05T22:10:23.43846Z","steps":["trace[831175463] 'agreement among raft nodes before linearized reading' (duration: 208.967523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:23.438674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.357847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-05-05T22:10:23.438701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.381844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-05T22:10:23.438761Z","caller":"traceutil/trace.go:171","msg":"trace[2002784086] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:360; }","duration":"191.470909ms","start":"2026-05-05T22:10:23.247268Z","end":"2026-05-05T22:10:23.438739Z","steps":["trace[2002784086] 'agreement among raft nodes before linearized reading' (duration: 191.379184ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:10:23.438815Z","caller":"traceutil/trace.go:171","msg":"trace[1329668246] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:360; }","duration":"141.468991ms","start":"2026-05-05T22:10:23.297252Z","end":"2026-05-05T22:10:23.438721Z","steps":["trace[1329668246] 'agreement among raft nodes before linearized reading' (duration: 141.318889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:23.437531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.254277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:2205"} {"level":"info","ts":"2026-05-05T22:10:23.439015Z","caller":"traceutil/trace.go:171","msg":"trace[2106636969] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:360; }","duration":"209.718596ms","start":"2026-05-05T22:10:23.229212Z","end":"2026-05-05T22:10:23.438931Z","steps":["trace[2106636969] 'agreement among raft nodes before linearized reading' (duration: 208.164461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:23.999139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.496938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:10:23.999288Z","caller":"traceutil/trace.go:171","msg":"trace[301865010] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:396; }","duration":"260.356512ms","start":"2026-05-05T22:10:23.7389Z","end":"2026-05-05T22:10:23.999257Z","steps":["trace[301865010] 'read index received' (duration: 63.574569ms)","trace[301865010] 'applied index is now lower than readState.Index' (duration: 196.777424ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:10:23.999415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.184488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:10:23.999446Z","caller":"traceutil/trace.go:171","msg":"trace[1865312614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:385; }","duration":"189.212856ms","start":"2026-05-05T22:10:23.810223Z","end":"2026-05-05T22:10:23.999436Z","steps":["trace[1865312614] 'agreement among raft nodes before linearized reading' (duration: 189.157328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:23.99945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.538484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-05-05T22:10:23.999523Z","caller":"traceutil/trace.go:171","msg":"trace[1479484378] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:385; }","duration":"260.675157ms","start":"2026-05-05T22:10:23.738831Z","end":"2026-05-05T22:10:23.999506Z","steps":["trace[1479484378] 'agreement among raft nodes before linearized reading' (duration: 260.534374ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:10:23.999379Z","caller":"traceutil/trace.go:171","msg":"trace[678210279] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"358.267659ms","start":"2026-05-05T22:10:23.641067Z","end":"2026-05-05T22:10:23.999335Z","steps":["trace[678210279] 'process raft request' (duration: 161.442117ms)","trace[678210279] 'compare' (duration: 196.324756ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:10:24.000028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:23.641049Z","time spent":"358.806693ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:10:24.452941Z","caller":"traceutil/trace.go:171","msg":"trace[496104741] linearizableReadLoop","detail":"{readStateIndex:399; appliedIndex:398; }","duration":"343.341633ms","start":"2026-05-05T22:10:24.109494Z","end":"2026-05-05T22:10:24.452836Z","steps":["trace[496104741] 'read index received' (duration: 343.19321ms)","trace[496104741] 'applied index is now lower than readState.Index' (duration: 147.123µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:10:24.452952Z","caller":"traceutil/trace.go:171","msg":"trace[545887134] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"349.977245ms","start":"2026-05-05T22:10:24.10293Z","end":"2026-05-05T22:10:24.452907Z","steps":["trace[545887134] 'process raft request' (duration: 349.729927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:24.453166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.20922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-05T22:10:24.453181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:24.102896Z","time spent":"350.146186ms","remote":"127.0.0.1:50448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:10:24.45323Z","caller":"traceutil/trace.go:171","msg":"trace[920223772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:387; }","duration":"317.325035ms","start":"2026-05-05T22:10:24.135891Z","end":"2026-05-05T22:10:24.453216Z","steps":["trace[920223772] 'agreement among raft nodes before linearized reading' (duration: 317.188571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:24.453268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:24.135879Z","time spent":"317.379862ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T22:10:24.45336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.719077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:10:24.45352Z","caller":"traceutil/trace.go:171","msg":"trace[1119974054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:387; }","duration":"344.026802ms","start":"2026-05-05T22:10:24.109462Z","end":"2026-05-05T22:10:24.453489Z","steps":["trace[1119974054] 'agreement among raft nodes before linearized reading' (duration: 343.660819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:24.453615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:24.109454Z","time spent":"344.146406ms","remote":"127.0.0.1:50276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T22:10:27.123938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.360953ms","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-05-05T22:10:27.124028Z","caller":"traceutil/trace.go:171","msg":"trace[991903782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:401; }","duration":"102.509927ms","start":"2026-05-05T22:10:27.021496Z","end":"2026-05-05T22:10:27.124006Z","steps":["trace[991903782] 'range keys from in-memory index tree' (duration: 102.22643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:30.263582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.920012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-05T22:10:30.263671Z","caller":"traceutil/trace.go:171","msg":"trace[1095159340] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:410; }","duration":"115.059845ms","start":"2026-05-05T22:10:30.148592Z","end":"2026-05-05T22:10:30.263652Z","steps":["trace[1095159340] 'range keys from in-memory index tree' (duration: 114.816026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:32.763814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.193659ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-05T22:10:32.764022Z","caller":"traceutil/trace.go:171","msg":"trace[1112832570] linearizableReadLoop","detail":"{readStateIndex:431; appliedIndex:430; }","duration":"196.229554ms","start":"2026-05-05T22:10:32.567742Z","end":"2026-05-05T22:10:32.763972Z","steps":["trace[1112832570] 'read index received' (duration: 64.407µs)","trace[1112832570] 'applied index is now lower than readState.Index' (duration: 196.163657ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:10:32.764202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.558139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"warn","ts":"2026-05-05T22:10:32.764211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.401288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T22:10:32.764307Z","caller":"traceutil/trace.go:171","msg":"trace[1602712685] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:416; }","duration":"195.673455ms","start":"2026-05-05T22:10:32.568591Z","end":"2026-05-05T22:10:32.764264Z","steps":["trace[1602712685] 'agreement among raft nodes before linearized reading' (duration: 195.563059ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:10:32.764496Z","caller":"traceutil/trace.go:171","msg":"trace[1170956204] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:416; }","duration":"196.576731ms","start":"2026-05-05T22:10:32.567707Z","end":"2026-05-05T22:10:32.764284Z","steps":["trace[1170956204] 'agreement among raft nodes before linearized reading' (duration: 196.35053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:40.605433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.476315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:10:40.605516Z","caller":"traceutil/trace.go:171","msg":"trace[306851317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:467; }","duration":"263.622421ms","start":"2026-05-05T22:10:40.341874Z","end":"2026-05-05T22:10:40.605497Z","steps":["trace[306851317] 'range keys from in-memory index tree' (duration: 263.339049ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:10:43.335733Z","caller":"traceutil/trace.go:171","msg":"trace[963650383] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"501.857523ms","start":"2026-05-05T22:10:42.833844Z","end":"2026-05-05T22:10:43.335701Z","steps":["trace[963650383] 'process raft request' (duration: 501.688868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:43.335931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:42.83383Z","time spent":"501.99975ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:10:48.821035Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970286141728109,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T22:10:48.903926Z","caller":"traceutil/trace.go:171","msg":"trace[290387731] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"840.875648ms","start":"2026-05-05T22:10:48.063014Z","end":"2026-05-05T22:10:48.90389Z","steps":["trace[290387731] 'process raft request' (duration: 840.713401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:48.90413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:48.062984Z","time spent":"841.054623ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T22:10:48.918572Z","caller":"traceutil/trace.go:171","msg":"trace[2001441106] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"842.177765ms","start":"2026-05-05T22:10:48.076377Z","end":"2026-05-05T22:10:48.918554Z","steps":["trace[2001441106] 'process raft request' (duration: 841.954171ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:10:48.918346Z","caller":"traceutil/trace.go:171","msg":"trace[811292496] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"853.363732ms","start":"2026-05-05T22:10:48.064942Z","end":"2026-05-05T22:10:48.918305Z","steps":["trace[811292496] 'process raft request' (duration: 852.969163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:48.918777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:48.076356Z","time spent":"842.297052ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T22:10:48.91888Z","caller":"traceutil/trace.go:171","msg":"trace[1189597978] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"838.00465ms","start":"2026-05-05T22:10:48.080839Z","end":"2026-05-05T22:10:48.918844Z","steps":["trace[1189597978] 'process raft request' (duration: 837.706458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:48.919037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:48.080829Z","time spent":"838.115368ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T22:10:48.919019Z","caller":"traceutil/trace.go:171","msg":"trace[577160090] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"842.521056ms","start":"2026-05-05T22:10:48.076451Z","end":"2026-05-05T22:10:48.918972Z","steps":["trace[577160090] 'process raft request' (duration: 841.941151ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:10:48.919121Z","caller":"traceutil/trace.go:171","msg":"trace[1295562496] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"842.170815ms","start":"2026-05-05T22:10:48.076841Z","end":"2026-05-05T22:10:48.919012Z","steps":["trace[1295562496] 'process raft request' (duration: 841.654128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:48.919277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:48.076447Z","time spent":"842.744709ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T22:10:48.919148Z","caller":"traceutil/trace.go:171","msg":"trace[686873409] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"854.002996ms","start":"2026-05-05T22:10:48.065131Z","end":"2026-05-05T22:10:48.919134Z","steps":["trace[686873409] 'process raft request' (duration: 853.036861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:48.919449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:48.065126Z","time spent":"854.300038ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T22:10:48.91946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:48.076783Z","time spent":"842.542926ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T22:10:48.91947Z","caller":"traceutil/trace.go:171","msg":"trace[990292661] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"842.910146ms","start":"2026-05-05T22:10:48.076538Z","end":"2026-05-05T22:10:48.919448Z","steps":["trace[990292661] 'process raft request' (duration: 841.905922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:48.919562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:48.076532Z","time spent":"842.991594ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T22:10:48.919579Z","caller":"traceutil/trace.go:171","msg":"trace[1526005968] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"827.862849ms","start":"2026-05-05T22:10:48.091696Z","end":"2026-05-05T22:10:48.919559Z","steps":["trace[1526005968] 'process raft request' (duration: 826.916852ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:10:48.919701Z","caller":"traceutil/trace.go:171","msg":"trace[2049273722] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"820.799016ms","start":"2026-05-05T22:10:48.098889Z","end":"2026-05-05T22:10:48.919688Z","steps":["trace[2049273722] 'process raft request' (duration: 819.814691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:48.919761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:48.098876Z","time spent":"820.862685ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T22:10:48.919854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:48.091671Z","time spent":"828.048483ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T22:10:48.919664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:48.064916Z","time spent":"854.710708ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T22:10:48.919884Z","caller":"traceutil/trace.go:171","msg":"trace[594490102] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"802.393463ms","start":"2026-05-05T22:10:48.117476Z","end":"2026-05-05T22:10:48.919869Z","steps":["trace[594490102] 'process raft request' (duration: 801.308061ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:10:48.919926Z","caller":"traceutil/trace.go:171","msg":"trace[1739583977] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:537; }","duration":"598.918204ms","start":"2026-05-05T22:10:48.320987Z","end":"2026-05-05T22:10:48.919905Z","steps":["trace[1739583977] 'read index received' (duration: 582.798302ms)","trace[1739583977] 'applied index is now lower than readState.Index' (duration: 16.118532ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:10:48.919945Z","caller":"traceutil/trace.go:171","msg":"trace[1874685360] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"696.505569ms","start":"2026-05-05T22:10:48.223428Z","end":"2026-05-05T22:10:48.919934Z","steps":["trace[1874685360] 'process raft request' (duration: 696.392442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:48.919973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:48.117442Z","time spent":"802.501171ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T22:10:48.920054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:48.223393Z","time spent":"696.612396ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T22:10:48.920177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.184207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T22:10:48.920226Z","caller":"traceutil/trace.go:171","msg":"trace[434287499] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"599.277414ms","start":"2026-05-05T22:10:48.320935Z","end":"2026-05-05T22:10:48.920213Z","steps":["trace[434287499] 'agreement among raft nodes before linearized reading' (duration: 599.044091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:48.920261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:48.320919Z","time spent":"599.335212ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T22:10:55.469949Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970286141728345,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T22:10:55.953699Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.259678791s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T22:10:55.956186Z","caller":"traceutil/trace.go:171","msg":"trace[1240891437] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"987.243933ms","start":"2026-05-05T22:10:54.968908Z","end":"2026-05-05T22:10:55.956152Z","steps":["trace[1240891437] 'read index received' (duration: 984.990052ms)","trace[1240891437] 'applied index is now lower than readState.Index' (duration: 2.252831ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:10:55.956335Z","caller":"traceutil/trace.go:171","msg":"trace[1581985480] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"1.264534903s","start":"2026-05-05T22:10:54.691753Z","end":"2026-05-05T22:10:55.956288Z","steps":["trace[1581985480] 'process raft request' (duration: 1.262914609s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:55.956492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.561296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T22:10:55.956559Z","caller":"traceutil/trace.go:171","msg":"trace[775453486] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"987.720873ms","start":"2026-05-05T22:10:54.968821Z","end":"2026-05-05T22:10:55.956541Z","steps":["trace[775453486] 'agreement among raft nodes before linearized reading' (duration: 987.503587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:55.956522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.175083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-789km\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-05-05T22:10:55.956585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.902642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-05T22:10:55.956613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:54.968765Z","time spent":"987.834909ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T22:10:55.956634Z","caller":"traceutil/trace.go:171","msg":"trace[1421298750] range","detail":"{range_begin:/registry/pods/kube-system/cilium-789km; range_end:; response_count:1; response_revision:624; }","duration":"774.362329ms","start":"2026-05-05T22:10:55.182245Z","end":"2026-05-05T22:10:55.956607Z","steps":["trace[1421298750] 'agreement among raft nodes before linearized reading' (duration: 774.133754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:55.956648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.763586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:10:55.956688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:55.182217Z","time spent":"774.461148ms","remote":"127.0.0.1:50448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-789km\" "} {"level":"info","ts":"2026-05-05T22:10:55.956706Z","caller":"traceutil/trace.go:171","msg":"trace[326967086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"555.00615ms","start":"2026-05-05T22:10:55.40162Z","end":"2026-05-05T22:10:55.956627Z","steps":["trace[326967086] 'agreement among raft nodes before linearized reading' (duration: 554.830034ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:10:55.956713Z","caller":"traceutil/trace.go:171","msg":"trace[582025309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"614.828154ms","start":"2026-05-05T22:10:55.341863Z","end":"2026-05-05T22:10:55.956691Z","steps":["trace[582025309] 'agreement among raft nodes before linearized reading' (duration: 614.669118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:55.956732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.991958ms","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-05-05T22:10:55.956759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:55.341795Z","time spent":"614.956011ms","remote":"127.0.0.1:50276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T22:10:55.956761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:55.401594Z","time spent":"555.152746ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T22:10:55.957722Z","caller":"traceutil/trace.go:171","msg":"trace[288110602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"146.097895ms","start":"2026-05-05T22:10:55.810676Z","end":"2026-05-05T22:10:55.956773Z","steps":["trace[288110602] 'agreement among raft nodes before linearized reading' (duration: 145.829411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:55.957821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:54.691716Z","time spent":"1.264684651s","remote":"127.0.0.1:50344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:10:55.958971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.530825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T22:10:55.959059Z","caller":"traceutil/trace.go:171","msg":"trace[1300054864] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"563.633243ms","start":"2026-05-05T22:10:55.395385Z","end":"2026-05-05T22:10:55.959019Z","steps":["trace[1300054864] 'agreement among raft nodes before linearized reading' (duration: 561.095448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:55.959129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:55.395364Z","time spent":"563.755191ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T22:10:56.842334Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970286141728348,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T22:10:56.861564Z","caller":"traceutil/trace.go:171","msg":"trace[312315374] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"895.862267ms","start":"2026-05-05T22:10:55.965672Z","end":"2026-05-05T22:10:56.861534Z","steps":["trace[312315374] 'process raft request' (duration: 895.74849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:56.861747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:55.96565Z","time spent":"896.004364ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:10:56.861761Z","caller":"traceutil/trace.go:171","msg":"trace[886457139] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"896.738018ms","start":"2026-05-05T22:10:55.965002Z","end":"2026-05-05T22:10:56.86174Z","steps":["trace[886457139] 'process raft request' (duration: 896.281298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:56.861861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:55.964983Z","time spent":"896.828885ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:10:56.862068Z","caller":"traceutil/trace.go:171","msg":"trace[1863581080] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"890.075003ms","start":"2026-05-05T22:10:55.971979Z","end":"2026-05-05T22:10:56.862054Z","steps":["trace[1863581080] 'process raft request' (duration: 889.507204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:56.862137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:55.971955Z","time spent":"890.143841ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:10:56.862385Z","caller":"traceutil/trace.go:171","msg":"trace[1161509923] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"887.211694ms","start":"2026-05-05T22:10:55.975137Z","end":"2026-05-05T22:10:56.862349Z","steps":["trace[1161509923] 'process raft request' (duration: 886.769464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:56.862542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:55.975113Z","time spent":"887.333892ms","remote":"127.0.0.1:50448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:10:56.880374Z","caller":"traceutil/trace.go:171","msg":"trace[1698054312] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:648; }","duration":"538.220454ms","start":"2026-05-05T22:10:56.342119Z","end":"2026-05-05T22:10:56.880339Z","steps":["trace[1698054312] 'read index received' (duration: 519.092819ms)","trace[1698054312] 'applied index is now lower than readState.Index' (duration: 19.126595ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:10:56.880448Z","caller":"traceutil/trace.go:171","msg":"trace[960426131] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"863.069589ms","start":"2026-05-05T22:10:56.017323Z","end":"2026-05-05T22:10:56.880393Z","steps":["trace[960426131] 'process raft request' (duration: 862.822095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:56.880559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.37885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:10:56.880648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:56.017291Z","time spent":"863.262606ms","remote":"127.0.0.1:50780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T22:10:56.880696Z","caller":"traceutil/trace.go:171","msg":"trace[1471328337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"538.612476ms","start":"2026-05-05T22:10:56.342065Z","end":"2026-05-05T22:10:56.880678Z","steps":["trace[1471328337] 'agreement among raft nodes before linearized reading' (duration: 538.390621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:56.880882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:56.342036Z","time spent":"538.830931ms","remote":"127.0.0.1:50274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T22:10:56.880916Z","caller":"traceutil/trace.go:171","msg":"trace[1115319059] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"177.89208ms","start":"2026-05-05T22:10:56.702972Z","end":"2026-05-05T22:10:56.880864Z","steps":["trace[1115319059] 'process raft request' (duration: 177.306483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:57.842853Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970286141728358,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T22:10:58.343752Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970286141728358,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T22:10:58.844511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970286141728358,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T22:10:59.180651Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.885646394s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T22:10:59.181514Z","caller":"traceutil/trace.go:171","msg":"trace[1771337346] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"1.838751847s","start":"2026-05-05T22:10:57.342743Z","end":"2026-05-05T22:10:59.181495Z","steps":["trace[1771337346] 'read index received' (duration: 1.838047761s)","trace[1771337346] 'applied index is now lower than readState.Index' (duration: 703.396µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:10:59.18155Z","caller":"traceutil/trace.go:171","msg":"trace[1533797358] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.888464865s","start":"2026-05-05T22:10:57.293064Z","end":"2026-05-05T22:10:59.181529Z","steps":["trace[1533797358] 'process raft request' (duration: 1.887975125s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:59.181629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.838879915s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:10:59.181647Z","caller":"traceutil/trace.go:171","msg":"trace[1034185088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"1.838958623s","start":"2026-05-05T22:10:57.342682Z","end":"2026-05-05T22:10:59.181641Z","steps":["trace[1034185088] 'agreement among raft nodes before linearized reading' (duration: 1.838906174s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:59.181663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:57.342654Z","time spent":"1.839004951s","remote":"127.0.0.1:50274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T22:10:59.181686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:57.293004Z","time spent":"1.888591014s","remote":"127.0.0.1:50344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:10:59.181698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.807398427s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T22:10:59.181745Z","caller":"traceutil/trace.go:171","msg":"trace[716474203] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:632; }","duration":"1.807532625s","start":"2026-05-05T22:10:57.374193Z","end":"2026-05-05T22:10:59.181725Z","steps":["trace[716474203] 'agreement among raft nodes before linearized reading' (duration: 1.807431637s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:59.181775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:57.374137Z","time spent":"1.807596845s","remote":"127.0.0.1:50306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.116\" "} {"level":"warn","ts":"2026-05-05T22:10:59.181832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187660304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-05T22:10:59.181838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.277054ms","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-05-05T22:10:59.181876Z","caller":"traceutil/trace.go:171","msg":"trace[1657682564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"1.187765472s","start":"2026-05-05T22:10:57.994098Z","end":"2026-05-05T22:10:59.181864Z","steps":["trace[1657682564] 'agreement among raft nodes before linearized reading' (duration: 1.187638194s)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:10:59.181883Z","caller":"traceutil/trace.go:171","msg":"trace[666495886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"315.370662ms","start":"2026-05-05T22:10:58.866499Z","end":"2026-05-05T22:10:59.18187Z","steps":["trace[666495886] 'agreement among raft nodes before linearized reading' (duration: 315.219654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:59.181905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:57.994063Z","time spent":"1.187834911s","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T22:10:59.181908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:58.866477Z","time spent":"315.42422ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T22:10:59.181948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.273634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T22:10:59.182045Z","caller":"traceutil/trace.go:171","msg":"trace[901252066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"314.415771ms","start":"2026-05-05T22:10:58.867606Z","end":"2026-05-05T22:10:59.182022Z","steps":["trace[901252066] 'agreement among raft nodes before linearized reading' (duration: 314.096627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:59.182111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:58.867598Z","time spent":"314.504599ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T22:10:59.182113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.704736ms","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-05-05T22:10:59.182214Z","caller":"traceutil/trace.go:171","msg":"trace[63317084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"312.891943ms","start":"2026-05-05T22:10:58.869297Z","end":"2026-05-05T22:10:59.182189Z","steps":["trace[63317084] 'agreement among raft nodes before linearized reading' (duration: 312.53731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:10:59.182269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:10:58.869276Z","time spent":"312.98365ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T22:11:15.461151Z","caller":"traceutil/trace.go:171","msg":"trace[699097021] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"507.500722ms","start":"2026-05-05T22:11:14.953622Z","end":"2026-05-05T22:11:15.461123Z","steps":["trace[699097021] 'process raft request' (duration: 507.328095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:15.461325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:11:14.953604Z","time spent":"507.63423ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:11:15.575447Z","caller":"traceutil/trace.go:171","msg":"trace[90175112] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:822; }","duration":"255.477023ms","start":"2026-05-05T22:11:15.31995Z","end":"2026-05-05T22:11:15.575427Z","steps":["trace[90175112] 'read index received' (duration: 141.11656ms)","trace[90175112] 'applied index is now lower than readState.Index' (duration: 114.359363ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:11:15.575545Z","caller":"traceutil/trace.go:171","msg":"trace[408520735] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"260.933422ms","start":"2026-05-05T22:11:15.314573Z","end":"2026-05-05T22:11:15.575507Z","steps":["trace[408520735] 'process raft request' (duration: 260.732275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:15.575665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.696469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T22:11:15.575701Z","caller":"traceutil/trace.go:171","msg":"trace[1524783008] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:796; }","duration":"255.779008ms","start":"2026-05-05T22:11:15.319911Z","end":"2026-05-05T22:11:15.57569Z","steps":["trace[1524783008] 'agreement among raft nodes before linearized reading' (duration: 255.6358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:15.575851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.842109ms","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-05-05T22:11:15.575892Z","caller":"traceutil/trace.go:171","msg":"trace[1554245151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"250.912059ms","start":"2026-05-05T22:11:15.324969Z","end":"2026-05-05T22:11:15.575881Z","steps":["trace[1554245151] 'agreement among raft nodes before linearized reading' (duration: 250.759751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:15.575953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.873349ms","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-05-05T22:11:15.575992Z","caller":"traceutil/trace.go:171","msg":"trace[528627018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"250.955278ms","start":"2026-05-05T22:11:15.325027Z","end":"2026-05-05T22:11:15.575982Z","steps":["trace[528627018] 'agreement among raft nodes before linearized reading' (duration: 250.84645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:15.576468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.073434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T22:11:15.576538Z","caller":"traceutil/trace.go:171","msg":"trace[730825558] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:796; }","duration":"243.746884ms","start":"2026-05-05T22:11:15.332772Z","end":"2026-05-05T22:11:15.576519Z","steps":["trace[730825558] 'agreement among raft nodes before linearized reading' (duration: 242.988996ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:11:19.647686Z","caller":"traceutil/trace.go:171","msg":"trace[748666339] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"148.299893ms","start":"2026-05-05T22:11:19.499348Z","end":"2026-05-05T22:11:19.647648Z","steps":["trace[748666339] 'process raft request' (duration: 139.964138ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:11:19.647814Z","caller":"traceutil/trace.go:171","msg":"trace[37844158] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"124.770766ms","start":"2026-05-05T22:11:19.523022Z","end":"2026-05-05T22:11:19.647793Z","steps":["trace[37844158] 'process raft request' (duration: 124.48739ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:11:19.647739Z","caller":"traceutil/trace.go:171","msg":"trace[1615297654] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"124.455411ms","start":"2026-05-05T22:11:19.523253Z","end":"2026-05-05T22:11:19.647709Z","steps":["trace[1615297654] 'process raft request' (duration: 124.360093ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:11:35.92643Z","caller":"traceutil/trace.go:171","msg":"trace[551008778] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1021; }","duration":"175.309326ms","start":"2026-05-05T22:11:35.751095Z","end":"2026-05-05T22:11:35.926404Z","steps":["trace[551008778] 'read index received' (duration: 175.122088ms)","trace[551008778] 'applied index is now lower than readState.Index' (duration: 186.308µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:11:35.926489Z","caller":"traceutil/trace.go:171","msg":"trace[227233014] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"180.287612ms","start":"2026-05-05T22:11:35.746167Z","end":"2026-05-05T22:11:35.926455Z","steps":["trace[227233014] 'process raft request' (duration: 180.100795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:35.926797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.769103ms","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-05-05T22:11:35.926834Z","caller":"traceutil/trace.go:171","msg":"trace[220642047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:989; }","duration":"174.830392ms","start":"2026-05-05T22:11:35.751994Z","end":"2026-05-05T22:11:35.926824Z","steps":["trace[220642047] 'agreement among raft nodes before linearized reading' (duration: 174.657244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:35.926813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.787085ms","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-05-05T22:11:35.926888Z","caller":"traceutil/trace.go:171","msg":"trace[1964559924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:989; }","duration":"171.913253ms","start":"2026-05-05T22:11:35.754953Z","end":"2026-05-05T22:11:35.926867Z","steps":["trace[1964559924] 'agreement among raft nodes before linearized reading' (duration: 171.695995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:35.926897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.509145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T22:11:35.926938Z","caller":"traceutil/trace.go:171","msg":"trace[2089711718] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:989; }","duration":"175.574183ms","start":"2026-05-05T22:11:35.751352Z","end":"2026-05-05T22:11:35.926926Z","steps":["trace[2089711718] 'agreement among raft nodes before linearized reading' (duration: 175.210017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:35.926975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.780901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T22:11:35.927112Z","caller":"traceutil/trace.go:171","msg":"trace[1456185488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:989; }","duration":"176.041899ms","start":"2026-05-05T22:11:35.751054Z","end":"2026-05-05T22:11:35.927096Z","steps":["trace[1456185488] 'agreement among raft nodes before linearized reading' (duration: 175.466225ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:11:36.046722Z","caller":"traceutil/trace.go:171","msg":"trace[415035456] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"114.958295ms","start":"2026-05-05T22:11:35.93174Z","end":"2026-05-05T22:11:36.046699Z","steps":["trace[415035456] 'process raft request' (duration: 64.194386ms)","trace[415035456] 'compare' (duration: 50.64278ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:11:36.046981Z","caller":"traceutil/trace.go:171","msg":"trace[826724870] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"115.212322ms","start":"2026-05-05T22:11:35.931752Z","end":"2026-05-05T22:11:36.046964Z","steps":["trace[826724870] 'process raft request' (duration: 114.911515ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:11:36.047225Z","caller":"traceutil/trace.go:171","msg":"trace[1129280746] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"115.177443ms","start":"2026-05-05T22:11:35.932024Z","end":"2026-05-05T22:11:36.047202Z","steps":["trace[1129280746] 'process raft request' (duration: 114.855847ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:11:44.113634Z","caller":"traceutil/trace.go:171","msg":"trace[1410056333] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"129.535922ms","start":"2026-05-05T22:11:43.984071Z","end":"2026-05-05T22:11:44.113607Z","steps":["trace[1410056333] 'process raft request' (duration: 90.157393ms)","trace[1410056333] 'compare' (duration: 39.208521ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:13:08.70938Z","caller":"traceutil/trace.go:171","msg":"trace[580371548] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1390; }","duration":"156.754386ms","start":"2026-05-05T22:13:08.5526Z","end":"2026-05-05T22:13:08.709354Z","steps":["trace[580371548] 'read index received' (duration: 156.630914ms)","trace[580371548] 'applied index is now lower than readState.Index' (duration: 121.972µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:13:08.709663Z","caller":"traceutil/trace.go:171","msg":"trace[1999944619] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"226.700972ms","start":"2026-05-05T22:13:08.482949Z","end":"2026-05-05T22:13:08.70965Z","steps":["trace[1999944619] 'process raft request' (duration: 226.282034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:13:08.709915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.317877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T22:13:08.709955Z","caller":"traceutil/trace.go:171","msg":"trace[1247025936] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1337; }","duration":"157.412439ms","start":"2026-05-05T22:13:08.552534Z","end":"2026-05-05T22:13:08.709946Z","steps":["trace[1247025936] 'agreement among raft nodes before linearized reading' (duration: 157.287986ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:13:08.991486Z","caller":"traceutil/trace.go:171","msg":"trace[1808101839] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1392; }","duration":"146.866889ms","start":"2026-05-05T22:13:08.844593Z","end":"2026-05-05T22:13:08.99146Z","steps":["trace[1808101839] 'read index received' (duration: 105.606342ms)","trace[1808101839] 'applied index is now lower than readState.Index' (duration: 41.259297ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:13:08.991503Z","caller":"traceutil/trace.go:171","msg":"trace[1988734637] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"276.61493ms","start":"2026-05-05T22:13:08.714845Z","end":"2026-05-05T22:13:08.99146Z","steps":["trace[1988734637] 'process raft request' (duration: 235.319373ms)","trace[1988734637] 'compare' (duration: 41.050293ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:13:08.991595Z","caller":"traceutil/trace.go:171","msg":"trace[1984504264] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"276.659191ms","start":"2026-05-05T22:13:08.714899Z","end":"2026-05-05T22:13:08.991558Z","steps":["trace[1984504264] 'process raft request' (duration: 276.465567ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:13:08.991703Z","caller":"traceutil/trace.go:171","msg":"trace[290413623] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"275.960598ms","start":"2026-05-05T22:13:08.715727Z","end":"2026-05-05T22:13:08.991687Z","steps":["trace[290413623] 'process raft request' (duration: 275.687342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:13:08.991771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.160746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:13:08.992478Z","caller":"traceutil/trace.go:171","msg":"trace[749362062] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1341; }","duration":"147.924961ms","start":"2026-05-05T22:13:08.844531Z","end":"2026-05-05T22:13:08.992456Z","steps":["trace[749362062] 'agreement among raft nodes before linearized reading' (duration: 147.161046ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:13:09.551986Z","caller":"traceutil/trace.go:171","msg":"trace[427931700] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1395; }","duration":"208.412031ms","start":"2026-05-05T22:13:09.343553Z","end":"2026-05-05T22:13:09.551965Z","steps":["trace[427931700] 'read index received' (duration: 208.232947ms)","trace[427931700] 'applied index is now lower than readState.Index' (duration: 178.164µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:13:09.552241Z","caller":"traceutil/trace.go:171","msg":"trace[1539722648] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"356.240921ms","start":"2026-05-05T22:13:09.195987Z","end":"2026-05-05T22:13:09.552228Z","steps":["trace[1539722648] 'process raft request' (duration: 355.856263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:13:09.55234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:13:09.195945Z","time spent":"356.322873ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:13:09.5526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.044983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:13:09.552632Z","caller":"traceutil/trace.go:171","msg":"trace[898760967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1342; }","duration":"209.118934ms","start":"2026-05-05T22:13:09.343504Z","end":"2026-05-05T22:13:09.552623Z","steps":["trace[898760967] 'agreement among raft nodes before linearized reading' (duration: 209.066573ms)"],"step_count":1}