{"level":"warn","ts":"2026-05-01T22:20:51.292551Z","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-01T22:20:51.292639Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157: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.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157: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-01T22:20:51.292712Z","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-01T22:20:51.292724Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-05-01T22:20:51.292742Z","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-01T22:20:51.293394Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-05-01T22:20:51.293469Z","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.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157: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-01T22:20:51.311896Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"18.253462ms"} {"level":"info","ts":"2026-05-01T22:20:51.335488Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-05-01T22:20:51.335607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-05-01T22:20:51.335642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-05-01T22:20:51.335653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-01T22:20:51.335662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-05-01T22:20:51.335697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-05-01T22:20:51.411344Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-01T22:20:51.427375Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-01T22:20:51.442207Z","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-01T22:20:51.445115Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-01T22:20:51.448466Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-01T22:20:51.449188Z","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-01T22:20:51.449254Z","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-01T22:20:51.449319Z","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-01T22:20:51.457906Z","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-01T22:20:51.458041Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-05-01T22:20:51.458119Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-01T22:20:51.458148Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-01T22:20:51.458086Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-05-01T22:20:51.462112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-05-01T22:20:51.462261Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-05-01T22:20:51.535863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-05-01T22:20:51.535899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-05-01T22:20:51.535932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-05-01T22:20:51.535985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-05-01T22:20:51.536003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-05-01T22:20:51.536148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-05-01T22:20:51.536168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-05-01T22:20:51.537234Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-01T22:20:51.537414Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-01T22:20:51.53742Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-01T22:20:51.53749Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-01T22:20:51.537525Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-01T22:20:51.537598Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T22:20:51.539229Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-01T22:20:51.541533Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-05-01T22:20:51.557338Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T22:20:51.557489Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T22:20:51.557532Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T22:20:53.79487Z","caller":"traceutil/trace.go:171","msg":"trace[2091394925] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"445.687863ms","start":"2026-05-01T22:20:53.349162Z","end":"2026-05-01T22:20:53.79485Z","steps":["trace[2091394925] 'process raft request' (duration: 445.666073ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:20:53.795039Z","caller":"traceutil/trace.go:171","msg":"trace[704629988] linearizableReadLoop","detail":"{readStateIndex:44; appliedIndex:43; }","duration":"447.537654ms","start":"2026-05-01T22:20:53.347474Z","end":"2026-05-01T22:20:53.795011Z","steps":["trace[704629988] 'read index received' (duration: 437.253661ms)","trace[704629988] 'applied index is now lower than readState.Index' (duration: 10.281403ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:20:53.795288Z","caller":"traceutil/trace.go:171","msg":"trace[1574835590] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"448.548485ms","start":"2026-05-01T22:20:53.346688Z","end":"2026-05-01T22:20:53.795236Z","steps":["trace[1574835590] 'process raft request' (duration: 438.04485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:20:53.795327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.828827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-01T22:20:53.795359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:20:53.349144Z","time spent":"445.798584ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T22:20:53.795396Z","caller":"traceutil/trace.go:171","msg":"trace[166528780] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:42; }","duration":"447.985359ms","start":"2026-05-01T22:20:53.347398Z","end":"2026-05-01T22:20:53.795383Z","steps":["trace[166528780] 'agreement among raft nodes before linearized reading' (duration: 447.751697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:20:53.795434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:20:53.347389Z","time spent":"448.03617ms","remote":"127.0.0.1:39456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" "} {"level":"info","ts":"2026-05-01T22:20:53.795957Z","caller":"traceutil/trace.go:171","msg":"trace[702103553] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"446.416821ms","start":"2026-05-01T22:20:53.349089Z","end":"2026-05-01T22:20:53.795506Z","steps":["trace[702103553] 'process raft request' (duration: 445.679533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:20:53.796311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:20:53.349081Z","time spent":"447.16001ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T22:20:53.796762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:20:53.34667Z","time spent":"448.735107ms","remote":"127.0.0.1:39818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":715,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T22:20:53.79965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.275945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-01T22:20:53.799715Z","caller":"traceutil/trace.go:171","msg":"trace[71545787] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:42; }","duration":"401.365056ms","start":"2026-05-01T22:20:53.398319Z","end":"2026-05-01T22:20:53.799684Z","steps":["trace[71545787] 'agreement among raft nodes before linearized reading' (duration: 397.163409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:20:53.799891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:20:53.398306Z","time spent":"401.541738ms","remote":"127.0.0.1:39484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-05-01T22:20:55.30096Z","caller":"traceutil/trace.go:171","msg":"trace[653372110] transaction","detail":"{read_only:false; response_revision:185; number_of_response:1; }","duration":"136.157754ms","start":"2026-05-01T22:20:55.164765Z","end":"2026-05-01T22:20:55.300922Z","steps":["trace[653372110] 'process raft request' (duration: 50.498396ms)","trace[653372110] 'compare' (duration: 85.429645ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:20:55.871105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.618789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-01T22:20:55.871504Z","caller":"traceutil/trace.go:171","msg":"trace[1191570820] transaction","detail":"{read_only:false; response_revision:193; number_of_response:1; }","duration":"348.821644ms","start":"2026-05-01T22:20:55.522638Z","end":"2026-05-01T22:20:55.87146Z","steps":["trace[1191570820] 'process raft request' (duration: 146.640337ms)","trace[1191570820] 'compare' (duration: 201.443226ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:20:55.871688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:20:55.522628Z","time spent":"348.975466ms","remote":"127.0.0.1:39704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T22:20:56.320372Z","caller":"traceutil/trace.go:171","msg":"trace[342314406] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"106.839539ms","start":"2026-05-01T22:20:56.213496Z","end":"2026-05-01T22:20:56.320335Z","steps":["trace[342314406] 'process raft request' (duration: 68.896721ms)","trace[342314406] 'compare' (duration: 37.684653ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:20:56.499925Z","caller":"traceutil/trace.go:171","msg":"trace[1468547742] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"112.420171ms","start":"2026-05-01T22:20:56.38748Z","end":"2026-05-01T22:20:56.499901Z","steps":["trace[1468547742] 'process raft request' (duration: 57.68903ms)","trace[1468547742] 'compare' (duration: 54.627119ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:20:56.823716Z","caller":"traceutil/trace.go:171","msg":"trace[1686755314] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"109.052035ms","start":"2026-05-01T22:20:56.714628Z","end":"2026-05-01T22:20:56.82368Z","steps":["trace[1686755314] 'process raft request' (duration: 46.568098ms)","trace[1686755314] 'compare' (duration: 62.345125ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:20:58.054109Z","caller":"traceutil/trace.go:171","msg":"trace[26929815] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"124.112434ms","start":"2026-05-01T22:20:57.929962Z","end":"2026-05-01T22:20:58.054075Z","steps":["trace[26929815] 'process raft request' (duration: 85.027421ms)","trace[26929815] 'compare' (duration: 38.8946ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:20:58.054283Z","caller":"traceutil/trace.go:171","msg":"trace[1700165994] linearizableReadLoop","detail":"{readStateIndex:278; appliedIndex:277; }","duration":"102.136441ms","start":"2026-05-01T22:20:57.952114Z","end":"2026-05-01T22:20:58.05425Z","steps":["trace[1700165994] 'read index received' (duration: 62.884904ms)","trace[1700165994] 'applied index is now lower than readState.Index' (duration: 39.248697ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:20:58.054401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.900228ms","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-01T22:20:58.054536Z","caller":"traceutil/trace.go:171","msg":"trace[1747942760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:273; }","duration":"111.058871ms","start":"2026-05-01T22:20:57.943456Z","end":"2026-05-01T22:20:58.054515Z","steps":["trace[1747942760] 'agreement among raft nodes before linearized reading' (duration: 110.834227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:20:58.054545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.178763ms","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-01T22:20:58.05462Z","caller":"traceutil/trace.go:171","msg":"trace[1130226746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:273; }","duration":"111.287416ms","start":"2026-05-01T22:20:57.943314Z","end":"2026-05-01T22:20:58.054601Z","steps":["trace[1130226746] 'agreement among raft nodes before linearized reading' (duration: 111.104122ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:20:58.535832Z","caller":"traceutil/trace.go:171","msg":"trace[765273464] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"104.403858ms","start":"2026-05-01T22:20:58.4314Z","end":"2026-05-01T22:20:58.535804Z","steps":["trace[765273464] 'process raft request' (duration: 71.874253ms)","trace[765273464] 'compare' (duration: 32.387062ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:20:58.895648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.921437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-01T22:20:58.895748Z","caller":"traceutil/trace.go:171","msg":"trace[16596052] linearizableReadLoop","detail":"{readStateIndex:292; appliedIndex:291; }","duration":"226.898741ms","start":"2026-05-01T22:20:58.668833Z","end":"2026-05-01T22:20:58.895732Z","steps":["trace[16596052] 'read index received' (duration: 32.421µs)","trace[16596052] 'applied index is now lower than readState.Index' (duration: 226.86539ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:20:58.895901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.003683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T22:20:58.895939Z","caller":"traceutil/trace.go:171","msg":"trace[1113865916] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:0; response_revision:286; }","duration":"227.131256ms","start":"2026-05-01T22:20:58.668797Z","end":"2026-05-01T22:20:58.895928Z","steps":["trace[1113865916] 'agreement among raft nodes before linearized reading' (duration: 226.979353ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:20:58.895896Z","caller":"traceutil/trace.go:171","msg":"trace[178280020] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"263.547997ms","start":"2026-05-01T22:20:58.632281Z","end":"2026-05-01T22:20:58.895829Z","steps":["trace[178280020] 'compare' (duration: 256.792105ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:20:59.131615Z","caller":"traceutil/trace.go:171","msg":"trace[2039004575] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"230.282303ms","start":"2026-05-01T22:20:58.901269Z","end":"2026-05-01T22:20:59.131551Z","steps":["trace[2039004575] 'process raft request' (duration: 230.124759ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:20:59.132126Z","caller":"traceutil/trace.go:171","msg":"trace[1925221653] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"229.514098ms","start":"2026-05-01T22:20:58.902598Z","end":"2026-05-01T22:20:59.132112Z","steps":["trace[1925221653] 'process raft request' (duration: 229.439177ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:21:05.646501Z","caller":"traceutil/trace.go:171","msg":"trace[1210594077] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"102.905566ms","start":"2026-05-01T22:21:05.543576Z","end":"2026-05-01T22:21:05.646481Z","steps":["trace[1210594077] 'process raft request' (duration: 102.760152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:21:22.76128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.468424ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-01T22:21:23.434064Z","caller":"traceutil/trace.go:171","msg":"trace[178781616] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"292.631748ms","start":"2026-05-01T22:21:23.141404Z","end":"2026-05-01T22:21:23.434036Z","steps":["trace[178781616] 'process raft request' (duration: 292.364266ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:21:23.449303Z","caller":"traceutil/trace.go:171","msg":"trace[1410171700] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"304.299892ms","start":"2026-05-01T22:21:23.144974Z","end":"2026-05-01T22:21:23.449274Z","steps":["trace[1410171700] 'process raft request' (duration: 304.107433ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:21:23.449554Z","caller":"traceutil/trace.go:171","msg":"trace[1606122366] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"293.459567ms","start":"2026-05-01T22:21:23.156076Z","end":"2026-05-01T22:21:23.449536Z","steps":["trace[1606122366] 'process raft request' (duration: 293.171003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:21:23.449433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:21:23.144959Z","time spent":"304.417338ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T22:21:23.449613Z","caller":"traceutil/trace.go:171","msg":"trace[179415498] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"292.860959ms","start":"2026-05-01T22:21:23.156714Z","end":"2026-05-01T22:21:23.449575Z","steps":["trace[179415498] 'process raft request' (duration: 292.604027ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:21:23.449739Z","caller":"traceutil/trace.go:171","msg":"trace[716011757] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"292.293573ms","start":"2026-05-01T22:21:23.157437Z","end":"2026-05-01T22:21:23.449731Z","steps":["trace[716011757] 'process raft request' (duration: 291.920545ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:21:23.449795Z","caller":"traceutil/trace.go:171","msg":"trace[334053321] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"284.921208ms","start":"2026-05-01T22:21:23.164865Z","end":"2026-05-01T22:21:23.449786Z","steps":["trace[334053321] 'process raft request' (duration: 284.677767ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:21:23.449645Z","caller":"traceutil/trace.go:171","msg":"trace[2141594159] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"304.569605ms","start":"2026-05-01T22:21:23.145026Z","end":"2026-05-01T22:21:23.449595Z","steps":["trace[2141594159] 'process raft request' (duration: 304.175247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:21:23.44987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:21:23.145021Z","time spent":"304.815517ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T22:21:23.449916Z","caller":"traceutil/trace.go:171","msg":"trace[2337549] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"284.94722ms","start":"2026-05-01T22:21:23.164961Z","end":"2026-05-01T22:21:23.449908Z","steps":["trace[2337549] 'process raft request' (duration: 284.620905ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:21:23.449934Z","caller":"traceutil/trace.go:171","msg":"trace[994418818] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"276.762518ms","start":"2026-05-01T22:21:23.173164Z","end":"2026-05-01T22:21:23.449926Z","steps":["trace[994418818] 'process raft request' (duration: 276.515926ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:21:23.450058Z","caller":"traceutil/trace.go:171","msg":"trace[1376455469] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"274.534734ms","start":"2026-05-01T22:21:23.175499Z","end":"2026-05-01T22:21:23.450034Z","steps":["trace[1376455469] 'process raft request' (duration: 274.313384ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:21:23.450186Z","caller":"traceutil/trace.go:171","msg":"trace[1089932555] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"265.774674ms","start":"2026-05-01T22:21:23.184352Z","end":"2026-05-01T22:21:23.450127Z","steps":["trace[1089932555] 'process raft request' (duration: 265.621827ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:21:23.450228Z","caller":"traceutil/trace.go:171","msg":"trace[890988970] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"156.565697ms","start":"2026-05-01T22:21:23.293644Z","end":"2026-05-01T22:21:23.45021Z","steps":["trace[890988970] 'process raft request' (duration: 156.495034ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:21:24.348789Z","caller":"traceutil/trace.go:171","msg":"trace[1477882372] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"268.346613ms","start":"2026-05-01T22:21:24.080414Z","end":"2026-05-01T22:21:24.348761Z","steps":["trace[1477882372] 'process raft request' (duration: 251.539776ms)","trace[1477882372] 'compare' (duration: 16.636799ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:21:24.350524Z","caller":"traceutil/trace.go:171","msg":"trace[1077999202] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:602; }","duration":"174.519447ms","start":"2026-05-01T22:21:24.175986Z","end":"2026-05-01T22:21:24.350506Z","steps":["trace[1077999202] 'read index received' (duration: 156.059673ms)","trace[1077999202] 'applied index is now lower than readState.Index' (duration: 18.456354ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:21:24.351457Z","caller":"traceutil/trace.go:171","msg":"trace[1816400674] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"219.561092ms","start":"2026-05-01T22:21:24.131884Z","end":"2026-05-01T22:21:24.351445Z","steps":["trace[1816400674] 'process raft request' (duration: 218.202177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:21:24.352042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.03614ms","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-05-01T22:21:24.35209Z","caller":"traceutil/trace.go:171","msg":"trace[427742020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"176.148025ms","start":"2026-05-01T22:21:24.175931Z","end":"2026-05-01T22:21:24.352079Z","steps":["trace[427742020] 'agreement among raft nodes before linearized reading' (duration: 176.04011ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:21:29.716419Z","caller":"traceutil/trace.go:171","msg":"trace[95139527] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"515.400989ms","start":"2026-05-01T22:21:29.200977Z","end":"2026-05-01T22:21:29.716378Z","steps":["trace[95139527] 'process raft request' (duration: 514.63171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:21:29.71699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:21:29.200955Z","time spent":"515.577398ms","remote":"127.0.0.1:39468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T22:21:30.026324Z","caller":"traceutil/trace.go:171","msg":"trace[1823772351] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"268.55293ms","start":"2026-05-01T22:21:29.757744Z","end":"2026-05-01T22:21:30.026297Z","steps":["trace[1823772351] 'read index received' (duration: 260.630695ms)","trace[1823772351] 'applied index is now lower than readState.Index' (duration: 7.921465ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:21:30.026543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.780972ms","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-01T22:21:30.02653Z","caller":"traceutil/trace.go:171","msg":"trace[323810536] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"291.918402ms","start":"2026-05-01T22:21:29.734568Z","end":"2026-05-01T22:21:30.026486Z","steps":["trace[323810536] 'process raft request' (duration: 283.712592ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:21:30.026574Z","caller":"traceutil/trace.go:171","msg":"trace[602835409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:619; }","duration":"268.847215ms","start":"2026-05-01T22:21:29.757717Z","end":"2026-05-01T22:21:30.026564Z","steps":["trace[602835409] 'agreement among raft nodes before linearized reading' (duration: 268.670656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:21:31.122798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.425911ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-01T22:21:31.123297Z","caller":"traceutil/trace.go:171","msg":"trace[1364894210] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"702.706421ms","start":"2026-05-01T22:21:30.420567Z","end":"2026-05-01T22:21:31.123273Z","steps":["trace[1364894210] 'process raft request' (duration: 702.597265ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:21:31.123349Z","caller":"traceutil/trace.go:171","msg":"trace[256718331] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.352420741s","start":"2026-05-01T22:21:29.770895Z","end":"2026-05-01T22:21:31.123316Z","steps":["trace[256718331] 'process raft request' (duration: 356.38698ms)","trace[256718331] 'compare' (duration: 995.253401ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:21:31.123389Z","caller":"traceutil/trace.go:171","msg":"trace[815634788] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"1.09143628s","start":"2026-05-01T22:21:30.031935Z","end":"2026-05-01T22:21:31.123372Z","steps":["trace[815634788] 'process raft request' (duration: 1.090986397s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:21:31.123423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:21:30.420555Z","time spent":"702.817247ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T22:21:31.123417Z","caller":"traceutil/trace.go:171","msg":"trace[968854909] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"1.0540859s","start":"2026-05-01T22:21:30.069302Z","end":"2026-05-01T22:21:31.123387Z","steps":["trace[968854909] 'process raft request' (duration: 1.053785685s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:21:31.123467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:21:30.031909Z","time spent":"1.091517865s","remote":"127.0.0.1:39614","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-05-01T22:21:31.123498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:21:29.770877Z","time spent":"1.352541628s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T22:21:31.123583Z","caller":"traceutil/trace.go:171","msg":"trace[397677381] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:644; }","duration":"738.141623ms","start":"2026-05-01T22:21:30.385428Z","end":"2026-05-01T22:21:31.12357Z","steps":["trace[397677381] 'read index received' (duration: 413.4084ms)","trace[397677381] 'applied index is now lower than readState.Index' (duration: 324.731293ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:21:31.123589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:21:30.069278Z","time spent":"1.054216678s","remote":"127.0.0.1:39546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T22:21:31.123802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.876639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T22:21:31.12381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.377486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-01T22:21:31.123832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.720421ms","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-01T22:21:31.123837Z","caller":"traceutil/trace.go:171","msg":"trace[1510122644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"455.960364ms","start":"2026-05-01T22:21:30.667864Z","end":"2026-05-01T22:21:31.123825Z","steps":["trace[1510122644] 'agreement among raft nodes before linearized reading' (duration: 455.89017ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:21:31.123846Z","caller":"traceutil/trace.go:171","msg":"trace[1975512636] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"738.45965ms","start":"2026-05-01T22:21:30.385375Z","end":"2026-05-01T22:21:31.123835Z","steps":["trace[1975512636] 'agreement among raft nodes before linearized reading' (duration: 738.284381ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:21:31.123859Z","caller":"traceutil/trace.go:171","msg":"trace[1934823159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"727.757113ms","start":"2026-05-01T22:21:30.396092Z","end":"2026-05-01T22:21:31.123849Z","steps":["trace[1934823159] 'agreement among raft nodes before linearized reading' (duration: 727.677099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:21:31.123862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:21:30.667835Z","time spent":"456.022717ms","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-01T22:21:31.123882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:21:30.396083Z","time spent":"727.792744ms","remote":"127.0.0.1:39614","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-05-01T22:21:31.123879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:21:30.38535Z","time spent":"738.522103ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-01T22:21:31.123883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.840093ms","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-01T22:21:31.123927Z","caller":"traceutil/trace.go:171","msg":"trace[1625247220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"732.899556ms","start":"2026-05-01T22:21:30.391013Z","end":"2026-05-01T22:21:31.123913Z","steps":["trace[1625247220] 'agreement among raft nodes before linearized reading' (duration: 732.737248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:21:31.124242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:21:30.391002Z","time spent":"733.225532ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-01T22:21:46.782576Z","caller":"traceutil/trace.go:171","msg":"trace[211015639] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:815; }","duration":"113.972773ms","start":"2026-05-01T22:21:46.668576Z","end":"2026-05-01T22:21:46.782549Z","steps":["trace[211015639] 'read index received' (duration: 113.788212ms)","trace[211015639] 'applied index is now lower than readState.Index' (duration: 183.601µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:21:46.78264Z","caller":"traceutil/trace.go:171","msg":"trace[2092536903] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"276.012097ms","start":"2026-05-01T22:21:46.506585Z","end":"2026-05-01T22:21:46.782597Z","steps":["trace[2092536903] 'process raft request' (duration: 275.845217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:21:46.782698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.109601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T22:21:46.782736Z","caller":"traceutil/trace.go:171","msg":"trace[81036006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"114.201166ms","start":"2026-05-01T22:21:46.668525Z","end":"2026-05-01T22:21:46.782726Z","steps":["trace[81036006] 'agreement among raft nodes before linearized reading' (duration: 114.126572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:21:47.284398Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291204556524960,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-01T22:21:47.448235Z","caller":"traceutil/trace.go:171","msg":"trace[483803090] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"664.281674ms","start":"2026-05-01T22:21:46.783934Z","end":"2026-05-01T22:21:47.448215Z","steps":["trace[483803090] 'read index received' (duration: 664.126445ms)","trace[483803090] 'applied index is now lower than readState.Index' (duration: 154.509µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:21:47.448335Z","caller":"traceutil/trace.go:171","msg":"trace[1614014864] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"693.575802ms","start":"2026-05-01T22:21:46.754753Z","end":"2026-05-01T22:21:47.448328Z","steps":["trace[1614014864] 'process raft request' (duration: 693.362259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:21:47.448412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:21:46.754728Z","time spent":"693.625185ms","remote":"127.0.0.1:39594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T22:21:47.44858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.432913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-01T22:21:47.448581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.088046ms","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-05-01T22:21:47.448653Z","caller":"traceutil/trace.go:171","msg":"trace[21758] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:788; }","duration":"334.54897ms","start":"2026-05-01T22:21:47.114085Z","end":"2026-05-01T22:21:47.448634Z","steps":["trace[21758] 'agreement among raft nodes before linearized reading' (duration: 334.343908ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:21:47.448656Z","caller":"traceutil/trace.go:171","msg":"trace[1503058055] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:788; }","duration":"245.200233ms","start":"2026-05-01T22:21:47.203438Z","end":"2026-05-01T22:21:47.448638Z","steps":["trace[1503058055] 'agreement among raft nodes before linearized reading' (duration: 245.043144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:21:47.448637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.014567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-01T22:21:47.448694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:21:47.114066Z","time spent":"334.619464ms","remote":"127.0.0.1:39414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.157\" "} {"level":"info","ts":"2026-05-01T22:21:47.448713Z","caller":"traceutil/trace.go:171","msg":"trace[96205608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:788; }","duration":"241.123973ms","start":"2026-05-01T22:21:47.207571Z","end":"2026-05-01T22:21:47.448695Z","steps":["trace[96205608] 'agreement among raft nodes before linearized reading' (duration: 240.882179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:21:47.448731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.805605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T22:21:47.448777Z","caller":"traceutil/trace.go:171","msg":"trace[1829139899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"664.87638ms","start":"2026-05-01T22:21:46.783893Z","end":"2026-05-01T22:21:47.44877Z","steps":["trace[1829139899] 'agreement among raft nodes before linearized reading' (duration: 664.812826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:21:47.4488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:21:46.783879Z","time spent":"664.917161ms","remote":"127.0.0.1:39410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-01T22:21:47.448904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.102962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-01T22:21:47.448935Z","caller":"traceutil/trace.go:171","msg":"trace[806386245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:788; }","duration":"241.149076ms","start":"2026-05-01T22:21:47.207774Z","end":"2026-05-01T22:21:47.448923Z","steps":["trace[806386245] 'agreement among raft nodes before linearized reading' (duration: 241.071551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:21:47.448972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.172115ms","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-01T22:21:47.449004Z","caller":"traceutil/trace.go:171","msg":"trace[1204362794] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:788; }","duration":"256.24479ms","start":"2026-05-01T22:21:47.19275Z","end":"2026-05-01T22:21:47.448994Z","steps":["trace[1204362794] 'agreement among raft nodes before linearized reading' (duration: 256.155075ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:22:00.053732Z","caller":"traceutil/trace.go:171","msg":"trace[760270807] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"152.706299ms","start":"2026-05-01T22:21:59.901Z","end":"2026-05-01T22:22:00.053707Z","steps":["trace[760270807] 'read index received' (duration: 149.115016ms)","trace[760270807] 'applied index is now lower than readState.Index' (duration: 3.589873ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:22:00.053793Z","caller":"traceutil/trace.go:171","msg":"trace[498669696] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"176.14472ms","start":"2026-05-01T22:21:59.877587Z","end":"2026-05-01T22:22:00.053732Z","steps":["trace[498669696] 'process raft request' (duration: 172.533506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:22:00.054099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.078202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/local-path-config\" ","response":"range_response_count:1 size:1464"} {"level":"info","ts":"2026-05-01T22:22:00.054416Z","caller":"traceutil/trace.go:171","msg":"trace[197653145] range","detail":"{range_begin:/registry/configmaps/local-path-storage/local-path-config; range_end:; response_count:1; response_revision:946; }","duration":"153.449735ms","start":"2026-05-01T22:21:59.900925Z","end":"2026-05-01T22:22:00.054375Z","steps":["trace[197653145] 'agreement among raft nodes before linearized reading' (duration: 152.869259ms)"],"step_count":1}