{"level":"warn","ts":"2026-05-21T14:39:06.138707Z","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-21T14:39:06.138826Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.193: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.19.213.193:2380","--initial-cluster=instance=https://199.19.213.193:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.193:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.193: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-21T14:39:06.138901Z","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-21T14:39:06.138916Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.193:2380"]} {"level":"info","ts":"2026-05-21T14:39:06.138935Z","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-21T14:39:06.139651Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"]} {"level":"info","ts":"2026-05-21T14:39:06.139738Z","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.19.213.193:2380"],"listen-peer-urls":["https://199.19.213.193:2380"],"advertise-client-urls":["https://199.19.213.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.193: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-21T14:39:06.144391Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.428994ms"} {"level":"info","ts":"2026-05-21T14:39:06.150345Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fc4b8086375a405d","cluster-id":"7155ef20751e16de"} {"level":"info","ts":"2026-05-21T14:39:06.150444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T14:39:06.150473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became follower at term 0"} {"level":"info","ts":"2026-05-21T14:39:06.150484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fc4b8086375a405d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T14:39:06.150497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became follower at term 1"} {"level":"info","ts":"2026-05-21T14:39:06.150548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=(18179765634753773661)"} {"level":"warn","ts":"2026-05-21T14:39:06.157819Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T14:39:06.159256Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T14:39:06.160615Z","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-21T14:39:06.163383Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fc4b8086375a405d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T14:39:06.167865Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fc4b8086375a405d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T14:39:06.168132Z","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-21T14:39:06.168439Z","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-21T14:39:06.173784Z","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-21T14:39:06.174143Z","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-21T14:39:06.174143Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.193:2380"} {"level":"info","ts":"2026-05-21T14:39:06.174849Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T14:39:06.174865Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.193:2380"} {"level":"info","ts":"2026-05-21T14:39:06.174193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=(18179765634753773661)"} {"level":"info","ts":"2026-05-21T14:39:06.174795Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fc4b8086375a405d","initial-advertise-peer-urls":["https://199.19.213.193:2380"],"listen-peer-urls":["https://199.19.213.193:2380"],"advertise-client-urls":["https://199.19.213.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T14:39:06.175041Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7155ef20751e16de","local-member-id":"fc4b8086375a405d","added-peer-id":"fc4b8086375a405d","added-peer-peer-urls":["https://199.19.213.193:2380"]} {"level":"info","ts":"2026-05-21T14:39:07.051581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T14:39:07.051666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T14:39:07.051722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d received MsgPreVoteResp from fc4b8086375a405d at term 1"} {"level":"info","ts":"2026-05-21T14:39:07.05175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became candidate at term 2"} {"level":"info","ts":"2026-05-21T14:39:07.051767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d received MsgVoteResp from fc4b8086375a405d at term 2"} {"level":"info","ts":"2026-05-21T14:39:07.051809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became leader at term 2"} {"level":"info","ts":"2026-05-21T14:39:07.05183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fc4b8086375a405d elected leader fc4b8086375a405d at term 2"} {"level":"info","ts":"2026-05-21T14:39:07.053032Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T14:39:07.054246Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T14:39:07.054288Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T14:39:07.054216Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fc4b8086375a405d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.193:2379]}","request-path":"/0/members/fc4b8086375a405d/attributes","cluster-id":"7155ef20751e16de","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T14:39:07.054642Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T14:39:07.054701Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T14:39:07.054686Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7155ef20751e16de","local-member-id":"fc4b8086375a405d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T14:39:07.054885Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T14:39:07.05495Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T14:39:07.056547Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.193:2379"} {"level":"info","ts":"2026-05-21T14:39:07.058374Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T14:39:09.765555Z","caller":"traceutil/trace.go:171","msg":"trace[1026866749] transaction","detail":"{read_only:false; response_revision:107; number_of_response:1; }","duration":"114.457306ms","start":"2026-05-21T14:39:09.651053Z","end":"2026-05-21T14:39:09.765511Z","steps":["trace[1026866749] 'process raft request' (duration: 97.442852ms)","trace[1026866749] 'compare' (duration: 16.835508ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T14:39:10.067591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.781447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-21T14:39:10.067762Z","caller":"traceutil/trace.go:171","msg":"trace[796618364] transaction","detail":"{read_only:false; response_revision:109; number_of_response:1; }","duration":"286.371347ms","start":"2026-05-21T14:39:09.781367Z","end":"2026-05-21T14:39:10.067738Z","steps":["trace[796618364] 'process raft request' (duration: 123.770228ms)","trace[796618364] 'compare' (duration: 161.578992ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T14:39:10.38355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.737166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-21T14:39:10.383723Z","caller":"traceutil/trace.go:171","msg":"trace[2077502564] transaction","detail":"{read_only:false; response_revision:110; number_of_response:1; }","duration":"309.743749ms","start":"2026-05-21T14:39:10.07395Z","end":"2026-05-21T14:39:10.383694Z","steps":["trace[2077502564] 'process raft request' (duration: 111.7349ms)","trace[2077502564] 'compare' (duration: 197.475571ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T14:39:10.383978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:10.073924Z","time spent":"310.008554ms","remote":"127.0.0.1:55908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":741,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T14:39:12.507825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.995569731s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-21T14:39:12.507963Z","caller":"traceutil/trace.go:171","msg":"trace[568266282] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.995805845s","start":"2026-05-21T14:39:10.512136Z","end":"2026-05-21T14:39:12.507942Z","steps":["trace[568266282] 'agreement among raft nodes before linearized reading' (duration: 1.99560383s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:12.508103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:10.512107Z","time spent":"1.995979932s","remote":"127.0.0.1:55522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/21 14:39:12 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-21T14:39:12.687162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.181276162s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-21T14:39:12.687277Z","caller":"traceutil/trace.go:171","msg":"trace[1211593087] linearizableReadLoop","detail":"{readStateIndex:115; appliedIndex:114; }","duration":"2.175068624s","start":"2026-05-21T14:39:10.512189Z","end":"2026-05-21T14:39:12.687257Z","steps":["trace[1211593087] 'read index received' (duration: 72.458µs)","trace[1211593087] 'applied index is now lower than readState.Index' (duration: 2.174992036s)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:39:12.687495Z","caller":"traceutil/trace.go:171","msg":"trace[99321373] transaction","detail":"{read_only:false; response_revision:111; number_of_response:1; }","duration":"2.295797068s","start":"2026-05-21T14:39:10.391681Z","end":"2026-05-21T14:39:12.687479Z","steps":["trace[99321373] 'process raft request' (duration: 114.127976ms)","trace[99321373] 'compare' (duration: 2.180728534s)"],"step_count":2} {"level":"warn","ts":"2026-05-21T14:39:12.687564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:10.391663Z","time spent":"2.295866977s","remote":"127.0.0.1:55908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":778,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T14:39:12.687808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.664891857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T14:39:12.687839Z","caller":"traceutil/trace.go:171","msg":"trace[1028136544] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:111; }","duration":"1.664960145s","start":"2026-05-21T14:39:11.022868Z","end":"2026-05-21T14:39:12.687828Z","steps":["trace[1028136544] 'agreement among raft nodes before linearized reading' (duration: 1.664862988s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:12.687869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:11.022848Z","time spent":"1.665014285s","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-05-21T14:39:12.688044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.900754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T14:39:12.688082Z","caller":"traceutil/trace.go:171","msg":"trace[1893163308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:111; }","duration":"179.980523ms","start":"2026-05-21T14:39:12.508089Z","end":"2026-05-21T14:39:12.68807Z","steps":["trace[1893163308] 'agreement among raft nodes before linearized reading' (duration: 179.920764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:12.688575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140278858s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T14:39:12.688683Z","caller":"traceutil/trace.go:171","msg":"trace[614321265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:111; }","duration":"1.140407446s","start":"2026-05-21T14:39:11.548252Z","end":"2026-05-21T14:39:12.688659Z","steps":["trace[614321265] 'agreement among raft nodes before linearized reading' (duration: 1.14022084s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:12.688749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:11.548187Z","time spent":"1.140552043s","remote":"127.0.0.1:55522","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-21T14:39:12.688394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246637714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T14:39:12.689227Z","caller":"traceutil/trace.go:171","msg":"trace[1421820291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:111; }","duration":"1.247487105s","start":"2026-05-21T14:39:11.441694Z","end":"2026-05-21T14:39:12.689181Z","steps":["trace[1421820291] 'agreement among raft nodes before linearized reading' (duration: 1.246654534s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:12.689511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:11.441666Z","time spent":"1.247685361s","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T14:39:13.192195Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037236117382962,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T14:39:13.683452Z","caller":"traceutil/trace.go:171","msg":"trace[1294836598] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"992.37985ms","start":"2026-05-21T14:39:12.691046Z","end":"2026-05-21T14:39:13.683426Z","steps":["trace[1294836598] 'process raft request' (duration: 992.226402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:13.683621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:12.691011Z","time spent":"992.529896ms","remote":"127.0.0.1:55724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T14:39:13.696723Z","caller":"traceutil/trace.go:171","msg":"trace[690664149] linearizableReadLoop","detail":"{readStateIndex:117; appliedIndex:115; }","duration":"1.005267759s","start":"2026-05-21T14:39:12.691437Z","end":"2026-05-21T14:39:13.696705Z","steps":["trace[690664149] 'read index received' (duration: 991.802932ms)","trace[690664149] 'applied index is now lower than readState.Index' (duration: 13.463857ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:39:13.696827Z","caller":"traceutil/trace.go:171","msg":"trace[1626383577] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"274.753658ms","start":"2026-05-21T14:39:13.422053Z","end":"2026-05-21T14:39:13.696807Z","steps":["trace[1626383577] 'process raft request' (duration: 274.618831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:13.696939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005472143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpointslicemirroring-controller\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T14:39:13.696985Z","caller":"traceutil/trace.go:171","msg":"trace[1828779809] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpointslicemirroring-controller; range_end:; response_count:0; response_revision:114; }","duration":"1.005573092s","start":"2026-05-21T14:39:12.691396Z","end":"2026-05-21T14:39:13.696969Z","steps":["trace[1828779809] 'agreement among raft nodes before linearized reading' (duration: 1.005418035s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:13.697024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:12.691384Z","time spent":"1.00563039s","remote":"127.0.0.1:55908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:controller:endpointslicemirroring-controller\" "} {"level":"info","ts":"2026-05-21T14:39:13.697018Z","caller":"traceutil/trace.go:171","msg":"trace[1338991479] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"1.003801472s","start":"2026-05-21T14:39:12.693203Z","end":"2026-05-21T14:39:13.697005Z","steps":["trace[1338991479] 'process raft request' (duration: 1.003365731s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:13.697302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.764618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-21T14:39:13.69736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.049961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T14:39:13.69796Z","caller":"traceutil/trace.go:171","msg":"trace[1346776750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:114; }","duration":"936.694446ms","start":"2026-05-21T14:39:12.761249Z","end":"2026-05-21T14:39:13.697944Z","steps":["trace[1346776750] 'agreement among raft nodes before linearized reading' (duration: 936.08311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:13.698002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:12.761219Z","time spent":"936.770275ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T14:39:13.697868Z","caller":"traceutil/trace.go:171","msg":"trace[78628968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:114; }","duration":"991.363904ms","start":"2026-05-21T14:39:12.70649Z","end":"2026-05-21T14:39:13.697854Z","steps":["trace[78628968] 'agreement among raft nodes before linearized reading' (duration: 990.777717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:13.697343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:12.693181Z","time spent":"1.003966708s","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T14:39:13.698846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:12.706472Z","time spent":"992.355343ms","remote":"127.0.0.1:55522","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-21T14:39:14.156684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.619997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T14:39:14.15695Z","caller":"traceutil/trace.go:171","msg":"trace[1468395755] transaction","detail":"{read_only:false; response_revision:129; number_of_response:1; }","duration":"278.150717ms","start":"2026-05-21T14:39:13.878772Z","end":"2026-05-21T14:39:14.156923Z","steps":["trace[1468395755] 'process raft request' (duration: 35.177247ms)","trace[1468395755] 'compare' (duration: 242.433942ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:39:14.494941Z","caller":"traceutil/trace.go:171","msg":"trace[1949329945] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"142.412896ms","start":"2026-05-21T14:39:14.352486Z","end":"2026-05-21T14:39:14.494899Z","steps":["trace[1949329945] 'process raft request' (duration: 72.849541ms)","trace[1949329945] 'compare' (duration: 69.337889ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:39:15.260887Z","caller":"traceutil/trace.go:171","msg":"trace[1928751635] transaction","detail":"{read_only:false; response_revision:166; number_of_response:1; }","duration":"157.166802ms","start":"2026-05-21T14:39:15.103655Z","end":"2026-05-21T14:39:15.260822Z","steps":["trace[1928751635] 'process raft request' (duration: 64.369652ms)","trace[1928751635] 'compare' (duration: 92.377069ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:39:15.414831Z","caller":"traceutil/trace.go:171","msg":"trace[353394992] transaction","detail":"{read_only:false; response_revision:169; number_of_response:1; }","duration":"142.860728ms","start":"2026-05-21T14:39:15.271906Z","end":"2026-05-21T14:39:15.414766Z","steps":["trace[353394992] 'process raft request' (duration: 98.406684ms)","trace[353394992] 'compare' (duration: 44.317807ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:39:15.58628Z","caller":"traceutil/trace.go:171","msg":"trace[1424975975] transaction","detail":"{read_only:false; response_revision:174; number_of_response:1; }","duration":"108.991301ms","start":"2026-05-21T14:39:15.47726Z","end":"2026-05-21T14:39:15.586251Z","steps":["trace[1424975975] 'process raft request' (duration: 108.764595ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:39:15.586297Z","caller":"traceutil/trace.go:171","msg":"trace[113534276] transaction","detail":"{read_only:false; response_revision:173; number_of_response:1; }","duration":"109.133277ms","start":"2026-05-21T14:39:15.477108Z","end":"2026-05-21T14:39:15.586241Z","steps":["trace[113534276] 'process raft request' (duration: 52.65902ms)","trace[113534276] 'compare' (duration: 56.098385ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:39:17.246884Z","caller":"traceutil/trace.go:171","msg":"trace[1411849012] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"100.171728ms","start":"2026-05-21T14:39:17.146658Z","end":"2026-05-21T14:39:17.246829Z","steps":["trace[1411849012] 'process raft request' (duration: 85.489899ms)","trace[1411849012] 'compare' (duration: 14.431804ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:39:17.445236Z","caller":"traceutil/trace.go:171","msg":"trace[1467724723] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"124.679791ms","start":"2026-05-21T14:39:17.320506Z","end":"2026-05-21T14:39:17.445186Z","steps":["trace[1467724723] 'process raft request' (duration: 45.029654ms)","trace[1467724723] 'compare' (duration: 79.227506ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:39:18.054377Z","caller":"traceutil/trace.go:171","msg":"trace[1252855399] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"101.975215ms","start":"2026-05-21T14:39:17.952377Z","end":"2026-05-21T14:39:18.054352Z","steps":["trace[1252855399] 'process raft request' (duration: 101.882057ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:39:18.054402Z","caller":"traceutil/trace.go:171","msg":"trace[1397307803] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"104.885852ms","start":"2026-05-21T14:39:17.949483Z","end":"2026-05-21T14:39:18.054369Z","steps":["trace[1397307803] 'process raft request' (duration: 86.948662ms)","trace[1397307803] 'compare' (duration: 17.596797ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T14:39:18.314274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.22187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-21T14:39:18.314391Z","caller":"traceutil/trace.go:171","msg":"trace[34841237] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:295; }","duration":"112.364348ms","start":"2026-05-21T14:39:18.201991Z","end":"2026-05-21T14:39:18.314355Z","steps":["trace[34841237] 'range keys from in-memory index tree' (duration: 112.083533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:19.902539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.131722ms","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-21T14:39:19.902678Z","caller":"traceutil/trace.go:171","msg":"trace[798663632] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:335; }","duration":"198.309738ms","start":"2026-05-21T14:39:19.704341Z","end":"2026-05-21T14:39:19.90265Z","steps":["trace[798663632] 'range keys from in-memory index tree' (duration: 197.984635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:19.902587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.687611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T14:39:19.90278Z","caller":"traceutil/trace.go:171","msg":"trace[763557600] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:335; }","duration":"197.908957ms","start":"2026-05-21T14:39:19.704857Z","end":"2026-05-21T14:39:19.902766Z","steps":["trace[763557600] 'range keys from in-memory index tree' (duration: 197.610883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:19.902953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.97563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T14:39:19.903211Z","caller":"traceutil/trace.go:171","msg":"trace[1530141971] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"191.511604ms","start":"2026-05-21T14:39:19.711645Z","end":"2026-05-21T14:39:19.903157Z","steps":["trace[1530141971] 'process raft request' (duration: 65.053444ms)","trace[1530141971] 'compare' (duration: 125.808674ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:39:20.803814Z","caller":"traceutil/trace.go:171","msg":"trace[1466711016] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:361; }","duration":"159.00931ms","start":"2026-05-21T14:39:20.644771Z","end":"2026-05-21T14:39:20.803781Z","steps":["trace[1466711016] 'read index received' (duration: 158.756355ms)","trace[1466711016] 'applied index is now lower than readState.Index' (duration: 251.535µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:39:20.804359Z","caller":"traceutil/trace.go:171","msg":"trace[184888144] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"243.687903ms","start":"2026-05-21T14:39:20.560652Z","end":"2026-05-21T14:39:20.80434Z","steps":["trace[184888144] 'process raft request' (duration: 242.962629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:20.804708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.91843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T14:39:20.804774Z","caller":"traceutil/trace.go:171","msg":"trace[318698981] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pvc-protection-controller; range_end:; response_count:0; response_revision:348; }","duration":"160.068977ms","start":"2026-05-21T14:39:20.644687Z","end":"2026-05-21T14:39:20.804756Z","steps":["trace[318698981] 'agreement among raft nodes before linearized reading' (duration: 159.93831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:20.804997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.752974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T14:39:20.805142Z","caller":"traceutil/trace.go:171","msg":"trace[1325950357] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:348; }","duration":"117.960979ms","start":"2026-05-21T14:39:20.687133Z","end":"2026-05-21T14:39:20.805094Z","steps":["trace[1325950357] 'agreement among raft nodes before linearized reading' (duration: 117.679475ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:39:22.611434Z","caller":"traceutil/trace.go:171","msg":"trace[1911033827] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"189.886929ms","start":"2026-05-21T14:39:22.421508Z","end":"2026-05-21T14:39:22.611395Z","steps":["trace[1911033827] 'process raft request' (duration: 189.637513ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:39:22.896186Z","caller":"traceutil/trace.go:171","msg":"trace[1199127340] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"154.239872ms","start":"2026-05-21T14:39:22.741918Z","end":"2026-05-21T14:39:22.896157Z","steps":["trace[1199127340] 'read index received' (duration: 154.004178ms)","trace[1199127340] 'applied index is now lower than readState.Index' (duration: 234.734µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T14:39:22.896391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.452079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-21T14:39:22.896434Z","caller":"traceutil/trace.go:171","msg":"trace[1072317967] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:407; }","duration":"154.568106ms","start":"2026-05-21T14:39:22.741855Z","end":"2026-05-21T14:39:22.896423Z","steps":["trace[1072317967] 'agreement among raft nodes before linearized reading' (duration: 154.464749ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:39:22.896709Z","caller":"traceutil/trace.go:171","msg":"trace[1580766139] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"207.307509ms","start":"2026-05-21T14:39:22.688993Z","end":"2026-05-21T14:39:22.896301Z","steps":["trace[1580766139] 'process raft request' (duration: 207.007235ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:39:23.041952Z","caller":"traceutil/trace.go:171","msg":"trace[1019735360] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"141.356551ms","start":"2026-05-21T14:39:22.900545Z","end":"2026-05-21T14:39:23.041901Z","steps":["trace[1019735360] 'process raft request' (duration: 92.699496ms)","trace[1019735360] 'compare' (duration: 48.413489ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:39:23.042231Z","caller":"traceutil/trace.go:171","msg":"trace[254152588] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"137.233338ms","start":"2026-05-21T14:39:22.904941Z","end":"2026-05-21T14:39:23.042174Z","steps":["trace[254152588] 'process raft request' (duration: 136.894116ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:39:53.6389Z","caller":"traceutil/trace.go:171","msg":"trace[582838957] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"513.613273ms","start":"2026-05-21T14:39:53.125211Z","end":"2026-05-21T14:39:53.638824Z","steps":["trace[582838957] 'process raft request' (duration: 513.167414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:53.639286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:53.12519Z","time spent":"513.888541ms","remote":"127.0.0.1:55602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T14:39:53.648489Z","caller":"traceutil/trace.go:171","msg":"trace[1405509057] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"518.780653ms","start":"2026-05-21T14:39:53.129691Z","end":"2026-05-21T14:39:53.648472Z","steps":["trace[1405509057] 'process raft request' (duration: 518.620523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:53.648613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:53.12967Z","time spent":"518.875619ms","remote":"127.0.0.1:55602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T14:39:53.64872Z","caller":"traceutil/trace.go:171","msg":"trace[1397564193] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"518.28385ms","start":"2026-05-21T14:39:53.130415Z","end":"2026-05-21T14:39:53.648699Z","steps":["trace[1397564193] 'process raft request' (duration: 517.96435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:53.6489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:53.130407Z","time spent":"518.368266ms","remote":"127.0.0.1:55602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T14:39:53.64893Z","caller":"traceutil/trace.go:171","msg":"trace[999368932] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"496.313798ms","start":"2026-05-21T14:39:53.152607Z","end":"2026-05-21T14:39:53.648921Z","steps":["trace[999368932] 'process raft request' (duration: 495.806714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:53.64897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:53.15259Z","time spent":"496.361301ms","remote":"127.0.0.1:55602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T14:39:53.649038Z","caller":"traceutil/trace.go:171","msg":"trace[1917728011] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"493.60251ms","start":"2026-05-21T14:39:53.155428Z","end":"2026-05-21T14:39:53.649031Z","steps":["trace[1917728011] 'process raft request' (duration: 493.204993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:53.649069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:53.15542Z","time spent":"493.636461ms","remote":"127.0.0.1:55602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T14:39:53.649108Z","caller":"traceutil/trace.go:171","msg":"trace[581115376] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"492.732013ms","start":"2026-05-21T14:39:53.156363Z","end":"2026-05-21T14:39:53.649095Z","steps":["trace[581115376] 'process raft request' (duration: 492.37189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:53.649159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:53.156356Z","time spent":"492.780365ms","remote":"127.0.0.1:55602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T14:39:53.649228Z","caller":"traceutil/trace.go:171","msg":"trace[167106205] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"486.15878ms","start":"2026-05-21T14:39:53.163039Z","end":"2026-05-21T14:39:53.649198Z","steps":["trace[167106205] 'process raft request' (duration: 485.867952ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:39:53.649299Z","caller":"traceutil/trace.go:171","msg":"trace[1621315906] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"485.22721ms","start":"2026-05-21T14:39:53.164037Z","end":"2026-05-21T14:39:53.649264Z","steps":["trace[1621315906] 'process raft request' (duration: 485.008166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:53.649337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:53.163015Z","time spent":"486.270807ms","remote":"127.0.0.1:55602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T14:39:53.649381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:53.164016Z","time spent":"485.335126ms","remote":"127.0.0.1:55602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T14:39:53.649504Z","caller":"traceutil/trace.go:171","msg":"trace[1531440328] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"478.08154ms","start":"2026-05-21T14:39:53.171413Z","end":"2026-05-21T14:39:53.649495Z","steps":["trace[1531440328] 'process raft request' (duration: 477.824763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:53.64955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:53.171398Z","time spent":"478.133684ms","remote":"127.0.0.1:55602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T14:39:53.649693Z","caller":"traceutil/trace.go:171","msg":"trace[960937608] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"478.200428ms","start":"2026-05-21T14:39:53.171479Z","end":"2026-05-21T14:39:53.64968Z","steps":["trace[960937608] 'process raft request' (duration: 477.912449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:53.649758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:53.171474Z","time spent":"478.255091ms","remote":"127.0.0.1:55602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T14:39:53.64976Z","caller":"traceutil/trace.go:171","msg":"trace[666030839] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"456.056124ms","start":"2026-05-21T14:39:53.193693Z","end":"2026-05-21T14:39:53.649749Z","steps":["trace[666030839] 'process raft request' (duration: 455.810738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:53.649825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:53.193677Z","time spent":"456.131099ms","remote":"127.0.0.1:55602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T14:39:53.649961Z","caller":"traceutil/trace.go:171","msg":"trace[1171903036] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:565; }","duration":"400.898362ms","start":"2026-05-21T14:39:53.249044Z","end":"2026-05-21T14:39:53.649943Z","steps":["trace[1171903036] 'read index received' (duration: 389.43374ms)","trace[1171903036] 'applied index is now lower than readState.Index' (duration: 11.463812ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T14:39:53.65018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.124837ms","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-21T14:39:53.650367Z","caller":"traceutil/trace.go:171","msg":"trace[763972468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"401.31927ms","start":"2026-05-21T14:39:53.249037Z","end":"2026-05-21T14:39:53.650357Z","steps":["trace[763972468] 'agreement among raft nodes before linearized reading' (duration: 401.043691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:53.650419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:53.248969Z","time spent":"401.440198ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-21T14:39:53.65022Z","caller":"traceutil/trace.go:171","msg":"trace[64718927] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"345.10795ms","start":"2026-05-21T14:39:53.305099Z","end":"2026-05-21T14:39:53.650207Z","steps":["trace[64718927] 'process raft request' (duration: 344.607997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:39:53.650619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:53.30506Z","time spent":"345.531837ms","remote":"127.0.0.1:55602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T14:39:59.732264Z","caller":"traceutil/trace.go:171","msg":"trace[401360427] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"100.698145ms","start":"2026-05-21T14:39:59.631489Z","end":"2026-05-21T14:39:59.732187Z","steps":["trace[401360427] 'process raft request' (duration: 99.969362ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:39:59.802784Z","caller":"traceutil/trace.go:171","msg":"trace[1707845643] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"107.992266ms","start":"2026-05-21T14:39:59.694745Z","end":"2026-05-21T14:39:59.802737Z","steps":["trace[1707845643] 'process raft request' (duration: 102.235515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:40:00.304367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.529515ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T14:40:00.304648Z","caller":"traceutil/trace.go:171","msg":"trace[521968031] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:679; }","duration":"296.303279ms","start":"2026-05-21T14:40:00.008323Z","end":"2026-05-21T14:40:00.304627Z","steps":["trace[521968031] 'read index received' (duration: 163.283965ms)","trace[521968031] 'applied index is now lower than readState.Index' (duration: 133.018154ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:40:00.304791Z","caller":"traceutil/trace.go:171","msg":"trace[619685018] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"564.413758ms","start":"2026-05-21T14:39:59.740324Z","end":"2026-05-21T14:40:00.304737Z","steps":["trace[619685018] 'process raft request' (duration: 431.415645ms)","trace[619685018] 'compare' (duration: 132.343704ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T14:40:00.304904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.558915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T14:40:00.305024Z","caller":"traceutil/trace.go:171","msg":"trace[728675806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"296.726635ms","start":"2026-05-21T14:40:00.008269Z","end":"2026-05-21T14:40:00.304996Z","steps":["trace[728675806] 'agreement among raft nodes before linearized reading' (duration: 296.499802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:40:00.305035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:59.740286Z","time spent":"564.619399ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T14:40:00.305043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.847343ms","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-21T14:40:00.305013Z","caller":"traceutil/trace.go:171","msg":"trace[1959665116] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"511.260869ms","start":"2026-05-21T14:39:59.793544Z","end":"2026-05-21T14:40:00.304805Z","steps":["trace[1959665116] 'process raft request' (duration: 511.011284ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:40:00.305155Z","caller":"traceutil/trace.go:171","msg":"trace[1961991756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:654; }","duration":"117.006011ms","start":"2026-05-21T14:40:00.188133Z","end":"2026-05-21T14:40:00.305139Z","steps":["trace[1961991756] 'agreement among raft nodes before linearized reading' (duration: 116.651771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:40:00.305834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:39:59.793478Z","time spent":"512.160031ms","remote":"127.0.0.1:56076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T14:40:01.509537Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037236117384066,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T14:40:01.67321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.522943ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T14:40:01.674193Z","caller":"traceutil/trace.go:171","msg":"trace[171821275] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:657; }","duration":"568.488188ms","start":"2026-05-21T14:40:01.105653Z","end":"2026-05-21T14:40:01.674141Z","steps":["trace[171821275] 'range keys from in-memory index tree' (duration: 567.502933ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:40:01.679175Z","caller":"traceutil/trace.go:171","msg":"trace[841311774] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:684; }","duration":"669.887321ms","start":"2026-05-21T14:40:01.009232Z","end":"2026-05-21T14:40:01.679119Z","steps":["trace[841311774] 'read index received' (duration: 663.093662ms)","trace[841311774] 'applied index is now lower than readState.Index' (duration: 6.792869ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:40:01.67922Z","caller":"traceutil/trace.go:171","msg":"trace[1881957394] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"900.614059ms","start":"2026-05-21T14:40:00.77857Z","end":"2026-05-21T14:40:01.679184Z","steps":["trace[1881957394] 'process raft request' (duration: 894.078245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:40:01.679288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.04649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T14:40:01.679324Z","caller":"traceutil/trace.go:171","msg":"trace[711513432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:658; }","duration":"670.127034ms","start":"2026-05-21T14:40:01.009186Z","end":"2026-05-21T14:40:01.679313Z","steps":["trace[711513432] 'agreement among raft nodes before linearized reading' (duration: 670.051759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:40:01.67935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:40:01.009169Z","time spent":"670.176166ms","remote":"127.0.0.1:55522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T14:40:01.679369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:40:00.778546Z","time spent":"900.744046ms","remote":"127.0.0.1:55630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T14:40:18.645754Z","caller":"traceutil/trace.go:171","msg":"trace[1071181313] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"232.428279ms","start":"2026-05-21T14:40:18.413297Z","end":"2026-05-21T14:40:18.645725Z","steps":["trace[1071181313] 'process raft request' (duration: 232.254542ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:40:19.223493Z","caller":"traceutil/trace.go:171","msg":"trace[158658719] linearizableReadLoop","detail":"{readStateIndex:857; appliedIndex:855; }","duration":"771.716233ms","start":"2026-05-21T14:40:18.451747Z","end":"2026-05-21T14:40:19.223463Z","steps":["trace[158658719] 'read index received' (duration: 193.80593ms)","trace[158658719] 'applied index is now lower than readState.Index' (duration: 577.908793ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:40:19.223726Z","caller":"traceutil/trace.go:171","msg":"trace[1779265049] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"805.484637ms","start":"2026-05-21T14:40:18.418191Z","end":"2026-05-21T14:40:19.223675Z","steps":["trace[1779265049] 'process raft request' (duration: 747.836903ms)","trace[1779265049] 'compare' (duration: 57.29536ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T14:40:19.22399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:40:18.418162Z","time spent":"805.682976ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T14:40:19.224519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.734535ms","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-21T14:40:19.224581Z","caller":"traceutil/trace.go:171","msg":"trace[1685882959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:824; }","duration":"772.864021ms","start":"2026-05-21T14:40:18.451696Z","end":"2026-05-21T14:40:19.22456Z","steps":["trace[1685882959] 'agreement among raft nodes before linearized reading' (duration: 772.289847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:40:19.224618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:40:18.451665Z","time spent":"772.944384ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T14:40:19.226532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.687426ms","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-21T14:40:19.226599Z","caller":"traceutil/trace.go:171","msg":"trace[1366437472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:825; }","duration":"774.799571ms","start":"2026-05-21T14:40:18.451784Z","end":"2026-05-21T14:40:19.226584Z","steps":["trace[1366437472] 'agreement among raft nodes before linearized reading' (duration: 774.543281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:40:19.226663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:40:18.451761Z","time spent":"774.890446ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T14:40:19.226494Z","caller":"traceutil/trace.go:171","msg":"trace[1018736824] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"474.892357ms","start":"2026-05-21T14:40:18.751524Z","end":"2026-05-21T14:40:19.226416Z","steps":["trace[1018736824] 'process raft request' (duration: 474.605276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:40:19.226699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.564217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T14:40:19.226766Z","caller":"traceutil/trace.go:171","msg":"trace[1544318861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:825; }","duration":"218.713594ms","start":"2026-05-21T14:40:19.008036Z","end":"2026-05-21T14:40:19.226749Z","steps":["trace[1544318861] 'agreement among raft nodes before linearized reading' (duration: 218.549616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:40:19.226809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:40:18.751493Z","time spent":"475.214061ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T14:40:39.490418Z","caller":"traceutil/trace.go:171","msg":"trace[112721866] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"141.190333ms","start":"2026-05-21T14:40:39.3492Z","end":"2026-05-21T14:40:39.49039Z","steps":["trace[112721866] 'process raft request' (duration: 141.126851ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:40:39.4906Z","caller":"traceutil/trace.go:171","msg":"trace[777823757] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"141.661128ms","start":"2026-05-21T14:40:39.348923Z","end":"2026-05-21T14:40:39.490584Z","steps":["trace[777823757] 'process raft request' (duration: 102.499735ms)","trace[777823757] 'compare' (duration: 38.763131ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:40:46.980255Z","caller":"traceutil/trace.go:171","msg":"trace[365170134] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"142.044526ms","start":"2026-05-21T14:40:46.838152Z","end":"2026-05-21T14:40:46.980197Z","steps":["trace[365170134] 'process raft request' (duration: 141.883582ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:41:00.302859Z","caller":"traceutil/trace.go:171","msg":"trace[2065657224] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"118.798241ms","start":"2026-05-21T14:41:00.184041Z","end":"2026-05-21T14:41:00.302839Z","steps":["trace[2065657224] 'process raft request' (duration: 118.662207ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:41:00.307044Z","caller":"traceutil/trace.go:171","msg":"trace[32750564] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"120.304589ms","start":"2026-05-21T14:41:00.186728Z","end":"2026-05-21T14:41:00.307033Z","steps":["trace[32750564] 'process raft request' (duration: 120.231948ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:41:19.601676Z","caller":"traceutil/trace.go:171","msg":"trace[1126848171] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"222.837847ms","start":"2026-05-21T14:41:19.378808Z","end":"2026-05-21T14:41:19.601645Z","steps":["trace[1126848171] 'process raft request' (duration: 222.656785ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:41:31.93719Z","caller":"traceutil/trace.go:171","msg":"trace[2094068175] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"108.579604ms","start":"2026-05-21T14:41:31.828578Z","end":"2026-05-21T14:41:31.937157Z","steps":["trace[2094068175] 'process raft request' (duration: 107.583714ms)"],"step_count":1}