{"level":"warn","ts":"2026-05-30T01:46:53.754025Z","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-30T01:46:53.754136Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-30T01:46:53.754216Z","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-30T01:46:53.754233Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-05-30T01:46:53.754254Z","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-30T01:46:53.755066Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-05-30T01:46:53.755167Z","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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-30T01:46:53.814778Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"59.340975ms"} {"level":"info","ts":"2026-05-30T01:46:53.830089Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-05-30T01:46:53.830187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-05-30T01:46:53.830252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-05-30T01:46:53.830267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-30T01:46:53.830315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-05-30T01:46:53.830411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-05-30T01:46:53.837455Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-30T01:46:53.83948Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-30T01:46:53.840819Z","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-30T01:46:53.844464Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-30T01:46:53.844679Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-30T01:46:53.845062Z","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-30T01:46:53.84515Z","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-30T01:46:53.845166Z","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-30T01:46:53.84575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-05-30T01:46:53.845846Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-05-30T01:46:53.849137Z","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-30T01:46:53.849286Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-05-30T01:46:53.849379Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-30T01:46:53.849416Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-30T01:46:53.849397Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-05-30T01:46:54.331298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-05-30T01:46:54.331343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-05-30T01:46:54.331373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-05-30T01:46:54.331388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-05-30T01:46:54.331395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-05-30T01:46:54.331405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-05-30T01:46:54.331414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-05-30T01:46:54.332101Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-05-30T01:46:54.332323Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T01:46:54.332401Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-30T01:46:54.332459Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-30T01:46:54.332326Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T01:46:54.332239Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T01:46:54.333899Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T01:46:54.33411Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T01:46:54.334207Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T01:46:54.335561Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-30T01:46:54.337957Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-05-30T01:46:58.098788Z","caller":"traceutil/trace.go:171","msg":"trace[1604269048] linearizableReadLoop","detail":"{readStateIndex:213; appliedIndex:211; }","duration":"108.464441ms","start":"2026-05-30T01:46:57.990281Z","end":"2026-05-30T01:46:58.098746Z","steps":["trace[1604269048] 'read index received' (duration: 39.836894ms)","trace[1604269048] 'applied index is now lower than readState.Index' (duration: 68.626777ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:46:58.098863Z","caller":"traceutil/trace.go:171","msg":"trace[1535758994] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"112.905538ms","start":"2026-05-30T01:46:57.985927Z","end":"2026-05-30T01:46:58.098833Z","steps":["trace[1535758994] 'process raft request' (duration: 112.671958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:46:58.098969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.656679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:46:58.099197Z","caller":"traceutil/trace.go:171","msg":"trace[1624932892] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:0; response_revision:207; }","duration":"108.872738ms","start":"2026-05-30T01:46:57.990255Z","end":"2026-05-30T01:46:58.099127Z","steps":["trace[1624932892] 'agreement among raft nodes before linearized reading' (duration: 108.622688ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:06.194526Z","caller":"traceutil/trace.go:171","msg":"trace[922352127] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"291.856194ms","start":"2026-05-30T01:47:05.90264Z","end":"2026-05-30T01:47:06.194496Z","steps":["trace[922352127] 'process raft request' (duration: 210.755073ms)","trace[922352127] 'compare' (duration: 80.936704ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:47:09.470053Z","caller":"traceutil/trace.go:171","msg":"trace[1475380769] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"181.810619ms","start":"2026-05-30T01:47:09.288224Z","end":"2026-05-30T01:47:09.470034Z","steps":["trace[1475380769] 'process raft request' (duration: 108.624168ms)","trace[1475380769] 'compare' (duration: 72.954512ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:47:16.610881Z","caller":"traceutil/trace.go:171","msg":"trace[813545940] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:399; }","duration":"343.209699ms","start":"2026-05-30T01:47:16.267645Z","end":"2026-05-30T01:47:16.610854Z","steps":["trace[813545940] 'read index received' (duration: 343.040614ms)","trace[813545940] 'applied index is now lower than readState.Index' (duration: 168.155µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:47:16.610949Z","caller":"traceutil/trace.go:171","msg":"trace[1833758492] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"377.60943ms","start":"2026-05-30T01:47:16.233308Z","end":"2026-05-30T01:47:16.610918Z","steps":["trace[1833758492] 'process raft request' (duration: 377.392502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:16.611069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.409347ms","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-30T01:47:16.611332Z","caller":"traceutil/trace.go:171","msg":"trace[1483012356] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:384; }","duration":"343.709377ms","start":"2026-05-30T01:47:16.267606Z","end":"2026-05-30T01:47:16.611315Z","steps":["trace[1483012356] 'agreement among raft nodes before linearized reading' (duration: 343.389416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:16.611466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:16.267592Z","time spent":"343.835711ms","remote":"127.0.0.1:57326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-30T01:47:16.612208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:16.233288Z","time spent":"377.920739ms","remote":"127.0.0.1:57326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:47:16.655885Z","caller":"traceutil/trace.go:171","msg":"trace[1165996915] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"352.158786ms","start":"2026-05-30T01:47:16.303711Z","end":"2026-05-30T01:47:16.65587Z","steps":["trace[1165996915] 'process raft request' (duration: 352.076683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:16.656016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:16.303688Z","time spent":"352.245639ms","remote":"127.0.0.1:57326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:47:29.843327Z","caller":"traceutil/trace.go:171","msg":"trace[1033374471] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"121.868124ms","start":"2026-05-30T01:47:29.721437Z","end":"2026-05-30T01:47:29.843305Z","steps":["trace[1033374471] 'process raft request' (duration: 121.765391ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:29.850127Z","caller":"traceutil/trace.go:171","msg":"trace[682498695] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"126.206764ms","start":"2026-05-30T01:47:29.723904Z","end":"2026-05-30T01:47:29.850111Z","steps":["trace[682498695] 'process raft request' (duration: 125.635367ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:29.850255Z","caller":"traceutil/trace.go:171","msg":"trace[1856454824] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"120.423071ms","start":"2026-05-30T01:47:29.729826Z","end":"2026-05-30T01:47:29.850249Z","steps":["trace[1856454824] 'process raft request' (duration: 119.793762ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:29.850512Z","caller":"traceutil/trace.go:171","msg":"trace[781202306] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"102.875292ms","start":"2026-05-30T01:47:29.747624Z","end":"2026-05-30T01:47:29.850499Z","steps":["trace[781202306] 'process raft request' (duration: 102.116149ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:29.850614Z","caller":"traceutil/trace.go:171","msg":"trace[200810565] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"102.587613ms","start":"2026-05-30T01:47:29.748018Z","end":"2026-05-30T01:47:29.850606Z","steps":["trace[200810565] 'process raft request' (duration: 101.744318ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:29.850724Z","caller":"traceutil/trace.go:171","msg":"trace[1755858421] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"106.884263ms","start":"2026-05-30T01:47:29.743833Z","end":"2026-05-30T01:47:29.850717Z","steps":["trace[1755858421] 'process raft request' (duration: 105.812821ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:29.850763Z","caller":"traceutil/trace.go:171","msg":"trace[250262257] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"106.441529ms","start":"2026-05-30T01:47:29.744301Z","end":"2026-05-30T01:47:29.850742Z","steps":["trace[250262257] 'process raft request' (duration: 105.365907ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:29.850513Z","caller":"traceutil/trace.go:171","msg":"trace[215324167] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"103.11639ms","start":"2026-05-30T01:47:29.747366Z","end":"2026-05-30T01:47:29.850482Z","steps":["trace[215324167] 'process raft request' (duration: 102.319516ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:30.509275Z","caller":"traceutil/trace.go:171","msg":"trace[398487086] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"537.614954ms","start":"2026-05-30T01:47:29.971623Z","end":"2026-05-30T01:47:30.509238Z","steps":["trace[398487086] 'process raft request' (duration: 527.441141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:30.509438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:29.971602Z","time spent":"537.741388ms","remote":"127.0.0.1:57096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:47:30.50948Z","caller":"traceutil/trace.go:171","msg":"trace[1183623532] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:532; }","duration":"440.527316ms","start":"2026-05-30T01:47:30.068933Z","end":"2026-05-30T01:47:30.50946Z","steps":["trace[1183623532] 'read index received' (duration: 430.155256ms)","trace[1183623532] 'applied index is now lower than readState.Index' (duration: 10.3709ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:47:30.509502Z","caller":"traceutil/trace.go:171","msg":"trace[279581441] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"536.285263ms","start":"2026-05-30T01:47:29.973167Z","end":"2026-05-30T01:47:30.509452Z","steps":["trace[279581441] 'process raft request' (duration: 535.755868ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:30.509589Z","caller":"traceutil/trace.go:171","msg":"trace[1670377817] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"491.380354ms","start":"2026-05-30T01:47:30.018152Z","end":"2026-05-30T01:47:30.509532Z","steps":["trace[1670377817] 'process raft request' (duration: 491.034813ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:30.509644Z","caller":"traceutil/trace.go:171","msg":"trace[1720227480] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"490.630602ms","start":"2026-05-30T01:47:30.018944Z","end":"2026-05-30T01:47:30.509575Z","steps":["trace[1720227480] 'process raft request' (duration: 490.385755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:30.509707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.770492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-30T01:47:30.509679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:29.973157Z","time spent":"536.434598ms","remote":"127.0.0.1:57096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:47:30.509807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:30.018913Z","time spent":"490.821288ms","remote":"127.0.0.1:57096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T01:47:30.509754Z","caller":"traceutil/trace.go:171","msg":"trace[1834725248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"440.854095ms","start":"2026-05-30T01:47:30.068887Z","end":"2026-05-30T01:47:30.509741Z","steps":["trace[1834725248] 'agreement among raft nodes before linearized reading' (duration: 440.640269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:30.509876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:30.018114Z","time spent":"491.60249ms","remote":"127.0.0.1:57096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:47:30.509909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:30.068872Z","time spent":"441.026231ms","remote":"127.0.0.1:57326","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-30T01:47:36.98471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.900423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:47:36.984812Z","caller":"traceutil/trace.go:171","msg":"trace[127744300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"265.049276ms","start":"2026-05-30T01:47:36.719742Z","end":"2026-05-30T01:47:36.984791Z","steps":["trace[127744300] 'range keys from in-memory index tree' (duration: 264.838991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:36.984833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.437892ms","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-30T01:47:36.984948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.454327ms","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-30T01:47:36.985007Z","caller":"traceutil/trace.go:171","msg":"trace[1398602629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"425.54878ms","start":"2026-05-30T01:47:36.559443Z","end":"2026-05-30T01:47:36.984992Z","steps":["trace[1398602629] 'range keys from in-memory index tree' (duration: 425.344764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:36.985049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:36.559424Z","time spent":"425.616651ms","remote":"127.0.0.1:57326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-30T01:47:36.98492Z","caller":"traceutil/trace.go:171","msg":"trace[419654839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"183.595086ms","start":"2026-05-30T01:47:36.801304Z","end":"2026-05-30T01:47:36.984899Z","steps":["trace[419654839] 'range keys from in-memory index tree' (duration: 183.141414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:36.985203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.71938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-30T01:47:36.985276Z","caller":"traceutil/trace.go:171","msg":"trace[1498148092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"183.805301ms","start":"2026-05-30T01:47:36.801457Z","end":"2026-05-30T01:47:36.985262Z","steps":["trace[1498148092] 'range keys from in-memory index tree' (duration: 183.567525ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:37.050599Z","caller":"traceutil/trace.go:171","msg":"trace[1701083598] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"212.875501ms","start":"2026-05-30T01:47:36.837702Z","end":"2026-05-30T01:47:37.050577Z","steps":["trace[1701083598] 'process raft request' (duration: 212.333586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:38.364282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16433646s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:47:38.364485Z","caller":"traceutil/trace.go:171","msg":"trace[508545011] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"1.375453262s","start":"2026-05-30T01:47:36.989006Z","end":"2026-05-30T01:47:38.364459Z","steps":["trace[508545011] 'process raft request' (duration: 210.613318ms)","trace[508545011] 'compare' (duration: 1.164047613s)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:47:38.36458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:36.988977Z","time spent":"1.375555665s","remote":"127.0.0.1:57326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:47:38.364797Z","caller":"traceutil/trace.go:171","msg":"trace[523809318] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:610; }","duration":"1.079186746s","start":"2026-05-30T01:47:37.285583Z","end":"2026-05-30T01:47:38.36477Z","steps":["trace[523809318] 'read index received' (duration: 470.596862ms)","trace[523809318] 'applied index is now lower than readState.Index' (duration: 608.588934ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:47:38.365068Z","caller":"traceutil/trace.go:171","msg":"trace[1065204631] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.242032904s","start":"2026-05-30T01:47:37.123023Z","end":"2026-05-30T01:47:38.365056Z","steps":["trace[1065204631] 'process raft request' (duration: 1.241713515s)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:38.365116Z","caller":"traceutil/trace.go:171","msg":"trace[1719427010] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.304041849s","start":"2026-05-30T01:47:37.061057Z","end":"2026-05-30T01:47:38.365099Z","steps":["trace[1719427010] 'process raft request' (duration: 1.303644308s)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:38.365104Z","caller":"traceutil/trace.go:171","msg":"trace[1174127894] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.374962398s","start":"2026-05-30T01:47:36.990104Z","end":"2026-05-30T01:47:38.365067Z","steps":["trace[1174127894] 'process raft request' (duration: 1.374525146s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:38.365213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079630798s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-30T01:47:38.365213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:37.061039Z","time spent":"1.304127312s","remote":"127.0.0.1:57326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:47:38.365245Z","caller":"traceutil/trace.go:171","msg":"trace[550587263] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:591; }","duration":"1.079720682s","start":"2026-05-30T01:47:37.285515Z","end":"2026-05-30T01:47:38.365236Z","steps":["trace[550587263] 'agreement among raft nodes before linearized reading' (duration: 1.079660449s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:38.365273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:37.285495Z","time spent":"1.079771024s","remote":"127.0.0.1:57072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.169\" "} {"level":"warn","ts":"2026-05-30T01:47:38.3653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:36.99009Z","time spent":"1.375105772s","remote":"127.0.0.1:57326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:47:38.365351Z","caller":"traceutil/trace.go:171","msg":"trace[1794107988] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.376108482s","start":"2026-05-30T01:47:36.989211Z","end":"2026-05-30T01:47:38.36532Z","steps":["trace[1794107988] 'process raft request' (duration: 1.375187606s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:38.365487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067427337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fgpnb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-30T01:47:38.365529Z","caller":"traceutil/trace.go:171","msg":"trace[1532315496] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fgpnb; range_end:; response_count:1; response_revision:591; }","duration":"1.067481968s","start":"2026-05-30T01:47:37.298038Z","end":"2026-05-30T01:47:38.36552Z","steps":["trace[1532315496] 'agreement among raft nodes before linearized reading' (duration: 1.067415357s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:38.365523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:36.989198Z","time spent":"1.376240205s","remote":"127.0.0.1:57326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:47:38.365561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:37.298031Z","time spent":"1.067524619s","remote":"127.0.0.1:57230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-fgpnb\" "} {"level":"warn","ts":"2026-05-30T01:47:38.365149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:37.123Z","time spent":"1.242098346s","remote":"127.0.0.1:57288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T01:47:38.365691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.959774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T01:47:38.365728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.474328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T01:47:38.365732Z","caller":"traceutil/trace.go:171","msg":"trace[1784358700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"644.042796ms","start":"2026-05-30T01:47:37.721677Z","end":"2026-05-30T01:47:38.36572Z","steps":["trace[1784358700] 'agreement among raft nodes before linearized reading' (duration: 643.971124ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:38.365764Z","caller":"traceutil/trace.go:171","msg":"trace[941836631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"918.54657ms","start":"2026-05-30T01:47:37.447206Z","end":"2026-05-30T01:47:38.365752Z","steps":["trace[941836631] 'agreement among raft nodes before linearized reading' (duration: 918.429137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:38.365769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:37.721652Z","time spent":"644.110378ms","remote":"127.0.0.1:57060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-30T01:47:38.365796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:37.447172Z","time spent":"918.617642ms","remote":"127.0.0.1:57326","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-30T01:47:39.786164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.222179429s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:47:39.786418Z","caller":"traceutil/trace.go:171","msg":"trace[267940964] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"1.407096127s","start":"2026-05-30T01:47:38.379302Z","end":"2026-05-30T01:47:39.786398Z","steps":["trace[267940964] 'process raft request' (duration: 1.407002275s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:39.786495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:38.379282Z","time spent":"1.40717785s","remote":"127.0.0.1:57230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:47:39.786525Z","caller":"traceutil/trace.go:171","msg":"trace[1931222288] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"1.415017747s","start":"2026-05-30T01:47:38.371487Z","end":"2026-05-30T01:47:39.786505Z","steps":["trace[1931222288] 'process raft request' (duration: 192.365445ms)","trace[1931222288] 'compare' (duration: 1.221965474s)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:47:39.786644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:38.371472Z","time spent":"1.41510817s","remote":"127.0.0.1:57326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:47:39.835222Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.271284899s","expected-duration":"1s"} {"level":"info","ts":"2026-05-30T01:47:39.835619Z","caller":"traceutil/trace.go:171","msg":"trace[1754635375] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:616; }","duration":"1.383083499s","start":"2026-05-30T01:47:38.452512Z","end":"2026-05-30T01:47:39.835596Z","steps":["trace[1754635375] 'read index received' (duration: 111.458481ms)","trace[1754635375] 'applied index is now lower than readState.Index' (duration: 1.271624008s)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:47:39.835677Z","caller":"traceutil/trace.go:171","msg":"trace[71405611] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.385372453s","start":"2026-05-30T01:47:38.45027Z","end":"2026-05-30T01:47:39.835642Z","steps":["trace[71405611] 'process raft request' (duration: 1.385179537s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:39.83583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.383305745s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-05-30T01:47:39.835834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.243288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T01:47:39.835841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:38.450248Z","time spent":"1.385504947s","remote":"127.0.0.1:57072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:47:39.83588Z","caller":"traceutil/trace.go:171","msg":"trace[99931275] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:594; }","duration":"1.383364297s","start":"2026-05-30T01:47:38.452501Z","end":"2026-05-30T01:47:39.835865Z","steps":["trace[99931275] 'agreement among raft nodes before linearized reading' (duration: 1.383214233s)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:39.835891Z","caller":"traceutil/trace.go:171","msg":"trace[1180014884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"115.351061ms","start":"2026-05-30T01:47:39.720524Z","end":"2026-05-30T01:47:39.835875Z","steps":["trace[1180014884] 'agreement among raft nodes before linearized reading' (duration: 115.219347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:39.835923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:38.452442Z","time spent":"1.38347144s","remote":"127.0.0.1:57248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-30T01:47:40.871902Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828985506106191,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T01:47:40.922196Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.077077026s","expected-duration":"1s"} {"level":"info","ts":"2026-05-30T01:47:40.922648Z","caller":"traceutil/trace.go:171","msg":"trace[111734017] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.07758486s","start":"2026-05-30T01:47:39.845041Z","end":"2026-05-30T01:47:40.922626Z","steps":["trace[111734017] 'process raft request' (duration: 1.077442176s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:40.922774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:39.845026Z","time spent":"1.077690824s","remote":"127.0.0.1:57230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T01:47:40.999275Z","caller":"traceutil/trace.go:171","msg":"trace[1266875085] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:619; }","duration":"628.312472ms","start":"2026-05-30T01:47:40.370938Z","end":"2026-05-30T01:47:40.999251Z","steps":["trace[1266875085] 'read index received' (duration: 551.491148ms)","trace[1266875085] 'applied index is now lower than readState.Index' (duration: 76.817353ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:47:40.999356Z","caller":"traceutil/trace.go:171","msg":"trace[2012935909] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"823.188951ms","start":"2026-05-30T01:47:40.176145Z","end":"2026-05-30T01:47:40.999334Z","steps":["trace[2012935909] 'process raft request' (duration: 822.946684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:40.99949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.527857ms","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-30T01:47:40.999765Z","caller":"traceutil/trace.go:171","msg":"trace[1723662532] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:596; }","duration":"628.834086ms","start":"2026-05-30T01:47:40.370907Z","end":"2026-05-30T01:47:40.999741Z","steps":["trace[1723662532] 'agreement among raft nodes before linearized reading' (duration: 628.504847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:40.999747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:40.176129Z","time spent":"823.481368ms","remote":"127.0.0.1:57326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:47:40.999845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:40.370892Z","time spent":"628.933478ms","remote":"127.0.0.1:57326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-30T01:47:41.200452Z","caller":"traceutil/trace.go:171","msg":"trace[214928655] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"273.868655ms","start":"2026-05-30T01:47:40.926556Z","end":"2026-05-30T01:47:41.200425Z","steps":["trace[214928655] 'process raft request' (duration: 273.627608ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:41.200538Z","caller":"traceutil/trace.go:171","msg":"trace[276811242] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"273.811914ms","start":"2026-05-30T01:47:40.926693Z","end":"2026-05-30T01:47:41.200505Z","steps":["trace[276811242] 'process raft request' (duration: 273.66299ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:41.200581Z","caller":"traceutil/trace.go:171","msg":"trace[644860702] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"271.084079ms","start":"2026-05-30T01:47:40.929469Z","end":"2026-05-30T01:47:41.200553Z","steps":["trace[644860702] 'process raft request' (duration: 271.005057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:41.200658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.244811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T01:47:41.200672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.697742ms","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-30T01:47:41.200545Z","caller":"traceutil/trace.go:171","msg":"trace[277377635] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:621; }","duration":"201.047481ms","start":"2026-05-30T01:47:40.999473Z","end":"2026-05-30T01:47:41.200521Z","steps":["trace[277377635] 'read index received' (duration: 200.852475ms)","trace[277377635] 'applied index is now lower than readState.Index' (duration: 193.976µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:47:41.200719Z","caller":"traceutil/trace.go:171","msg":"trace[1010789950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"479.354533ms","start":"2026-05-30T01:47:40.721351Z","end":"2026-05-30T01:47:41.200705Z","steps":["trace[1010789950] 'agreement among raft nodes before linearized reading' (duration: 479.20783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:41.200755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:40.721321Z","time spent":"479.430006ms","remote":"127.0.0.1:57060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-30T01:47:41.200743Z","caller":"traceutil/trace.go:171","msg":"trace[1729809060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:600; }","duration":"829.803144ms","start":"2026-05-30T01:47:40.370919Z","end":"2026-05-30T01:47:41.200722Z","steps":["trace[1729809060] 'agreement among raft nodes before linearized reading' (duration: 829.674491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:41.200808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:40.370908Z","time spent":"829.885917ms","remote":"127.0.0.1:57326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T01:47:41.200803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.67944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-30T01:47:41.200885Z","caller":"traceutil/trace.go:171","msg":"trace[1262037600] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:600; }","duration":"829.783683ms","start":"2026-05-30T01:47:40.371088Z","end":"2026-05-30T01:47:41.200871Z","steps":["trace[1262037600] 'agreement among raft nodes before linearized reading' (duration: 829.514516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:41.200963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:40.371083Z","time spent":"829.867805ms","remote":"127.0.0.1:57326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-30T01:47:41.200902Z","caller":"traceutil/trace.go:171","msg":"trace[508221705] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"273.109255ms","start":"2026-05-30T01:47:40.927777Z","end":"2026-05-30T01:47:41.200886Z","steps":["trace[508221705] 'process raft request' (duration: 272.642323ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:41.250698Z","caller":"traceutil/trace.go:171","msg":"trace[131741890] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"246.494448ms","start":"2026-05-30T01:47:41.004166Z","end":"2026-05-30T01:47:41.250661Z","steps":["trace[131741890] 'process raft request' (duration: 246.312162ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:42.599589Z","caller":"traceutil/trace.go:171","msg":"trace[1044251800] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"280.10803ms","start":"2026-05-30T01:47:42.319455Z","end":"2026-05-30T01:47:42.599563Z","steps":["trace[1044251800] 'process raft request' (duration: 279.882784ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:43.095448Z","caller":"traceutil/trace.go:171","msg":"trace[81292438] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"761.386873ms","start":"2026-05-30T01:47:42.33403Z","end":"2026-05-30T01:47:43.095417Z","steps":["trace[81292438] 'process raft request' (duration: 760.963532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:43.09564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:42.333962Z","time spent":"761.579819ms","remote":"127.0.0.1:57230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:47:43.19048Z","caller":"traceutil/trace.go:171","msg":"trace[1413812663] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:634; }","duration":"468.604392ms","start":"2026-05-30T01:47:42.721856Z","end":"2026-05-30T01:47:43.19046Z","steps":["trace[1413812663] 'read index received' (duration: 373.051556ms)","trace[1413812663] 'applied index is now lower than readState.Index' (duration: 95.551576ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:47:43.190577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.713615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:47:43.190611Z","caller":"traceutil/trace.go:171","msg":"trace[1401770302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"468.789657ms","start":"2026-05-30T01:47:42.72181Z","end":"2026-05-30T01:47:43.190599Z","steps":["trace[1401770302] 'agreement among raft nodes before linearized reading' (duration: 468.725125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:43.190643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:42.721789Z","time spent":"468.846439ms","remote":"127.0.0.1:57060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-30T01:47:44.683356Z","caller":"traceutil/trace.go:171","msg":"trace[1927146272] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"153.543411ms","start":"2026-05-30T01:47:44.529788Z","end":"2026-05-30T01:47:44.683331Z","steps":["trace[1927146272] 'process raft request' (duration: 153.281454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:46.665153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.09674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-05-30T01:47:46.665242Z","caller":"traceutil/trace.go:171","msg":"trace[1187255256] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:669; }","duration":"136.205603ms","start":"2026-05-30T01:47:46.529014Z","end":"2026-05-30T01:47:46.66522Z","steps":["trace[1187255256] 'range keys from in-memory index tree' (duration: 135.846994ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:54.323122Z","caller":"traceutil/trace.go:171","msg":"trace[1898690405] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"137.889279ms","start":"2026-05-30T01:47:54.185199Z","end":"2026-05-30T01:47:54.323088Z","steps":["trace[1898690405] 'process raft request' (duration: 137.666263ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:57.539663Z","caller":"traceutil/trace.go:171","msg":"trace[319557592] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"167.140073ms","start":"2026-05-30T01:47:57.372495Z","end":"2026-05-30T01:47:57.539635Z","steps":["trace[319557592] 'process raft request' (duration: 165.401152ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:57.546512Z","caller":"traceutil/trace.go:171","msg":"trace[1560238701] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"170.431591ms","start":"2026-05-30T01:47:57.376067Z","end":"2026-05-30T01:47:57.546499Z","steps":["trace[1560238701] 'process raft request' (duration: 170.091932ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:57.54666Z","caller":"traceutil/trace.go:171","msg":"trace[727569144] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"171.074676ms","start":"2026-05-30T01:47:57.375576Z","end":"2026-05-30T01:47:57.546651Z","steps":["trace[727569144] 'process raft request' (duration: 170.371349ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:57.546644Z","caller":"traceutil/trace.go:171","msg":"trace[679670150] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"125.16208ms","start":"2026-05-30T01:47:57.42142Z","end":"2026-05-30T01:47:57.546582Z","steps":["trace[679670150] 'process raft request' (duration: 124.76906ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:57.546796Z","caller":"traceutil/trace.go:171","msg":"trace[1207938313] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"171.177848ms","start":"2026-05-30T01:47:57.37561Z","end":"2026-05-30T01:47:57.546788Z","steps":["trace[1207938313] 'process raft request' (duration: 170.503452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:48:01.778396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.483341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:48:01.778582Z","caller":"traceutil/trace.go:171","msg":"trace[1070394235] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:807; }","duration":"219.972923ms","start":"2026-05-30T01:48:01.558559Z","end":"2026-05-30T01:48:01.778532Z","steps":["trace[1070394235] 'read index received' (duration: 106.163484ms)","trace[1070394235] 'applied index is now lower than readState.Index' (duration: 113.807759ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:48:01.778955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.332209ms","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-30T01:48:01.779021Z","caller":"traceutil/trace.go:171","msg":"trace[1990787593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:778; }","duration":"219.419101ms","start":"2026-05-30T01:48:01.559586Z","end":"2026-05-30T01:48:01.779005Z","steps":["trace[1990787593] 'agreement among raft nodes before linearized reading' (duration: 219.191675ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:48:01.779027Z","caller":"traceutil/trace.go:171","msg":"trace[851814781] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"250.820801ms","start":"2026-05-30T01:48:01.528186Z","end":"2026-05-30T01:48:01.779007Z","steps":["trace[851814781] 'process raft request' (duration: 136.618173ms)","trace[851814781] 'compare' (duration: 113.253956ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:48:01.778955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.848298ms","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-30T01:48:01.779261Z","caller":"traceutil/trace.go:171","msg":"trace[98871448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:778; }","duration":"219.105555ms","start":"2026-05-30T01:48:01.56009Z","end":"2026-05-30T01:48:01.779196Z","steps":["trace[98871448] 'agreement among raft nodes before linearized reading' (duration: 218.736066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:48:01.779082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.449555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-30T01:48:01.779395Z","caller":"traceutil/trace.go:171","msg":"trace[1618183511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:778; }","duration":"220.861414ms","start":"2026-05-30T01:48:01.558516Z","end":"2026-05-30T01:48:01.779377Z","steps":["trace[1618183511] 'agreement among raft nodes before linearized reading' (duration: 220.210818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:48:02.113543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.111904ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:48:02.114072Z","caller":"traceutil/trace.go:171","msg":"trace[1682172313] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:808; }","duration":"197.400447ms","start":"2026-05-30T01:48:01.916652Z","end":"2026-05-30T01:48:02.114052Z","steps":["trace[1682172313] 'read index received' (duration: 196.555218ms)","trace[1682172313] 'applied index is now lower than readState.Index' (duration: 844.259µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:48:02.11407Z","caller":"traceutil/trace.go:171","msg":"trace[218495685] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"330.157851ms","start":"2026-05-30T01:48:01.78388Z","end":"2026-05-30T01:48:02.114038Z","steps":["trace[218495685] 'process raft request' (duration: 131.412413ms)","trace[218495685] 'compare' (duration: 197.988681ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:48:02.11414Z","caller":"traceutil/trace.go:171","msg":"trace[1496557612] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"329.23669ms","start":"2026-05-30T01:48:01.784879Z","end":"2026-05-30T01:48:02.114116Z","steps":["trace[1496557612] 'process raft request' (duration: 328.913353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:48:02.114284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.628151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-30T01:48:02.114297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:48:01.784873Z","time spent":"329.334012ms","remote":"127.0.0.1:57326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:48:02.114334Z","caller":"traceutil/trace.go:171","msg":"trace[1644555027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:782; }","duration":"197.716634ms","start":"2026-05-30T01:48:01.9166Z","end":"2026-05-30T01:48:02.114316Z","steps":["trace[1644555027] 'agreement among raft nodes before linearized reading' (duration: 197.525159ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:48:02.114465Z","caller":"traceutil/trace.go:171","msg":"trace[1437169294] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"330.151042ms","start":"2026-05-30T01:48:01.784304Z","end":"2026-05-30T01:48:02.114455Z","steps":["trace[1437169294] 'process raft request' (duration: 329.408845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:48:02.114464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:48:01.783855Z","time spent":"330.284545ms","remote":"127.0.0.1:57326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:48:02.114513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:48:01.784287Z","time spent":"330.200042ms","remote":"127.0.0.1:57326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:48:02.11459Z","caller":"traceutil/trace.go:171","msg":"trace[1106919655] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"329.231809ms","start":"2026-05-30T01:48:01.785343Z","end":"2026-05-30T01:48:02.114575Z","steps":["trace[1106919655] 'process raft request' (duration: 328.593305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:48:02.114683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:48:01.785338Z","time spent":"329.294531ms","remote":"127.0.0.1:57288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2882,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:48:02.273684Z","caller":"traceutil/trace.go:171","msg":"trace[641717256] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"154.084547ms","start":"2026-05-30T01:48:02.119573Z","end":"2026-05-30T01:48:02.273657Z","steps":["trace[641717256] 'process raft request' (duration: 102.075293ms)","trace[641717256] 'compare' (duration: 51.84098ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:48:02.27393Z","caller":"traceutil/trace.go:171","msg":"trace[648208733] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"115.416466ms","start":"2026-05-30T01:48:02.158467Z","end":"2026-05-30T01:48:02.273884Z","steps":["trace[648208733] 'process raft request' (duration: 115.312464ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:48:02.273992Z","caller":"traceutil/trace.go:171","msg":"trace[1697685180] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"153.636046ms","start":"2026-05-30T01:48:02.120344Z","end":"2026-05-30T01:48:02.27398Z","steps":["trace[1697685180] 'process raft request' (duration: 153.243958ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:48:05.480221Z","caller":"traceutil/trace.go:171","msg":"trace[787423152] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"108.512856ms","start":"2026-05-30T01:48:05.371683Z","end":"2026-05-30T01:48:05.480196Z","steps":["trace[787423152] 'process raft request' (duration: 108.355862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:48:07.632636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.532638ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:48:07.632745Z","caller":"traceutil/trace.go:171","msg":"trace[795814233] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"149.07077ms","start":"2026-05-30T01:48:07.483656Z","end":"2026-05-30T01:48:07.632727Z","steps":["trace[795814233] 'process raft request' (duration: 36.352358ms)","trace[795814233] 'compare' (duration: 112.408806ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:48:07.633432Z","caller":"traceutil/trace.go:171","msg":"trace[1962496665] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"143.664301ms","start":"2026-05-30T01:48:07.489755Z","end":"2026-05-30T01:48:07.633419Z","steps":["trace[1962496665] 'process raft request' (duration: 143.61518ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:48:07.633621Z","caller":"traceutil/trace.go:171","msg":"trace[240824571] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"147.405903ms","start":"2026-05-30T01:48:07.486206Z","end":"2026-05-30T01:48:07.633611Z","steps":["trace[240824571] 'process raft request' (duration: 147.121907ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:48:07.633718Z","caller":"traceutil/trace.go:171","msg":"trace[482958658] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"148.832085ms","start":"2026-05-30T01:48:07.484815Z","end":"2026-05-30T01:48:07.633647Z","steps":["trace[482958658] 'process raft request' (duration: 148.432567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:48:10.217271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.782551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:48:10.217431Z","caller":"traceutil/trace.go:171","msg":"trace[1598032485] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"208.867416ms","start":"2026-05-30T01:48:10.008538Z","end":"2026-05-30T01:48:10.217405Z","steps":["trace[1598032485] 'process raft request' (duration: 96.844871ms)","trace[1598032485] 'compare' (duration: 111.650727ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:48:10.235125Z","caller":"traceutil/trace.go:171","msg":"trace[645115455] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:933; }","duration":"222.191491ms","start":"2026-05-30T01:48:10.012915Z","end":"2026-05-30T01:48:10.235107Z","steps":["trace[645115455] 'read index received' (duration: 92.485816ms)","trace[645115455] 'applied index is now lower than readState.Index' (duration: 129.704785ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:48:10.235205Z","caller":"traceutil/trace.go:171","msg":"trace[2072507348] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"224.216504ms","start":"2026-05-30T01:48:10.010959Z","end":"2026-05-30T01:48:10.235175Z","steps":["trace[2072507348] 'process raft request' (duration: 224.070701ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:48:10.235339Z","caller":"traceutil/trace.go:171","msg":"trace[779832863] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"223.950409ms","start":"2026-05-30T01:48:10.011356Z","end":"2026-05-30T01:48:10.235306Z","steps":["trace[779832863] 'process raft request' (duration: 223.715254ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:48:10.235419Z","caller":"traceutil/trace.go:171","msg":"trace[1545532870] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"224.768036ms","start":"2026-05-30T01:48:10.010634Z","end":"2026-05-30T01:48:10.235402Z","steps":["trace[1545532870] 'process raft request' (duration: 224.269225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:48:10.235778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.801324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-05-30T01:48:10.235925Z","caller":"traceutil/trace.go:171","msg":"trace[987451348] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:904; }","duration":"222.999778ms","start":"2026-05-30T01:48:10.012889Z","end":"2026-05-30T01:48:10.235889Z","steps":["trace[987451348] 'agreement among raft nodes before linearized reading' (duration: 222.456787ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:48:10.879367Z","caller":"traceutil/trace.go:171","msg":"trace[1222854993] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"140.956317ms","start":"2026-05-30T01:48:10.738392Z","end":"2026-05-30T01:48:10.879348Z","steps":["trace[1222854993] 'process raft request' (duration: 140.850195ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:48:16.473178Z","caller":"traceutil/trace.go:171","msg":"trace[898512054] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"116.517922ms","start":"2026-05-30T01:48:16.356628Z","end":"2026-05-30T01:48:16.473146Z","steps":["trace[898512054] 'process raft request' (duration: 96.465769ms)","trace[898512054] 'compare' (duration: 19.874299ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:48:28.532942Z","caller":"traceutil/trace.go:171","msg":"trace[1332328249] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1024; }","duration":"119.76514ms","start":"2026-05-30T01:48:28.413161Z","end":"2026-05-30T01:48:28.532926Z","steps":["trace[1332328249] 'read index received' (duration: 119.661548ms)","trace[1332328249] 'applied index is now lower than readState.Index' (duration: 102.582µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:48:28.533002Z","caller":"traceutil/trace.go:171","msg":"trace[1890258547] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"124.056572ms","start":"2026-05-30T01:48:28.40891Z","end":"2026-05-30T01:48:28.532966Z","steps":["trace[1890258547] 'process raft request' (duration: 123.899529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:48:28.53324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.075625ms","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-30T01:48:28.533299Z","caller":"traceutil/trace.go:171","msg":"trace[137070368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:987; }","duration":"120.202017ms","start":"2026-05-30T01:48:28.413081Z","end":"2026-05-30T01:48:28.533283Z","steps":["trace[137070368] 'agreement among raft nodes before linearized reading' (duration: 119.948553ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:48:44.150033Z","caller":"traceutil/trace.go:171","msg":"trace[1960663072] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1085; }","duration":"105.268514ms","start":"2026-05-30T01:48:44.044744Z","end":"2026-05-30T01:48:44.150012Z","steps":["trace[1960663072] 'read index received' (duration: 105.133042ms)","trace[1960663072] 'applied index is now lower than readState.Index' (duration: 134.632µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:48:44.150137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.378986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:48:44.150169Z","caller":"traceutil/trace.go:171","msg":"trace[1264672343] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1044; }","duration":"105.458567ms","start":"2026-05-30T01:48:44.044699Z","end":"2026-05-30T01:48:44.150158Z","steps":["trace[1264672343] 'agreement among raft nodes before linearized reading' (duration: 105.393856ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:48:44.150255Z","caller":"traceutil/trace.go:171","msg":"trace[1188438922] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"177.746766ms","start":"2026-05-30T01:48:43.972491Z","end":"2026-05-30T01:48:44.150238Z","steps":["trace[1188438922] 'process raft request' (duration: 177.388ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:48:47.05861Z","caller":"traceutil/trace.go:171","msg":"trace[1875064814] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"134.578339ms","start":"2026-05-30T01:48:46.924011Z","end":"2026-05-30T01:48:47.058589Z","steps":["trace[1875064814] 'process raft request' (duration: 133.968178ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:49:17.375789Z","caller":"traceutil/trace.go:171","msg":"trace[1844589944] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"118.381544ms","start":"2026-05-30T01:49:17.257388Z","end":"2026-05-30T01:49:17.375769Z","steps":["trace[1844589944] 'process raft request' (duration: 118.229672ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:49:19.175368Z","caller":"traceutil/trace.go:171","msg":"trace[1069102996] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"215.533235ms","start":"2026-05-30T01:49:18.959806Z","end":"2026-05-30T01:49:19.175339Z","steps":["trace[1069102996] 'process raft request' (duration: 153.553266ms)","trace[1069102996] 'compare' (duration: 61.820967ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:49:19.1755Z","caller":"traceutil/trace.go:171","msg":"trace[1314098175] linearizableReadLoop","detail":"{readStateIndex:1208; appliedIndex:1206; }","duration":"123.882076ms","start":"2026-05-30T01:49:19.051589Z","end":"2026-05-30T01:49:19.175471Z","steps":["trace[1314098175] 'read index received' (duration: 61.777995ms)","trace[1314098175] 'applied index is now lower than readState.Index' (duration: 62.102351ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:49:19.175568Z","caller":"traceutil/trace.go:171","msg":"trace[1574686626] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"212.804154ms","start":"2026-05-30T01:49:18.96272Z","end":"2026-05-30T01:49:19.175524Z","steps":["trace[1574686626] 'process raft request' (duration: 212.582071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:19.17567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.081129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-30T01:49:19.175709Z","caller":"traceutil/trace.go:171","msg":"trace[1641938063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1159; }","duration":"124.187231ms","start":"2026-05-30T01:49:19.051511Z","end":"2026-05-30T01:49:19.175698Z","steps":["trace[1641938063] 'agreement among raft nodes before linearized reading' (duration: 124.056599ms)"],"step_count":1}