{"level":"warn","ts":"2026-03-03T00:51:43.656611Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-03T00:51:43.656717Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223: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.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-03-03T00:51:43.656784Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-03T00:51:43.656793Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-03-03T00:51:43.656811Z","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-03-03T00:51:43.657361Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-03-03T00:51:43.657429Z","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.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-03-03T00:51:43.661907Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.280934ms"} {"level":"info","ts":"2026-03-03T00:51:43.670038Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-03-03T00:51:43.670223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-03-03T00:51:43.670259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-03-03T00:51:43.670286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-03T00:51:43.6703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-03-03T00:51:43.670371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-03-03T00:51:43.676593Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-03T00:51:43.678798Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-03T00:51:43.681949Z","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-03-03T00:51:43.686002Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-03T00:51:43.689046Z","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-03-03T00:51:43.689029Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-03T00:51:43.689147Z","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-03-03T00:51:43.690707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-03-03T00:51:43.691196Z","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-03-03T00:51:43.691577Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-03-03T00:51:43.697025Z","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-03-03T00:51:43.697159Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-03-03T00:51:43.697195Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-03-03T00:51:43.697518Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-03T00:51:43.697639Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-03T00:51:44.670861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-03-03T00:51:44.670948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-03T00:51:44.670993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-03-03T00:51:44.671017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-03-03T00:51:44.671028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-03-03T00:51:44.671046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-03-03T00:51:44.671062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-03-03T00:51:44.673639Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T00:51:44.675434Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-03-03T00:51:44.675432Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T00:51:44.675614Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T00:51:44.675803Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-03T00:51:44.67589Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-03T00:51:44.678867Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-03-03T00:51:44.675356Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T00:51:44.679099Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T00:51:44.679147Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T00:51:44.679886Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-03T00:51:46.525055Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224556537689720,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T00:51:46.600147Z","caller":"traceutil/trace.go:171","msg":"trace[869033486] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"579.205805ms","start":"2026-03-03T00:51:46.020906Z","end":"2026-03-03T00:51:46.600112Z","steps":["trace[869033486] 'process raft request' (duration: 578.868525ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:51:46.601251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:51:46.020889Z","time spent":"579.446423ms","remote":"127.0.0.1:52778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T00:51:46.62438Z","caller":"traceutil/trace.go:171","msg":"trace[1512181363] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"526.460073ms","start":"2026-03-03T00:51:46.09784Z","end":"2026-03-03T00:51:46.6243Z","steps":["trace[1512181363] 'process raft request' (duration: 526.242036ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:51:46.624469Z","caller":"traceutil/trace.go:171","msg":"trace[1290618279] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:4; }","duration":"599.723209ms","start":"2026-03-03T00:51:46.024715Z","end":"2026-03-03T00:51:46.624438Z","steps":["trace[1290618279] 'read index received' (duration: 575.015484ms)","trace[1290618279] 'applied index is now lower than readState.Index' (duration: 24.706415ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:51:46.624467Z","caller":"traceutil/trace.go:171","msg":"trace[667849826] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"526.349249ms","start":"2026-03-03T00:51:46.098057Z","end":"2026-03-03T00:51:46.624406Z","steps":["trace[667849826] 'process raft request' (duration: 526.161743ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:51:46.624565Z","caller":"traceutil/trace.go:171","msg":"trace[1495358652] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"526.329908ms","start":"2026-03-03T00:51:46.098206Z","end":"2026-03-03T00:51:46.624536Z","steps":["trace[1495358652] 'process raft request' (duration: 526.06942ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:51:46.6246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:51:46.097821Z","time spent":"526.669259ms","remote":"127.0.0.1:53182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T00:51:46.624575Z","caller":"traceutil/trace.go:171","msg":"trace[385575577] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"525.881134ms","start":"2026-03-03T00:51:46.09865Z","end":"2026-03-03T00:51:46.624531Z","steps":["trace[385575577] 'process raft request' (duration: 525.687838ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:51:46.624678Z","caller":"traceutil/trace.go:171","msg":"trace[336633637] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"526.36893ms","start":"2026-03-03T00:51:46.098286Z","end":"2026-03-03T00:51:46.624655Z","steps":["trace[336633637] 'process raft request' (duration: 526.012279ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:51:46.62471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:51:46.098598Z","time spent":"526.05833ms","remote":"127.0.0.1:53266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T00:51:46.624715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:51:46.098195Z","time spent":"526.435032ms","remote":"127.0.0.1:53266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T00:51:46.624664Z","caller":"traceutil/trace.go:171","msg":"trace[1389997356] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"526.413691ms","start":"2026-03-03T00:51:46.098158Z","end":"2026-03-03T00:51:46.624572Z","steps":["trace[1389997356] 'process raft request' (duration: 526.089781ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:51:46.624771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:51:46.098278Z","time spent":"526.444372ms","remote":"127.0.0.1:53266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T00:51:46.624714Z","caller":"traceutil/trace.go:171","msg":"trace[42297220] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"516.067497ms","start":"2026-03-03T00:51:46.108629Z","end":"2026-03-03T00:51:46.624697Z","steps":["trace[42297220] 'process raft request' (duration: 515.734977ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:51:46.624834Z","caller":"traceutil/trace.go:171","msg":"trace[752513643] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"516.105578ms","start":"2026-03-03T00:51:46.108717Z","end":"2026-03-03T00:51:46.624823Z","steps":["trace[752513643] 'process raft request' (duration: 515.682225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:51:46.624856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:51:46.108619Z","time spent":"516.215462ms","remote":"127.0.0.1:52980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T00:51:46.624886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:51:46.098145Z","time spent":"526.650988ms","remote":"127.0.0.1:53266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T00:51:46.625017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:51:46.098013Z","time spent":"526.796063ms","remote":"127.0.0.1:53266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T00:51:46.624996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.267996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T00:51:46.625045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.431442ms","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":"info","ts":"2026-03-03T00:51:46.625089Z","caller":"traceutil/trace.go:171","msg":"trace[527864803] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:10; }","duration":"600.3993ms","start":"2026-03-03T00:51:46.024675Z","end":"2026-03-03T00:51:46.625074Z","steps":["trace[527864803] 'agreement among raft nodes before linearized reading' (duration: 600.208804ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:51:46.625096Z","caller":"traceutil/trace.go:171","msg":"trace[1390413273] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"526.481253ms","start":"2026-03-03T00:51:46.098602Z","end":"2026-03-03T00:51:46.625084Z","steps":["trace[1390413273] 'agreement among raft nodes before linearized reading' (duration: 526.332909ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:51:46.625104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.080577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T00:51:46.625106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.780036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T00:51:46.625124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:51:46.024661Z","time spent":"600.456122ms","remote":"127.0.0.1:52788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":26,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-03-03T00:51:46.625137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:51:46.098571Z","time spent":"526.553526ms","remote":"127.0.0.1:52810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-03-03T00:51:46.625147Z","caller":"traceutil/trace.go:171","msg":"trace[660117410] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"454.877479ms","start":"2026-03-03T00:51:46.170256Z","end":"2026-03-03T00:51:46.625133Z","steps":["trace[660117410] 'agreement among raft nodes before linearized reading' (duration: 454.816747ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:51:46.625143Z","caller":"traceutil/trace.go:171","msg":"trace[1587425404] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"553.134089ms","start":"2026-03-03T00:51:46.071992Z","end":"2026-03-03T00:51:46.625126Z","steps":["trace[1587425404] 'agreement among raft nodes before linearized reading' (duration: 553.087047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:51:46.625178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:51:46.170237Z","time spent":"454.93507ms","remote":"127.0.0.1:52826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-03T00:51:46.625182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:51:46.071975Z","time spent":"553.20207ms","remote":"127.0.0.1:52826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-03T00:51:46.625196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.865532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T00:51:46.624996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.039744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T00:51:46.625246Z","caller":"traceutil/trace.go:171","msg":"trace[2042096966] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"523.930353ms","start":"2026-03-03T00:51:46.101302Z","end":"2026-03-03T00:51:46.625232Z","steps":["trace[2042096966] 'agreement among raft nodes before linearized reading' (duration: 523.83997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:51:46.625272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.615823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T00:51:46.624898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:51:46.108709Z","time spent":"516.155309ms","remote":"127.0.0.1:52886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T00:51:46.625279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:51:46.101291Z","time spent":"523.981435ms","remote":"127.0.0.1:52826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-03T00:51:46.625301Z","caller":"traceutil/trace.go:171","msg":"trace[1104593885] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:0; response_revision:10; }","duration":"530.667705ms","start":"2026-03-03T00:51:46.094625Z","end":"2026-03-03T00:51:46.625293Z","steps":["trace[1104593885] 'agreement among raft nodes before linearized reading' (duration: 530.626434ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:51:46.625327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:51:46.09461Z","time spent":"530.711506ms","remote":"127.0.0.1:52748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.204.45.223\" "} {"level":"info","ts":"2026-03-03T00:51:46.625276Z","caller":"traceutil/trace.go:171","msg":"trace[1852513580] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"478.316003ms","start":"2026-03-03T00:51:46.146943Z","end":"2026-03-03T00:51:46.625259Z","steps":["trace[1852513580] 'agreement among raft nodes before linearized reading' (duration: 478.002943ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:51:46.625469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:51:46.146905Z","time spent":"478.552651ms","remote":"127.0.0.1:52826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-03T00:51:46.625023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.778153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T00:51:46.625511Z","caller":"traceutil/trace.go:171","msg":"trace[2062132276] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"594.292358ms","start":"2026-03-03T00:51:46.031207Z","end":"2026-03-03T00:51:46.6255Z","steps":["trace[2062132276] 'agreement among raft nodes before linearized reading' (duration: 593.687119ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:51:46.625549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:51:46.031195Z","time spent":"594.342211ms","remote":"127.0.0.1:53118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-03-03T00:51:46.625688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.515588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T00:51:46.625738Z","caller":"traceutil/trace.go:171","msg":"trace[487281122] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"551.586721ms","start":"2026-03-03T00:51:46.074141Z","end":"2026-03-03T00:51:46.625727Z","steps":["trace[487281122] 'agreement among raft nodes before linearized reading' (duration: 550.93562ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:51:46.625768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:51:46.074127Z","time spent":"551.634262ms","remote":"127.0.0.1:52826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-03T00:52:14.831018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.282982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-03-03T00:52:14.831131Z","caller":"traceutil/trace.go:171","msg":"trace[374316128] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:469; }","duration":"214.500749ms","start":"2026-03-03T00:52:14.616602Z","end":"2026-03-03T00:52:14.831103Z","steps":["trace[374316128] 'range keys from in-memory index tree' (duration: 214.066865ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:52:20.758304Z","caller":"traceutil/trace.go:171","msg":"trace[1268041154] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"216.583375ms","start":"2026-03-03T00:52:20.541683Z","end":"2026-03-03T00:52:20.758266Z","steps":["trace[1268041154] 'process raft request' (duration: 216.408439ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:52:20.770261Z","caller":"traceutil/trace.go:171","msg":"trace[104173491] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:496; }","duration":"203.466878ms","start":"2026-03-03T00:52:20.56676Z","end":"2026-03-03T00:52:20.770227Z","steps":["trace[104173491] 'read index received' (duration: 191.412613ms)","trace[104173491] 'applied index is now lower than readState.Index' (duration: 12.053205ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:52:20.770313Z","caller":"traceutil/trace.go:171","msg":"trace[2102417399] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"225.312184ms","start":"2026-03-03T00:52:20.544979Z","end":"2026-03-03T00:52:20.770291Z","steps":["trace[2102417399] 'process raft request' (duration: 225.130128ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:52:20.770476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.710045ms","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-03-03T00:52:20.77051Z","caller":"traceutil/trace.go:171","msg":"trace[1876017063] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:484; }","duration":"203.822808ms","start":"2026-03-03T00:52:20.566676Z","end":"2026-03-03T00:52:20.770499Z","steps":["trace[1876017063] 'agreement among raft nodes before linearized reading' (duration: 203.670953ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:52:22.575453Z","caller":"traceutil/trace.go:171","msg":"trace[1304929049] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:504; }","duration":"347.269132ms","start":"2026-03-03T00:52:22.228151Z","end":"2026-03-03T00:52:22.575421Z","steps":["trace[1304929049] 'read index received' (duration: 346.972783ms)","trace[1304929049] 'applied index is now lower than readState.Index' (duration: 294.799µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:52:22.575614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.432617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:52:22.575657Z","caller":"traceutil/trace.go:171","msg":"trace[323381128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:491; }","duration":"347.50079ms","start":"2026-03-03T00:52:22.228143Z","end":"2026-03-03T00:52:22.575644Z","steps":["trace[323381128] 'agreement among raft nodes before linearized reading' (duration: 347.406987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:52:22.575734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:52:22.228077Z","time spent":"347.648164ms","remote":"127.0.0.1:52708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T00:52:22.575744Z","caller":"traceutil/trace.go:171","msg":"trace[251825122] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"494.121374ms","start":"2026-03-03T00:52:22.081517Z","end":"2026-03-03T00:52:22.575639Z","steps":["trace[251825122] 'process raft request' (duration: 493.719642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:52:22.575999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:52:22.081499Z","time spent":"494.398343ms","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T00:52:23.139379Z","caller":"traceutil/trace.go:171","msg":"trace[1265274267] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"359.152041ms","start":"2026-03-03T00:52:22.78019Z","end":"2026-03-03T00:52:23.139342Z","steps":["trace[1265274267] 'process raft request' (duration: 358.861892ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:52:23.139601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:52:22.780171Z","time spent":"359.308045ms","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T00:52:23.141775Z","caller":"traceutil/trace.go:171","msg":"trace[158662472] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"358.270493ms","start":"2026-03-03T00:52:22.783479Z","end":"2026-03-03T00:52:23.14175Z","steps":["trace[158662472] 'process raft request' (duration: 357.87103ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:52:23.141868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:52:22.783456Z","time spent":"358.360976ms","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:52:23.14204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.825339ms","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-03-03T00:52:23.142021Z","caller":"traceutil/trace.go:171","msg":"trace[968602252] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:505; }","duration":"203.392265ms","start":"2026-03-03T00:52:22.938181Z","end":"2026-03-03T00:52:23.141573Z","steps":["trace[968602252] 'read index received' (duration: 200.925626ms)","trace[968602252] 'applied index is now lower than readState.Index' (duration: 2.465189ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:52:23.142216Z","caller":"traceutil/trace.go:171","msg":"trace[238529681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:493; }","duration":"203.948772ms","start":"2026-03-03T00:52:22.938166Z","end":"2026-03-03T00:52:23.142115Z","steps":["trace[238529681] 'agreement among raft nodes before linearized reading' (duration: 203.637653ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:52:43.192672Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224556537691059,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T00:52:43.349269Z","caller":"traceutil/trace.go:171","msg":"trace[1986848540] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"674.120332ms","start":"2026-03-03T00:52:42.675115Z","end":"2026-03-03T00:52:43.349236Z","steps":["trace[1986848540] 'process raft request' (duration: 673.931046ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:52:43.349457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:52:42.675087Z","time spent":"674.277947ms","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:52:43.754783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.108358ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-03T00:52:43.754942Z","caller":"traceutil/trace.go:171","msg":"trace[1159194645] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:696; }","duration":"1.062877357s","start":"2026-03-03T00:52:42.692036Z","end":"2026-03-03T00:52:43.754913Z","steps":["trace[1159194645] 'read index received' (duration: 657.006246ms)","trace[1159194645] 'applied index is now lower than readState.Index' (duration: 405.869141ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:52:43.755318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063258819s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-03T00:52:43.755359Z","caller":"traceutil/trace.go:171","msg":"trace[1171636787] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:678; }","duration":"1.063341502s","start":"2026-03-03T00:52:42.692004Z","end":"2026-03-03T00:52:43.755346Z","steps":["trace[1171636787] 'agreement among raft nodes before linearized reading' (duration: 1.063051233s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:52:43.755408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:52:42.691992Z","time spent":"1.063404683s","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-03T00:52:43.784884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.939079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:52:43.784988Z","caller":"traceutil/trace.go:171","msg":"trace[669592422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"558.094173ms","start":"2026-03-03T00:52:43.226872Z","end":"2026-03-03T00:52:43.784966Z","steps":["trace[669592422] 'agreement among raft nodes before linearized reading' (duration: 557.903027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:52:43.785064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:52:43.226851Z","time spent":"558.202757ms","remote":"127.0.0.1:52724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T00:52:43.78517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.399108ms","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-03-03T00:52:43.785292Z","caller":"traceutil/trace.go:171","msg":"trace[1020053423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:679; }","duration":"518.702088ms","start":"2026-03-03T00:52:43.266548Z","end":"2026-03-03T00:52:43.78525Z","steps":["trace[1020053423] 'agreement among raft nodes before linearized reading' (duration: 518.242073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:52:43.785398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:52:43.266539Z","time spent":"518.847842ms","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-03T00:52:43.785565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.380212ms","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-03-03T00:52:43.785625Z","caller":"traceutil/trace.go:171","msg":"trace[175898720] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:679; }","duration":"520.470414ms","start":"2026-03-03T00:52:43.265143Z","end":"2026-03-03T00:52:43.785614Z","steps":["trace[175898720] 'agreement among raft nodes before linearized reading' (duration: 520.276407ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:52:43.785663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:52:43.265129Z","time spent":"520.524926ms","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-03T00:52:43.785743Z","caller":"traceutil/trace.go:171","msg":"trace[1703475685] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"378.761066ms","start":"2026-03-03T00:52:43.40695Z","end":"2026-03-03T00:52:43.785711Z","steps":["trace[1703475685] 'process raft request' (duration: 377.571868ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:52:43.785845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.024531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-03T00:52:43.785889Z","caller":"traceutil/trace.go:171","msg":"trace[633326095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:679; }","duration":"531.129074ms","start":"2026-03-03T00:52:43.254749Z","end":"2026-03-03T00:52:43.785878Z","steps":["trace[633326095] 'agreement among raft nodes before linearized reading' (duration: 531.022431ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:52:43.785907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:52:43.406921Z","time spent":"378.89982ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":813,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T00:52:43.785924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:52:43.254719Z","time spent":"531.197206ms","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-03T00:52:44.107072Z","caller":"traceutil/trace.go:171","msg":"trace[1164601487] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:699; }","duration":"320.255711ms","start":"2026-03-03T00:52:43.786771Z","end":"2026-03-03T00:52:44.107027Z","steps":["trace[1164601487] 'read index received' (duration: 301.499023ms)","trace[1164601487] 'applied index is now lower than readState.Index' (duration: 18.754788ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:52:44.107243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.434717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:52:44.107287Z","caller":"traceutil/trace.go:171","msg":"trace[1413245445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"320.525239ms","start":"2026-03-03T00:52:43.786744Z","end":"2026-03-03T00:52:44.10727Z","steps":["trace[1413245445] 'agreement among raft nodes before linearized reading' (duration: 320.410925ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:52:44.10733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:52:43.786724Z","time spent":"320.594171ms","remote":"127.0.0.1:52708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T00:52:44.107125Z","caller":"traceutil/trace.go:171","msg":"trace[1232771267] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"345.783204ms","start":"2026-03-03T00:52:43.76131Z","end":"2026-03-03T00:52:44.107093Z","steps":["trace[1232771267] 'process raft request' (duration: 326.960284ms)","trace[1232771267] 'compare' (duration: 18.404507ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:52:44.107495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:52:43.761269Z","time spent":"346.121875ms","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T00:52:44.123302Z","caller":"traceutil/trace.go:171","msg":"trace[1941903323] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"331.996575ms","start":"2026-03-03T00:52:43.791283Z","end":"2026-03-03T00:52:44.12328Z","steps":["trace[1941903323] 'process raft request' (duration: 331.83774ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:52:44.123469Z","caller":"traceutil/trace.go:171","msg":"trace[571449942] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"332.086008ms","start":"2026-03-03T00:52:43.791369Z","end":"2026-03-03T00:52:44.123455Z","steps":["trace[571449942] 'process raft request' (duration: 331.876131ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:52:44.123527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:52:43.791361Z","time spent":"332.132339ms","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T00:52:44.123561Z","caller":"traceutil/trace.go:171","msg":"trace[427772629] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"332.15339ms","start":"2026-03-03T00:52:43.791377Z","end":"2026-03-03T00:52:44.123531Z","steps":["trace[427772629] 'process raft request' (duration: 332.036997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:52:44.123755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:52:43.791351Z","time spent":"332.307334ms","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:52:44.123829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:52:43.791245Z","time spent":"332.118099ms","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T00:53:10.941695Z","caller":"traceutil/trace.go:171","msg":"trace[1317490236] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"626.583252ms","start":"2026-03-03T00:53:10.315085Z","end":"2026-03-03T00:53:10.941668Z","steps":["trace[1317490236] 'process raft request' (duration: 626.398986ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:53:10.942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:53:10.315053Z","time spent":"626.756048ms","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:53:11.155221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.197993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T00:53:11.155568Z","caller":"traceutil/trace.go:171","msg":"trace[1817883399] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"837.444242ms","start":"2026-03-03T00:53:10.318085Z","end":"2026-03-03T00:53:11.155529Z","steps":["trace[1817883399] 'process raft request' (duration: 698.847036ms)","trace[1817883399] 'compare' (duration: 137.944114ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:53:11.155623Z","caller":"traceutil/trace.go:171","msg":"trace[26637808] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"836.026374ms","start":"2026-03-03T00:53:10.319574Z","end":"2026-03-03T00:53:11.155601Z","steps":["trace[26637808] 'process raft request' (duration: 835.976582ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:53:11.155816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:53:10.319555Z","time spent":"836.219531ms","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T00:53:11.155818Z","caller":"traceutil/trace.go:171","msg":"trace[2042711970] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"836.191669ms","start":"2026-03-03T00:53:10.319543Z","end":"2026-03-03T00:53:11.155734Z","steps":["trace[2042711970] 'process raft request' (duration: 835.873178ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:53:11.156409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:53:10.319528Z","time spent":"836.457139ms","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:53:11.156429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:53:10.318069Z","time spent":"837.580247ms","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:53:13.008245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.819769ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-03T00:53:13.152566Z","caller":"traceutil/trace.go:171","msg":"trace[1918817110] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"138.379519ms","start":"2026-03-03T00:53:13.014157Z","end":"2026-03-03T00:53:13.152536Z","steps":["trace[1918817110] 'process raft request' (duration: 138.170142ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:53:13.353048Z","caller":"traceutil/trace.go:171","msg":"trace[358923562] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"187.631157ms","start":"2026-03-03T00:53:13.165387Z","end":"2026-03-03T00:53:13.353018Z","steps":["trace[358923562] 'process raft request' (duration: 187.550814ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:53:13.353377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.362634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:53:13.353417Z","caller":"traceutil/trace.go:171","msg":"trace[553139054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:768; }","duration":"125.451656ms","start":"2026-03-03T00:53:13.227958Z","end":"2026-03-03T00:53:13.35341Z","steps":["trace[553139054] 'agreement among raft nodes before linearized reading' (duration: 125.356863ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:53:13.353076Z","caller":"traceutil/trace.go:171","msg":"trace[429242420] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"187.856275ms","start":"2026-03-03T00:53:13.165188Z","end":"2026-03-03T00:53:13.353045Z","steps":["trace[429242420] 'process raft request' (duration: 125.712586ms)","trace[429242420] 'compare' (duration: 61.87753ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:53:13.353296Z","caller":"traceutil/trace.go:171","msg":"trace[63850593] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:792; }","duration":"125.278871ms","start":"2026-03-03T00:53:13.228006Z","end":"2026-03-03T00:53:13.353285Z","steps":["trace[63850593] 'read index received' (duration: 62.877664ms)","trace[63850593] 'applied index is now lower than readState.Index' (duration: 62.399547ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:53:13.353301Z","caller":"traceutil/trace.go:171","msg":"trace[981083772] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"187.832223ms","start":"2026-03-03T00:53:13.165456Z","end":"2026-03-03T00:53:13.353288Z","steps":["trace[981083772] 'process raft request' (duration: 187.530083ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:53:47.380393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.361497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:53:47.380532Z","caller":"traceutil/trace.go:171","msg":"trace[1701058120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:930; }","duration":"152.579445ms","start":"2026-03-03T00:53:47.227927Z","end":"2026-03-03T00:53:47.380507Z","steps":["trace[1701058120] 'range keys from in-memory index tree' (duration: 152.201112ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:53:47.672455Z","caller":"traceutil/trace.go:171","msg":"trace[554033225] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"264.411366ms","start":"2026-03-03T00:53:47.408021Z","end":"2026-03-03T00:53:47.672433Z","steps":["trace[554033225] 'process raft request' (duration: 264.23603ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:53:50.338321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.12258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T00:53:50.338413Z","caller":"traceutil/trace.go:171","msg":"trace[1287168425] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:948; }","duration":"249.287076ms","start":"2026-03-03T00:53:50.089102Z","end":"2026-03-03T00:53:50.338389Z","steps":["trace[1287168425] 'count revisions from in-memory index tree' (duration: 249.016687ms)"],"step_count":1}