{"level":"warn","ts":"2026-05-19T22:14:01.756426Z","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-19T22:14:01.756572Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4: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-19T22:14:01.758481Z","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-19T22:14:01.758515Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-05-19T22:14:01.758549Z","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-19T22:14:01.759695Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-05-19T22:14:01.759841Z","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.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4: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-19T22:14:01.764105Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.010244ms"} {"level":"info","ts":"2026-05-19T22:14:01.774327Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-05-19T22:14:01.774416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T22:14:01.774443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-05-19T22:14:01.774456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T22:14:01.774467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-05-19T22:14:01.774514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-05-19T22:14:01.779685Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T22:14:01.781114Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T22:14:01.782315Z","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-19T22:14:01.784714Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T22:14:01.788421Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T22:14:01.788441Z","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-19T22:14:01.788526Z","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-19T22:14:01.788546Z","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-19T22:14:01.78964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-05-19T22:14:01.789842Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-05-19T22:14:01.790783Z","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-19T22:14:01.790913Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-05-19T22:14:01.790942Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-05-19T22:14:01.791209Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T22:14:01.791237Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T22:14:02.075635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T22:14:02.07569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T22:14:02.075734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-05-19T22:14:02.075789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-05-19T22:14:02.075874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-05-19T22:14:02.075969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-05-19T22:14:02.076018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-05-19T22:14:02.076976Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T22:14:02.077032Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T22:14:02.07709Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T22:14:02.077149Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T22:14:02.077363Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T22:14:02.077392Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T22:14:02.078013Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T22:14:02.078105Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T22:14:02.07814Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T22:14:02.080606Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T22:14:02.081096Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-05-19T22:14:03.829754Z","caller":"traceutil/trace.go:171","msg":"trace[1135385247] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"275.623107ms","start":"2026-05-19T22:14:03.554104Z","end":"2026-05-19T22:14:03.829728Z","steps":["trace[1135385247] 'process raft request' (duration: 275.382934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:03.830054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.961748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T22:14:03.829736Z","caller":"traceutil/trace.go:171","msg":"trace[1441071208] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"273.641375ms","start":"2026-05-19T22:14:03.556068Z","end":"2026-05-19T22:14:03.829709Z","steps":["trace[1441071208] 'read index received' (duration: 273.405452ms)","trace[1441071208] 'applied index is now lower than readState.Index' (duration: 235.013µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:14:03.830216Z","caller":"traceutil/trace.go:171","msg":"trace[1800146947] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:2; }","duration":"274.11494ms","start":"2026-05-19T22:14:03.556054Z","end":"2026-05-19T22:14:03.830169Z","steps":["trace[1800146947] 'agreement among raft nodes before linearized reading' (duration: 273.743525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:04.331153Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311466957418112,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T22:14:04.452253Z","caller":"traceutil/trace.go:171","msg":"trace[324009638] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"621.994015ms","start":"2026-05-19T22:14:03.830235Z","end":"2026-05-19T22:14:04.452229Z","steps":["trace[324009638] 'read index received' (duration: 621.599611ms)","trace[324009638] 'applied index is now lower than readState.Index' (duration: 393.534µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:14:04.452606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.366925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T22:14:04.452596Z","caller":"traceutil/trace.go:171","msg":"trace[266437759] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"895.236356ms","start":"2026-05-19T22:14:03.557331Z","end":"2026-05-19T22:14:04.452568Z","steps":["trace[266437759] 'process raft request' (duration: 894.72559ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:04.452658Z","caller":"traceutil/trace.go:171","msg":"trace[1905098464] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"755.453426ms","start":"2026-05-19T22:14:03.69719Z","end":"2026-05-19T22:14:04.452643Z","steps":["trace[1905098464] 'agreement among raft nodes before linearized reading' (duration: 755.339885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:04.452691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:03.697177Z","time spent":"755.506487ms","remote":"127.0.0.1:45180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-19T22:14:04.452749Z","caller":"traceutil/trace.go:171","msg":"trace[2015838502] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"895.204625ms","start":"2026-05-19T22:14:03.557513Z","end":"2026-05-19T22:14:04.452718Z","steps":["trace[2015838502] 'process raft request' (duration: 894.566178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:04.452911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.70775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3169"} {"level":"warn","ts":"2026-05-19T22:14:04.452966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.555113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T22:14:04.452939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.411741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T22:14:04.453003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.621058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T22:14:04.453046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:03.557325Z","time spent":"895.346077ms","remote":"127.0.0.1:45596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T22:14:04.453081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.48414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T22:14:04.453089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.370309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T22:14:04.453048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.907273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T22:14:04.453139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.485061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T22:14:04.45314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.181665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T22:14:04.453173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:03.557509Z","time spent":"895.301126ms","remote":"127.0.0.1:45596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T22:14:04.452795Z","caller":"traceutil/trace.go:171","msg":"trace[515667076] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"821.025544ms","start":"2026-05-19T22:14:03.631753Z","end":"2026-05-19T22:14:04.452779Z","steps":["trace[515667076] 'process raft request' (duration: 820.372127ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:04.45323Z","caller":"traceutil/trace.go:171","msg":"trace[1996524205] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"895.180304ms","start":"2026-05-19T22:14:03.557646Z","end":"2026-05-19T22:14:04.452826Z","steps":["trace[1996524205] 'process raft request' (duration: 894.459707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:04.453323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:03.55764Z","time spent":"895.634269ms","remote":"127.0.0.1:45596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T22:14:04.453362Z","caller":"traceutil/trace.go:171","msg":"trace[1742626978] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"814.542934ms","start":"2026-05-19T22:14:03.638308Z","end":"2026-05-19T22:14:04.45285Z","steps":["trace[1742626978] 'process raft request' (duration: 813.848786ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:04.452531Z","caller":"traceutil/trace.go:171","msg":"trace[2047208930] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"801.523244ms","start":"2026-05-19T22:14:03.650967Z","end":"2026-05-19T22:14:04.45249Z","steps":["trace[2047208930] 'process raft request' (duration: 801.227101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:04.453422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:03.638297Z","time spent":"815.100291ms","remote":"127.0.0.1:45296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T22:14:04.453448Z","caller":"traceutil/trace.go:171","msg":"trace[1108880617] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"896.372568ms","start":"2026-05-19T22:14:03.556497Z","end":"2026-05-19T22:14:04.452869Z","steps":["trace[1108880617] 'process raft request' (duration: 895.401437ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:04.453942Z","caller":"traceutil/trace.go:171","msg":"trace[925980585] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"619.790771ms","start":"2026-05-19T22:14:03.833154Z","end":"2026-05-19T22:14:04.452944Z","steps":["trace[925980585] 'agreement among raft nodes before linearized reading' (duration: 619.69037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:04.454006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:03.833139Z","time spent":"620.855282ms","remote":"127.0.0.1:45250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3192,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-19T22:14:04.454131Z","caller":"traceutil/trace.go:171","msg":"trace[235941318] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"850.581323ms","start":"2026-05-19T22:14:03.602404Z","end":"2026-05-19T22:14:04.452986Z","steps":["trace[235941318] 'agreement among raft nodes before linearized reading' (duration: 850.542913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:04.454172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:03.602391Z","time spent":"851.771436ms","remote":"127.0.0.1:45180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-19T22:14:04.454299Z","caller":"traceutil/trace.go:171","msg":"trace[1580939789] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"850.554522ms","start":"2026-05-19T22:14:03.602467Z","end":"2026-05-19T22:14:04.453022Z","steps":["trace[1580939789] 'agreement among raft nodes before linearized reading' (duration: 850.34228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:04.454331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:03.602451Z","time spent":"851.870516ms","remote":"127.0.0.1:45180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-19T22:14:04.45448Z","caller":"traceutil/trace.go:171","msg":"trace[1898625581] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:10; }","duration":"894.658598ms","start":"2026-05-19T22:14:03.558363Z","end":"2026-05-19T22:14:04.453021Z","steps":["trace[1898625581] 'agreement among raft nodes before linearized reading' (duration: 894.625038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:04.454514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:03.558356Z","time spent":"896.149384ms","remote":"127.0.0.1:45142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-05-19T22:14:04.454987Z","caller":"traceutil/trace.go:171","msg":"trace[1286716737] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"895.902133ms","start":"2026-05-19T22:14:03.557141Z","end":"2026-05-19T22:14:04.453043Z","steps":["trace[1286716737] 'process raft request' (duration: 894.881442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:04.455054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:03.557135Z","time spent":"897.886254ms","remote":"127.0.0.1:45596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T22:14:04.45512Z","caller":"traceutil/trace.go:171","msg":"trace[1802035286] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"822.51307ms","start":"2026-05-19T22:14:03.630592Z","end":"2026-05-19T22:14:04.453105Z","steps":["trace[1802035286] 'agreement among raft nodes before linearized reading' (duration: 822.47486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:04.455217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:03.630586Z","time spent":"824.604093ms","remote":"127.0.0.1:45180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-19T22:14:04.455332Z","caller":"traceutil/trace.go:171","msg":"trace[1198598939] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:0; response_revision:10; }","duration":"823.412459ms","start":"2026-05-19T22:14:03.629697Z","end":"2026-05-19T22:14:04.453109Z","steps":["trace[1198598939] 'agreement among raft nodes before linearized reading' (duration: 823.376359ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:04.455425Z","caller":"traceutil/trace.go:171","msg":"trace[257790708] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"771.044655ms","start":"2026-05-19T22:14:03.6821Z","end":"2026-05-19T22:14:04.453145Z","steps":["trace[257790708] 'agreement among raft nodes before linearized reading' (duration: 770.927994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:04.455439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:03.629692Z","time spent":"825.722684ms","remote":"127.0.0.1:45122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.4\" "} {"level":"warn","ts":"2026-05-19T22:14:04.455505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:03.682082Z","time spent":"773.40234ms","remote":"127.0.0.1:45180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-19T22:14:04.455607Z","caller":"traceutil/trace.go:171","msg":"trace[1577076373] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:10; }","duration":"798.533181ms","start":"2026-05-19T22:14:03.65462Z","end":"2026-05-19T22:14:04.453153Z","steps":["trace[1577076373] 'agreement among raft nodes before linearized reading' (duration: 798.502331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:04.455655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:03.654607Z","time spent":"801.037458ms","remote":"127.0.0.1:45180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-05-19T22:14:04.455879Z","caller":"traceutil/trace.go:171","msg":"trace[602290862] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"821.301987ms","start":"2026-05-19T22:14:03.63189Z","end":"2026-05-19T22:14:04.453192Z","steps":["trace[602290862] 'agreement among raft nodes before linearized reading' (duration: 821.170736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:04.455958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:03.631871Z","time spent":"824.066197ms","remote":"127.0.0.1:45168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-05-19T22:14:04.45627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:03.631744Z","time spent":"821.507499ms","remote":"127.0.0.1:45500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T22:14:04.457494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:03.556488Z","time spent":"896.977124ms","remote":"127.0.0.1:45596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T22:14:04.458162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:03.650934Z","time spent":"802.484764ms","remote":"127.0.0.1:45250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3157,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T22:14:04.723425Z","caller":"traceutil/trace.go:171","msg":"trace[813539596] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"261.305702ms","start":"2026-05-19T22:14:04.462089Z","end":"2026-05-19T22:14:04.723394Z","steps":["trace[813539596] 'process raft request' (duration: 258.545433ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:04.723452Z","caller":"traceutil/trace.go:171","msg":"trace[1377198437] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"261.14836ms","start":"2026-05-19T22:14:04.462275Z","end":"2026-05-19T22:14:04.723424Z","steps":["trace[1377198437] 'process raft request' (duration: 261.061389ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:04.723519Z","caller":"traceutil/trace.go:171","msg":"trace[1436883778] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:14; }","duration":"238.451117ms","start":"2026-05-19T22:14:04.485054Z","end":"2026-05-19T22:14:04.723505Z","steps":["trace[1436883778] 'read index received' (duration: 235.484936ms)","trace[1436883778] 'applied index is now lower than readState.Index' (duration: 2.965521ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:14:04.723599Z","caller":"traceutil/trace.go:171","msg":"trace[1419817422] transaction","detail":"{read_only:false; number_of_response:0; response_revision:13; }","duration":"261.305452ms","start":"2026-05-19T22:14:04.46229Z","end":"2026-05-19T22:14:04.723595Z","steps":["trace[1419817422] 'process raft request' (duration: 261.07373ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:04.723614Z","caller":"traceutil/trace.go:171","msg":"trace[221750226] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"260.012298ms","start":"2026-05-19T22:14:04.463595Z","end":"2026-05-19T22:14:04.723608Z","steps":["trace[221750226] 'process raft request' (duration: 259.835016ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:04.723685Z","caller":"traceutil/trace.go:171","msg":"trace[1916636941] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"260.045298ms","start":"2026-05-19T22:14:04.463635Z","end":"2026-05-19T22:14:04.72368Z","steps":["trace[1916636941] 'process raft request' (duration: 259.813286ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:04.723732Z","caller":"traceutil/trace.go:171","msg":"trace[1902614492] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"260.986058ms","start":"2026-05-19T22:14:04.462709Z","end":"2026-05-19T22:14:04.723695Z","steps":["trace[1902614492] 'process raft request' (duration: 260.695525ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:04.723843Z","caller":"traceutil/trace.go:171","msg":"trace[144475648] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"260.16816ms","start":"2026-05-19T22:14:04.463668Z","end":"2026-05-19T22:14:04.723837Z","steps":["trace[144475648] 'process raft request' (duration: 259.794766ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:04.723856Z","caller":"traceutil/trace.go:171","msg":"trace[449794806] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"260.860377ms","start":"2026-05-19T22:14:04.462987Z","end":"2026-05-19T22:14:04.723847Z","steps":["trace[449794806] 'process raft request' (duration: 260.430132ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:04.723935Z","caller":"traceutil/trace.go:171","msg":"trace[690211325] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"261.430223ms","start":"2026-05-19T22:14:04.462495Z","end":"2026-05-19T22:14:04.723925Z","steps":["trace[690211325] 'process raft request' (duration: 260.880587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:04.724024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.65048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T22:14:04.724026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.352866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T22:14:04.724045Z","caller":"traceutil/trace.go:171","msg":"trace[485495361] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:20; }","duration":"194.70583ms","start":"2026-05-19T22:14:04.529332Z","end":"2026-05-19T22:14:04.724038Z","steps":["trace[485495361] 'agreement among raft nodes before linearized reading' (duration: 194.655439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:04.72405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.278003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-5lj9w\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-05-19T22:14:04.724071Z","caller":"traceutil/trace.go:171","msg":"trace[1347342118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"135.428617ms","start":"2026-05-19T22:14:04.588615Z","end":"2026-05-19T22:14:04.724044Z","steps":["trace[1347342118] 'agreement among raft nodes before linearized reading' (duration: 135.358376ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:04.724083Z","caller":"traceutil/trace.go:171","msg":"trace[989104916] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-5lj9w; range_end:; response_count:1; response_revision:20; }","duration":"263.322463ms","start":"2026-05-19T22:14:04.460752Z","end":"2026-05-19T22:14:04.724075Z","steps":["trace[989104916] 'agreement among raft nodes before linearized reading' (duration: 263.238272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:04.724142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.101803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T22:14:04.724161Z","caller":"traceutil/trace.go:171","msg":"trace[1271749263] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:20; }","duration":"193.180654ms","start":"2026-05-19T22:14:04.530976Z","end":"2026-05-19T22:14:04.724156Z","steps":["trace[1271749263] 'agreement among raft nodes before linearized reading' (duration: 193.140124ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:04.72419Z","caller":"traceutil/trace.go:171","msg":"trace[295598974] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"256.51147ms","start":"2026-05-19T22:14:04.467671Z","end":"2026-05-19T22:14:04.724182Z","steps":["trace[295598974] 'process raft request' (duration: 255.805853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:04.724162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.472288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T22:14:04.72429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.13185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-19T22:14:04.724308Z","caller":"traceutil/trace.go:171","msg":"trace[732140540] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"202.17145ms","start":"2026-05-19T22:14:04.522132Z","end":"2026-05-19T22:14:04.724304Z","steps":["trace[732140540] 'agreement among raft nodes before linearized reading' (duration: 202.138709ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:04.724292Z","caller":"traceutil/trace.go:171","msg":"trace[1767552392] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:20; }","duration":"209.615219ms","start":"2026-05-19T22:14:04.514642Z","end":"2026-05-19T22:14:04.724257Z","steps":["trace[1767552392] 'agreement among raft nodes before linearized reading' (duration: 209.320626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:32.708428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.082779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:14:32.708635Z","caller":"traceutil/trace.go:171","msg":"trace[1003857938] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"438.252016ms","start":"2026-05-19T22:14:32.270369Z","end":"2026-05-19T22:14:32.708621Z","steps":["trace[1003857938] 'process raft request' (duration: 438.180865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:32.708695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:32.270353Z","time spent":"438.316466ms","remote":"127.0.0.1:45132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:14:32.708862Z","caller":"traceutil/trace.go:171","msg":"trace[835985302] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"463.587144ms","start":"2026-05-19T22:14:32.245194Z","end":"2026-05-19T22:14:32.708781Z","steps":["trace[835985302] 'process raft request' (duration: 338.798769ms)","trace[835985302] 'compare' (duration: 123.928158ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:14:32.709013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:32.245174Z","time spent":"463.793996ms","remote":"127.0.0.1:45132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:14:32.721681Z","caller":"traceutil/trace.go:171","msg":"trace[1930865037] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:555; }","duration":"299.373939ms","start":"2026-05-19T22:14:32.42228Z","end":"2026-05-19T22:14:32.721654Z","steps":["trace[1930865037] 'read index received' (duration: 161.780234ms)","trace[1930865037] 'applied index is now lower than readState.Index' (duration: 137.592555ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:14:32.721966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.664091ms","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-19T22:14:32.721996Z","caller":"traceutil/trace.go:171","msg":"trace[2022411348] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"299.755293ms","start":"2026-05-19T22:14:32.422233Z","end":"2026-05-19T22:14:32.721988Z","steps":["trace[2022411348] 'agreement among raft nodes before linearized reading' (duration: 299.516221ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:32.722046Z","caller":"traceutil/trace.go:171","msg":"trace[1757858079] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"390.845527ms","start":"2026-05-19T22:14:32.331144Z","end":"2026-05-19T22:14:32.72199Z","steps":["trace[1757858079] 'process raft request' (duration: 390.324783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:32.722269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:32.331121Z","time spent":"391.05263ms","remote":"127.0.0.1:45132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T22:14:38.909718Z","caller":"traceutil/trace.go:171","msg":"trace[315325277] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"149.904814ms","start":"2026-05-19T22:14:38.759783Z","end":"2026-05-19T22:14:38.909688Z","steps":["trace[315325277] 'read index received' (duration: 149.063787ms)","trace[315325277] 'applied index is now lower than readState.Index' (duration: 840.157µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:14:38.910756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.692831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:14:38.910859Z","caller":"traceutil/trace.go:171","msg":"trace[644561387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"138.848822ms","start":"2026-05-19T22:14:38.77199Z","end":"2026-05-19T22:14:38.910838Z","steps":["trace[644561387] 'agreement among raft nodes before linearized reading' (duration: 138.65726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:38.910983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.037533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-19T22:14:38.911247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.248244ms","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-19T22:14:38.911298Z","caller":"traceutil/trace.go:171","msg":"trace[1844735191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:613; }","duration":"109.307364ms","start":"2026-05-19T22:14:38.801967Z","end":"2026-05-19T22:14:38.911274Z","steps":["trace[1844735191] 'agreement among raft nodes before linearized reading' (duration: 109.150843ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:38.911391Z","caller":"traceutil/trace.go:171","msg":"trace[2044039619] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:613; }","duration":"151.470258ms","start":"2026-05-19T22:14:38.759732Z","end":"2026-05-19T22:14:38.911202Z","steps":["trace[2044039619] 'agreement among raft nodes before linearized reading' (duration: 150.815122ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:38.91514Z","caller":"traceutil/trace.go:171","msg":"trace[1802087945] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"175.189885ms","start":"2026-05-19T22:14:38.73991Z","end":"2026-05-19T22:14:38.9151Z","steps":["trace[1802087945] 'process raft request' (duration: 169.247766ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:39.024352Z","caller":"traceutil/trace.go:171","msg":"trace[680569253] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"104.586534ms","start":"2026-05-19T22:14:38.919741Z","end":"2026-05-19T22:14:39.024328Z","steps":["trace[680569253] 'process raft request' (duration: 104.541704ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:14:39.024591Z","caller":"traceutil/trace.go:171","msg":"trace[832957464] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"105.33625ms","start":"2026-05-19T22:14:38.919231Z","end":"2026-05-19T22:14:39.024567Z","steps":["trace[832957464] 'process raft request' (duration: 52.769291ms)","trace[832957464] 'compare' (duration: 52.165416ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:14:39.209139Z","caller":"traceutil/trace.go:171","msg":"trace[1100777462] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"163.33329ms","start":"2026-05-19T22:14:39.045782Z","end":"2026-05-19T22:14:39.209116Z","steps":["trace[1100777462] 'read index received' (duration: 163.133389ms)","trace[1100777462] 'applied index is now lower than readState.Index' (duration: 198.581µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:14:39.209216Z","caller":"traceutil/trace.go:171","msg":"trace[1927079391] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"225.847852ms","start":"2026-05-19T22:14:38.983319Z","end":"2026-05-19T22:14:39.209167Z","steps":["trace[1927079391] 'process raft request' (duration: 225.60378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:39.209652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.839505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T22:14:39.209762Z","caller":"traceutil/trace.go:171","msg":"trace[253220293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"163.960346ms","start":"2026-05-19T22:14:39.045774Z","end":"2026-05-19T22:14:39.209734Z","steps":["trace[253220293] 'agreement among raft nodes before linearized reading' (duration: 163.438351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:39.75197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.541693ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:14:39.752119Z","caller":"traceutil/trace.go:171","msg":"trace[331726370] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"527.823326ms","start":"2026-05-19T22:14:39.224269Z","end":"2026-05-19T22:14:39.752092Z","steps":["trace[331726370] 'process raft request' (duration: 215.019941ms)","trace[331726370] 'compare' (duration: 312.337941ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:14:39.752197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:39.224247Z","time spent":"527.916647ms","remote":"127.0.0.1:45258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:14:39.831321Z","caller":"traceutil/trace.go:171","msg":"trace[1316452589] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"205.090638ms","start":"2026-05-19T22:14:39.626154Z","end":"2026-05-19T22:14:39.831245Z","steps":["trace[1316452589] 'process raft request' (duration: 204.463413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:40.550275Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311466957419270,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T22:14:40.811546Z","caller":"traceutil/trace.go:171","msg":"trace[788728430] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"923.778544ms","start":"2026-05-19T22:14:39.887723Z","end":"2026-05-19T22:14:40.811502Z","steps":["trace[788728430] 'process raft request' (duration: 899.689524ms)","trace[788728430] 'compare' (duration: 23.174502ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:14:40.811176Z","caller":"traceutil/trace.go:171","msg":"trace[771307280] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"761.09037ms","start":"2026-05-19T22:14:40.050061Z","end":"2026-05-19T22:14:40.811152Z","steps":["trace[771307280] 'read index received' (duration: 737.257302ms)","trace[771307280] 'applied index is now lower than readState.Index' (duration: 23.831588ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:14:40.811747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.682276ms","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-19T22:14:40.811678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:39.887697Z","time spent":"923.918495ms","remote":"127.0.0.1:45288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T22:14:40.811832Z","caller":"traceutil/trace.go:171","msg":"trace[499425968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:620; }","duration":"761.788516ms","start":"2026-05-19T22:14:40.050003Z","end":"2026-05-19T22:14:40.811791Z","steps":["trace[499425968] 'agreement among raft nodes before linearized reading' (duration: 761.580334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:40.811869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:14:40.049983Z","time spent":"761.878377ms","remote":"127.0.0.1:45306","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-19T22:14:55.014588Z","caller":"traceutil/trace.go:171","msg":"trace[1265422851] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"116.650948ms","start":"2026-05-19T22:14:54.897913Z","end":"2026-05-19T22:14:55.014564Z","steps":["trace[1265422851] 'process raft request' (duration: 116.48954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:55.346281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.472879ms","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-19T22:14:55.34636Z","caller":"traceutil/trace.go:171","msg":"trace[1095664953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:772; }","duration":"243.585906ms","start":"2026-05-19T22:14:55.102753Z","end":"2026-05-19T22:14:55.346339Z","steps":["trace[1095664953] 'range keys from in-memory index tree' (duration: 243.309591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:14:55.346385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.785482ms","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-19T22:14:55.346463Z","caller":"traceutil/trace.go:171","msg":"trace[1784895004] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:772; }","duration":"244.90593ms","start":"2026-05-19T22:14:55.101537Z","end":"2026-05-19T22:14:55.346443Z","steps":["trace[1784895004] 'range keys from in-memory index tree' (duration: 244.606102ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:15:09.600506Z","caller":"traceutil/trace.go:171","msg":"trace[1116014595] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:976; }","duration":"177.339331ms","start":"2026-05-19T22:15:09.42315Z","end":"2026-05-19T22:15:09.600489Z","steps":["trace[1116014595] 'read index received' (duration: 127.199155ms)","trace[1116014595] 'applied index is now lower than readState.Index' (duration: 50.139186ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:15:09.600618Z","caller":"traceutil/trace.go:171","msg":"trace[1601950298] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"177.53691ms","start":"2026-05-19T22:15:09.423071Z","end":"2026-05-19T22:15:09.600608Z","steps":["trace[1601950298] 'process raft request' (duration: 177.370402ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:15:09.600636Z","caller":"traceutil/trace.go:171","msg":"trace[389589118] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"177.624314ms","start":"2026-05-19T22:15:09.422975Z","end":"2026-05-19T22:15:09.6006Z","steps":["trace[389589118] 'process raft request' (duration: 127.350032ms)","trace[389589118] 'compare' (duration: 49.959207ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:15:09.600914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.74666ms","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-19T22:15:09.600951Z","caller":"traceutil/trace.go:171","msg":"trace[117284033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:948; }","duration":"177.845195ms","start":"2026-05-19T22:15:09.423095Z","end":"2026-05-19T22:15:09.60094Z","steps":["trace[117284033] 'agreement among raft nodes before linearized reading' (duration: 177.73304ms)"],"step_count":1}