{"level":"warn","ts":"2026-05-10T00:48:11.293913Z","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-10T00:48:11.294146Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-10T00:48:11.294257Z","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-10T00:48:11.294286Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-05-10T00:48:11.294327Z","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-10T00:48:11.295123Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-05-10T00:48:11.295283Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-10T00:48:11.299163Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.598032ms"} {"level":"info","ts":"2026-05-10T00:48:11.319462Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-05-10T00:48:11.319546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-05-10T00:48:11.319574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-05-10T00:48:11.319586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-10T00:48:11.319596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-05-10T00:48:11.31964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-05-10T00:48:11.405366Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-10T00:48:11.428282Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-10T00:48:11.431665Z","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-10T00:48:11.44789Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-10T00:48:11.448537Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-10T00:48:11.44879Z","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-10T00:48:11.448879Z","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-10T00:48:11.448933Z","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-10T00:48:11.450728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-05-10T00:48:11.45329Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-05-10T00:48:11.454544Z","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-10T00:48:11.454689Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-05-10T00:48:11.454736Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-05-10T00:48:11.455025Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-10T00:48:11.455065Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-10T00:48:11.620292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-05-10T00:48:11.620397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-10T00:48:11.620424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-05-10T00:48:11.620459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-05-10T00:48:11.620482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-05-10T00:48:11.620508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-05-10T00:48:11.620532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-05-10T00:48:11.627783Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:48:11.628549Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-10T00:48:11.628636Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-10T00:48:11.628844Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-10T00:48:11.628917Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-10T00:48:11.628544Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-05-10T00:48:11.628972Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:48:11.629105Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:48:11.629137Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:48:11.632516Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-05-10T00:48:11.634594Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-10T00:48:13.336387Z","caller":"traceutil/trace.go:171","msg":"trace[1947817216] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"115.221139ms","start":"2026-05-10T00:48:13.221085Z","end":"2026-05-10T00:48:13.336306Z","steps":["trace[1947817216] 'process raft request' (duration: 36.964788ms)","trace[1947817216] 'compare' (duration: 78.01389ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:48:13.336542Z","caller":"traceutil/trace.go:171","msg":"trace[2142914448] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"112.749795ms","start":"2026-05-10T00:48:13.223754Z","end":"2026-05-10T00:48:13.336504Z","steps":["trace[2142914448] 'process raft request' (duration: 112.474583ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:13.336668Z","caller":"traceutil/trace.go:171","msg":"trace[1147366873] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"102.866433ms","start":"2026-05-10T00:48:13.233738Z","end":"2026-05-10T00:48:13.336604Z","steps":["trace[1147366873] 'process raft request' (duration: 102.700882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:13.849593Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052574378932146,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T00:48:15.125186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169745988s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-05-10T00:48:15.125404Z","caller":"traceutil/trace.go:171","msg":"trace[132667627] linearizableReadLoop","detail":"{readStateIndex:53; appliedIndex:51; }","duration":"1.776235101s","start":"2026-05-10T00:48:13.349152Z","end":"2026-05-10T00:48:15.125387Z","steps":["trace[132667627] 'read index received' (duration: 605.665908ms)","trace[132667627] 'applied index is now lower than readState.Index' (duration: 1.170553473s)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:48:15.125486Z","caller":"traceutil/trace.go:171","msg":"trace[1170303989] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"1.782428919s","start":"2026-05-10T00:48:13.343048Z","end":"2026-05-10T00:48:15.125477Z","steps":["trace[1170303989] 'process raft request' (duration: 1.782265658s)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:15.125521Z","caller":"traceutil/trace.go:171","msg":"trace[1719944647] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"1.783447616s","start":"2026-05-10T00:48:13.34203Z","end":"2026-05-10T00:48:15.125478Z","steps":["trace[1719944647] 'process raft request' (duration: 612.774452ms)","trace[1719944647] 'compare' (duration: 1.169582637s)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:48:15.125661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.776474002s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T00:48:15.125764Z","caller":"traceutil/trace.go:171","msg":"trace[400234341] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:49; }","duration":"1.776625703s","start":"2026-05-10T00:48:13.349106Z","end":"2026-05-10T00:48:15.125732Z","steps":["trace[400234341] 'agreement among raft nodes before linearized reading' (duration: 1.776410702s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:15.125552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:13.342997Z","time spent":"1.78252582s","remote":"127.0.0.1:46940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":826,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T00:48:15.125842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:13.349093Z","time spent":"1.776734194s","remote":"127.0.0.1:46756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-05-10T00:48:15.125773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:13.342004Z","time spent":"1.783654568s","remote":"127.0.0.1:46940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1059,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:48:15.376705Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.161281536s","expected-duration":"1s"} {"level":"info","ts":"2026-05-10T00:48:15.394852Z","caller":"traceutil/trace.go:171","msg":"trace[2059698231] linearizableReadLoop","detail":"{readStateIndex:54; appliedIndex:53; }","duration":"269.35495ms","start":"2026-05-10T00:48:15.125435Z","end":"2026-05-10T00:48:15.39479Z","steps":["trace[2059698231] 'read index received' (duration: 251.585292ms)","trace[2059698231] 'applied index is now lower than readState.Index' (duration: 17.768398ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:48:15.39495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217409657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-10T00:48:15.39508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.971380474s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T00:48:15.39513Z","caller":"traceutil/trace.go:171","msg":"trace[844983019] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:49; }","duration":"1.971525344s","start":"2026-05-10T00:48:13.42359Z","end":"2026-05-10T00:48:15.395115Z","steps":["trace[844983019] 'agreement among raft nodes before linearized reading' (duration: 1.971404484s)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:15.395086Z","caller":"traceutil/trace.go:171","msg":"trace[1274659034] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:49; }","duration":"1.217628539s","start":"2026-05-10T00:48:14.177431Z","end":"2026-05-10T00:48:15.39506Z","steps":["trace[1274659034] 'agreement among raft nodes before linearized reading' (duration: 1.217382748s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:15.395192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:13.423573Z","time spent":"1.971608216s","remote":"127.0.0.1:46890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-10T00:48:15.395202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:14.177411Z","time spent":"1.21777814s","remote":"127.0.0.1:46458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-10T00:48:15.395366Z","caller":"traceutil/trace.go:171","msg":"trace[625864866] transaction","detail":"{read_only:false; number_of_response:0; response_revision:49; }","duration":"1.180033589s","start":"2026-05-10T00:48:14.215308Z","end":"2026-05-10T00:48:15.395342Z","steps":["trace[625864866] 'process raft request' (duration: 1.161682428s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:15.395507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:14.215285Z","time spent":"1.18015867s","remote":"127.0.0.1:46652","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T00:48:15.395383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323496287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T00:48:15.395716Z","caller":"traceutil/trace.go:171","msg":"trace[1227836639] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:50; }","duration":"1.323851039s","start":"2026-05-10T00:48:14.071851Z","end":"2026-05-10T00:48:15.395702Z","steps":["trace[1227836639] 'agreement among raft nodes before linearized reading' (duration: 1.323162125s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:15.395768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:14.071822Z","time spent":"1.323933349s","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"info","ts":"2026-05-10T00:48:15.39563Z","caller":"traceutil/trace.go:171","msg":"trace[546047919] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"266.104479ms","start":"2026-05-10T00:48:15.129501Z","end":"2026-05-10T00:48:15.395606Z","steps":["trace[546047919] 'process raft request' (duration: 265.441276ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:15.395885Z","caller":"traceutil/trace.go:171","msg":"trace[1325404069] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"265.977418ms","start":"2026-05-10T00:48:15.12989Z","end":"2026-05-10T00:48:15.395868Z","steps":["trace[1325404069] 'process raft request' (duration: 265.229014ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:15.395994Z","caller":"traceutil/trace.go:171","msg":"trace[1941486062] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"264.867282ms","start":"2026-05-10T00:48:15.131108Z","end":"2026-05-10T00:48:15.395975Z","steps":["trace[1941486062] 'process raft request' (duration: 264.416619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:15.396119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.324751405s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T00:48:15.396168Z","caller":"traceutil/trace.go:171","msg":"trace[390505814] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:52; }","duration":"1.324878866s","start":"2026-05-10T00:48:14.071276Z","end":"2026-05-10T00:48:15.396155Z","steps":["trace[390505814] 'agreement among raft nodes before linearized reading' (duration: 1.324026111s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:15.396263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:14.071254Z","time spent":"1.324997307s","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-05-10T00:48:16.665763Z","caller":"traceutil/trace.go:171","msg":"trace[2059793163] transaction","detail":"{read_only:false; response_revision:155; number_of_response:1; }","duration":"138.772251ms","start":"2026-05-10T00:48:16.526949Z","end":"2026-05-10T00:48:16.665721Z","steps":["trace[2059793163] 'process raft request' (duration: 52.276237ms)","trace[2059793163] 'compare' (duration: 86.319293ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:48:16.89845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.741004ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:48:16.898581Z","caller":"traceutil/trace.go:171","msg":"trace[1268808841] linearizableReadLoop","detail":"{readStateIndex:166; appliedIndex:165; }","duration":"153.071187ms","start":"2026-05-10T00:48:16.745488Z","end":"2026-05-10T00:48:16.898559Z","steps":["trace[1268808841] 'read index received' (duration: 20.053701ms)","trace[1268808841] 'applied index is now lower than readState.Index' (duration: 133.015756ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:48:16.898608Z","caller":"traceutil/trace.go:171","msg":"trace[671701980] transaction","detail":"{read_only:false; response_revision:161; number_of_response:1; }","duration":"156.754679ms","start":"2026-05-10T00:48:16.741822Z","end":"2026-05-10T00:48:16.898577Z","steps":["trace[671701980] 'process raft request' (duration: 23.775924ms)","trace[671701980] 'compare' (duration: 132.551973ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:48:16.8989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.347979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:48:16.899051Z","caller":"traceutil/trace.go:171","msg":"trace[1068527233] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:161; }","duration":"153.54601ms","start":"2026-05-10T00:48:16.745473Z","end":"2026-05-10T00:48:16.899019Z","steps":["trace[1068527233] 'agreement among raft nodes before linearized reading' (duration: 153.136387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:23.895041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.521403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:48:23.895104Z","caller":"traceutil/trace.go:171","msg":"trace[720012912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:318; }","duration":"325.621154ms","start":"2026-05-10T00:48:23.569469Z","end":"2026-05-10T00:48:23.89509Z","steps":["trace[720012912] 'range keys from in-memory index tree' (duration: 325.478023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:23.895129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:23.569462Z","time spent":"325.661665ms","remote":"127.0.0.1:46460","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-10T00:48:23.895147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.012488ms","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-10T00:48:23.895177Z","caller":"traceutil/trace.go:171","msg":"trace[1829453387] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:318; }","duration":"928.057438ms","start":"2026-05-10T00:48:22.967111Z","end":"2026-05-10T00:48:23.895169Z","steps":["trace[1829453387] 'range keys from in-memory index tree' (duration: 927.955318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:23.895192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:22.967104Z","time spent":"928.084339ms","remote":"127.0.0.1:46756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-10T00:48:23.895221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.512254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-10T00:48:23.895275Z","caller":"traceutil/trace.go:171","msg":"trace[679799129] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:318; }","duration":"826.610565ms","start":"2026-05-10T00:48:23.068652Z","end":"2026-05-10T00:48:23.895262Z","steps":["trace[679799129] 'range keys from in-memory index tree' (duration: 826.397462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:23.895297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.049388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"warn","ts":"2026-05-10T00:48:23.895325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.719387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-10T00:48:23.895327Z","caller":"traceutil/trace.go:171","msg":"trace[473660611] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:318; }","duration":"675.103388ms","start":"2026-05-10T00:48:23.220216Z","end":"2026-05-10T00:48:23.89532Z","steps":["trace[473660611] 'range keys from in-memory index tree' (duration: 674.971477ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:23.895341Z","caller":"traceutil/trace.go:171","msg":"trace[1425804404] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:318; }","duration":"876.757057ms","start":"2026-05-10T00:48:23.018579Z","end":"2026-05-10T00:48:23.895336Z","steps":["trace[1425804404] 'range keys from in-memory index tree' (duration: 876.662696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:23.895349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:23.220204Z","time spent":"675.137548ms","remote":"127.0.0.1:46652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6988,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-05-10T00:48:23.89536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:23.018569Z","time spent":"876.787727ms","remote":"127.0.0.1:46676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-05-10T00:48:23.895389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.135412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-10T00:48:23.895411Z","caller":"traceutil/trace.go:171","msg":"trace[1974287080] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:318; }","duration":"927.170773ms","start":"2026-05-10T00:48:22.968234Z","end":"2026-05-10T00:48:23.895405Z","steps":["trace[1974287080] 'range keys from in-memory index tree' (duration: 927.093042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:23.895432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:22.968228Z","time spent":"927.199163ms","remote":"127.0.0.1:46676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-05-10T00:48:23.895653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:23.068639Z","time spent":"827.008767ms","remote":"127.0.0.1:46676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-05-10T00:48:23.895785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.734143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-10T00:48:23.895873Z","caller":"traceutil/trace.go:171","msg":"trace[1615745533] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:318; }","duration":"776.850684ms","start":"2026-05-10T00:48:23.119003Z","end":"2026-05-10T00:48:23.895854Z","steps":["trace[1615745533] 'range keys from in-memory index tree' (duration: 776.638562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:23.895914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:23.118909Z","time spent":"776.997064ms","remote":"127.0.0.1:46676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-05-10T00:48:24.050495Z","caller":"traceutil/trace.go:171","msg":"trace[1314510384] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"114.85634ms","start":"2026-05-10T00:48:23.935611Z","end":"2026-05-10T00:48:24.050467Z","steps":["trace[1314510384] 'process raft request' (duration: 61.128028ms)","trace[1314510384] 'compare' (duration: 53.484101ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:48:24.050533Z","caller":"traceutil/trace.go:171","msg":"trace[371231155] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"114.674769ms","start":"2026-05-10T00:48:23.935834Z","end":"2026-05-10T00:48:24.050508Z","steps":["trace[371231155] 'process raft request' (duration: 114.537308ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:24.050677Z","caller":"traceutil/trace.go:171","msg":"trace[913705272] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"113.503432ms","start":"2026-05-10T00:48:23.937152Z","end":"2026-05-10T00:48:24.050655Z","steps":["trace[913705272] 'process raft request' (duration: 113.277301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:24.050784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.686536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"warn","ts":"2026-05-10T00:48:24.050827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.912545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-10T00:48:24.050853Z","caller":"traceutil/trace.go:171","msg":"trace[73652698] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:334; }","duration":"112.764557ms","start":"2026-05-10T00:48:23.93807Z","end":"2026-05-10T00:48:24.050835Z","steps":["trace[73652698] 'agreement among raft nodes before linearized reading' (duration: 112.624766ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:24.050866Z","caller":"traceutil/trace.go:171","msg":"trace[1030969708] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:334; }","duration":"116.037356ms","start":"2026-05-10T00:48:23.934817Z","end":"2026-05-10T00:48:24.050854Z","steps":["trace[1030969708] 'agreement among raft nodes before linearized reading' (duration: 115.854995ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:24.301454Z","caller":"traceutil/trace.go:171","msg":"trace[21398928] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"242.870005ms","start":"2026-05-10T00:48:24.057508Z","end":"2026-05-10T00:48:24.300378Z","steps":["trace[21398928] 'process raft request' (duration: 242.714274ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:24.30213Z","caller":"traceutil/trace.go:171","msg":"trace[580532550] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"243.598108ms","start":"2026-05-10T00:48:24.058514Z","end":"2026-05-10T00:48:24.302112Z","steps":["trace[580532550] 'process raft request' (duration: 243.202866ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:24.302127Z","caller":"traceutil/trace.go:171","msg":"trace[1958310628] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"236.532407ms","start":"2026-05-10T00:48:24.065574Z","end":"2026-05-10T00:48:24.302107Z","steps":["trace[1958310628] 'process raft request' (duration: 236.264986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:24.672258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.084505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T00:48:24.672273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.415012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-05-10T00:48:24.672332Z","caller":"traceutil/trace.go:171","msg":"trace[521205078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:351; }","duration":"102.189935ms","start":"2026-05-10T00:48:24.570127Z","end":"2026-05-10T00:48:24.672317Z","steps":["trace[521205078] 'range keys from in-memory index tree' (duration: 102.023435ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:24.672346Z","caller":"traceutil/trace.go:171","msg":"trace[114861907] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:351; }","duration":"115.570912ms","start":"2026-05-10T00:48:24.556758Z","end":"2026-05-10T00:48:24.672329Z","steps":["trace[114861907] 'range keys from in-memory index tree' (duration: 115.298241ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:32.913676Z","caller":"traceutil/trace.go:171","msg":"trace[1615111315] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"106.065044ms","start":"2026-05-10T00:48:32.807593Z","end":"2026-05-10T00:48:32.913658Z","steps":["trace[1615111315] 'process raft request' (duration: 105.947957ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:34.161911Z","caller":"traceutil/trace.go:171","msg":"trace[1372602511] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"184.895859ms","start":"2026-05-10T00:48:33.976981Z","end":"2026-05-10T00:48:34.161877Z","steps":["trace[1372602511] 'process raft request' (duration: 152.343079ms)","trace[1372602511] 'compare' (duration: 32.310527ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:48:34.162109Z","caller":"traceutil/trace.go:171","msg":"trace[1728636893] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"184.546269ms","start":"2026-05-10T00:48:33.977536Z","end":"2026-05-10T00:48:34.162083Z","steps":["trace[1728636893] 'process raft request' (duration: 184.321617ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:34.162283Z","caller":"traceutil/trace.go:171","msg":"trace[1203559993] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"181.722046ms","start":"2026-05-10T00:48:33.980516Z","end":"2026-05-10T00:48:34.162238Z","steps":["trace[1203559993] 'process raft request' (duration: 181.44641ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:34.164148Z","caller":"traceutil/trace.go:171","msg":"trace[529444030] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"184.7497ms","start":"2026-05-10T00:48:33.977419Z","end":"2026-05-10T00:48:34.162169Z","steps":["trace[529444030] 'process raft request' (duration: 184.343548ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:46.277218Z","caller":"traceutil/trace.go:171","msg":"trace[1114541942] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"216.648901ms","start":"2026-05-10T00:48:46.060544Z","end":"2026-05-10T00:48:46.277193Z","steps":["trace[1114541942] 'process raft request' (duration: 215.999191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:46.738706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.89092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:48:46.738781Z","caller":"traceutil/trace.go:171","msg":"trace[1403010633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:475; }","duration":"168.996754ms","start":"2026-05-10T00:48:46.569766Z","end":"2026-05-10T00:48:46.738763Z","steps":["trace[1403010633] 'range keys from in-memory index tree' (duration: 168.832978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:46.738706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.732971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:48:46.739017Z","caller":"traceutil/trace.go:171","msg":"trace[1816395090] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:475; }","duration":"121.066586ms","start":"2026-05-10T00:48:46.617938Z","end":"2026-05-10T00:48:46.739005Z","steps":["trace[1816395090] 'range keys from in-memory index tree' (duration: 120.656427ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:47.100299Z","caller":"traceutil/trace.go:171","msg":"trace[1914538144] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"140.902799ms","start":"2026-05-10T00:48:46.959367Z","end":"2026-05-10T00:48:47.10027Z","steps":["trace[1914538144] 'process raft request' (duration: 140.798724ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:47.10595Z","caller":"traceutil/trace.go:171","msg":"trace[264968986] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"138.283025ms","start":"2026-05-10T00:48:46.967645Z","end":"2026-05-10T00:48:47.105928Z","steps":["trace[264968986] 'process raft request' (duration: 137.94993ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:47.106037Z","caller":"traceutil/trace.go:171","msg":"trace[789294349] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"136.318266ms","start":"2026-05-10T00:48:46.969682Z","end":"2026-05-10T00:48:47.106Z","steps":["trace[789294349] 'process raft request' (duration: 136.092905ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:47.10632Z","caller":"traceutil/trace.go:171","msg":"trace[599940417] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"134.249369ms","start":"2026-05-10T00:48:46.972054Z","end":"2026-05-10T00:48:47.106303Z","steps":["trace[599940417] 'process raft request' (duration: 133.751747ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:47.107115Z","caller":"traceutil/trace.go:171","msg":"trace[511933887] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"139.398215ms","start":"2026-05-10T00:48:46.967705Z","end":"2026-05-10T00:48:47.107103Z","steps":["trace[511933887] 'process raft request' (duration: 138.015933ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:47.108147Z","caller":"traceutil/trace.go:171","msg":"trace[83151084] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"132.334721ms","start":"2026-05-10T00:48:46.975801Z","end":"2026-05-10T00:48:47.108136Z","steps":["trace[83151084] 'process raft request' (duration: 130.030976ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:47.108249Z","caller":"traceutil/trace.go:171","msg":"trace[30863403] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"129.526642ms","start":"2026-05-10T00:48:46.978715Z","end":"2026-05-10T00:48:47.108241Z","steps":["trace[30863403] 'process raft request' (duration: 129.073871ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:47.108378Z","caller":"traceutil/trace.go:171","msg":"trace[508741457] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"120.358009ms","start":"2026-05-10T00:48:46.98801Z","end":"2026-05-10T00:48:47.108368Z","steps":["trace[508741457] 'process raft request' (duration: 119.834225ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:47.108581Z","caller":"traceutil/trace.go:171","msg":"trace[441555087] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"103.067142ms","start":"2026-05-10T00:48:47.005505Z","end":"2026-05-10T00:48:47.108572Z","steps":["trace[441555087] 'process raft request' (duration: 102.452914ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:47.108676Z","caller":"traceutil/trace.go:171","msg":"trace[702988499] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:495; }","duration":"109.054028ms","start":"2026-05-10T00:48:46.999612Z","end":"2026-05-10T00:48:47.108666Z","steps":["trace[702988499] 'read index received' (duration: 101.053094ms)","trace[702988499] 'applied index is now lower than readState.Index' (duration: 7.999264ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:48:47.108877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.248087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-10T00:48:47.108906Z","caller":"traceutil/trace.go:171","msg":"trace[661522635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:487; }","duration":"109.33978ms","start":"2026-05-10T00:48:46.999556Z","end":"2026-05-10T00:48:47.108896Z","steps":["trace[661522635] 'agreement among raft nodes before linearized reading' (duration: 109.136881ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:47.108177Z","caller":"traceutil/trace.go:171","msg":"trace[67487853] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"105.288455ms","start":"2026-05-10T00:48:47.002871Z","end":"2026-05-10T00:48:47.108159Z","steps":["trace[67487853] 'process raft request' (duration: 105.037404ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:47.108222Z","caller":"traceutil/trace.go:171","msg":"trace[819686434] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"132.005174ms","start":"2026-05-10T00:48:46.976206Z","end":"2026-05-10T00:48:47.108211Z","steps":["trace[819686434] 'process raft request' (duration: 131.451269ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:50.343063Z","caller":"traceutil/trace.go:171","msg":"trace[1357136163] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"219.624133ms","start":"2026-05-10T00:48:50.123415Z","end":"2026-05-10T00:48:50.343039Z","steps":["trace[1357136163] 'process raft request' (duration: 219.062608ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:48:54.660306Z","caller":"traceutil/trace.go:171","msg":"trace[1486346980] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"670.294249ms","start":"2026-05-10T00:48:53.989984Z","end":"2026-05-10T00:48:54.660279Z","steps":["trace[1486346980] 'process raft request' (duration: 670.09545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:54.660514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:53.989928Z","time spent":"670.476356ms","remote":"127.0.0.1:46652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:48:55.280749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.996815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:48:55.280838Z","caller":"traceutil/trace.go:171","msg":"trace[479208286] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:608; }","duration":"907.460835ms","start":"2026-05-10T00:48:54.373362Z","end":"2026-05-10T00:48:55.280823Z","steps":["trace[479208286] 'read index received' (duration: 286.685014ms)","trace[479208286] 'applied index is now lower than readState.Index' (duration: 620.774741ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:48:55.281056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.806488ms","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-10T00:48:55.281139Z","caller":"traceutil/trace.go:171","msg":"trace[1151759803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"880.928023ms","start":"2026-05-10T00:48:54.40019Z","end":"2026-05-10T00:48:55.281118Z","steps":["trace[1151759803] 'agreement among raft nodes before linearized reading' (duration: 880.742556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:55.281184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:54.400169Z","time spent":"881.002967ms","remote":"127.0.0.1:46756","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-10T00:48:55.281175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.387364ms","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-10T00:48:55.281279Z","caller":"traceutil/trace.go:171","msg":"trace[568999734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"705.53507ms","start":"2026-05-10T00:48:54.575726Z","end":"2026-05-10T00:48:55.281261Z","steps":["trace[568999734] 'agreement among raft nodes before linearized reading' (duration: 705.340822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:55.281196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.82744ms","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-10T00:48:55.281376Z","caller":"traceutil/trace.go:171","msg":"trace[1283210530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"908.009107ms","start":"2026-05-10T00:48:54.373355Z","end":"2026-05-10T00:48:55.281364Z","steps":["trace[1283210530] 'agreement among raft nodes before linearized reading' (duration: 907.537348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:55.28138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:54.575706Z","time spent":"705.657894ms","remote":"127.0.0.1:46756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-10T00:48:55.281718Z","caller":"traceutil/trace.go:171","msg":"trace[273416088] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.148752931s","start":"2026-05-10T00:48:54.13214Z","end":"2026-05-10T00:48:55.280892Z","steps":["trace[273416088] 'process raft request' (duration: 658.538427ms)","trace[273416088] 'compare' (duration: 489.643651ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:48:55.281499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:54.373266Z","time spent":"908.135663ms","remote":"127.0.0.1:46756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-10T00:48:55.281975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:54.132105Z","time spent":"1.149761882s","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:48:55.282342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.696361ms","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-10T00:48:55.282391Z","caller":"traceutil/trace.go:171","msg":"trace[1025015467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"117.825246ms","start":"2026-05-10T00:48:55.164554Z","end":"2026-05-10T00:48:55.282379Z","steps":["trace[1025015467] 'agreement among raft nodes before linearized reading' (duration: 116.437408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:57.570231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99988072s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-10T00:48:57.570329Z","caller":"traceutil/trace.go:171","msg":"trace[363927745] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000049486s","start":"2026-05-10T00:48:55.570259Z","end":"2026-05-10T00:48:57.570308Z","steps":["trace[363927745] 'agreement among raft nodes before linearized reading' (duration: 1.999917531s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:57.570385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:55.570239Z","time spent":"2.00013576s","remote":"127.0.0.1:46458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/10 00:48:57 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-10T00:48:59.570374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999814022s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-10T00:48:59.570456Z","caller":"traceutil/trace.go:171","msg":"trace[2102553230] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999970928s","start":"2026-05-10T00:48:57.570467Z","end":"2026-05-10T00:48:59.570438Z","steps":["trace[2102553230] 'agreement among raft nodes before linearized reading' (duration: 1.999868954s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:59.570515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:57.570445Z","time spent":"2.000060121s","remote":"127.0.0.1:46460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/10 00:48:59 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-10T00:48:59.910059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000159362s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-10T00:48:59.910461Z","caller":"traceutil/trace.go:171","msg":"trace[607163394] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000627721s","start":"2026-05-10T00:48:57.909791Z","end":"2026-05-10T00:48:59.910419Z","steps":["trace[607163394] 'agreement among raft nodes before linearized reading' (duration: 2.000192023s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:48:59.910594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:57.90977Z","time spent":"2.000806358s","remote":"127.0.0.1:46458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/10 00:48:59 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-10T00:49:00.285029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:55.286198Z","time spent":"4.998825413s","remote":"127.0.0.1:46756","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-05-10T00:49:00.285029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:55.286589Z","time spent":"4.998431227s","remote":"127.0.0.1:46756","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/05/10 00:49:00 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" 2026/05/10 00:49:00 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-10T00:49:00.727926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:55.93839Z","time spent":"4.789525921s","remote":"127.0.0.1:48646","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-10T00:49:00.728161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.810890406s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-05-10T00:49:00.730473Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.813266618s","expected-duration":"1s"} {"level":"info","ts":"2026-05-10T00:49:00.731103Z","caller":"traceutil/trace.go:171","msg":"trace[506854301] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"5.444428649s","start":"2026-05-10T00:48:55.286623Z","end":"2026-05-10T00:49:00.731052Z","steps":["trace[506854301] 'process raft request' (duration: 5.444288353s)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:49:00.731189Z","caller":"traceutil/trace.go:171","msg":"trace[62663043] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:610; }","duration":"5.160850243s","start":"2026-05-10T00:48:55.570321Z","end":"2026-05-10T00:49:00.731172Z","steps":["trace[62663043] 'read index received' (duration: 346.868976ms)","trace[62663043] 'applied index is now lower than readState.Index' (duration: 4.813980577s)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:49:00.731209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:55.28661Z","time spent":"5.444546084s","remote":"127.0.0.1:46756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:49:00.731278Z","caller":"traceutil/trace.go:171","msg":"trace[1718517818] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"5.444567524s","start":"2026-05-10T00:48:55.286703Z","end":"2026-05-10T00:49:00.73127Z","steps":["trace[1718517818] 'process raft request' (duration: 5.444294783s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:49:00.731364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:55.286694Z","time spent":"5.444610226s","remote":"127.0.0.1:46756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:49:00.731457Z","caller":"traceutil/trace.go:171","msg":"trace[1344994771] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"5.377112343s","start":"2026-05-10T00:48:55.354331Z","end":"2026-05-10T00:49:00.731443Z","steps":["trace[1344994771] 'process raft request' (duration: 5.376772919s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:49:00.731518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:55.354308Z","time spent":"5.377179335s","remote":"127.0.0.1:46722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T00:49:00.749678Z","caller":"traceutil/trace.go:171","msg":"trace[1185042884] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"1.175802161s","start":"2026-05-10T00:48:59.573843Z","end":"2026-05-10T00:49:00.749645Z","steps":["trace[1185042884] 'process raft request' (duration: 1.175720808s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:49:00.749723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171401717s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:49:00.749774Z","caller":"traceutil/trace.go:171","msg":"trace[1462751194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"1.171491189s","start":"2026-05-10T00:48:59.578271Z","end":"2026-05-10T00:49:00.749762Z","steps":["trace[1462751194] 'agreement among raft nodes before linearized reading' (duration: 1.171375965s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:49:00.749778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.768085571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-10T00:49:00.749824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:59.578264Z","time spent":"1.171552562s","remote":"127.0.0.1:46458","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-10T00:49:00.749813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:59.573822Z","time spent":"1.175934536s","remote":"127.0.0.1:46530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T00:49:00.749844Z","caller":"traceutil/trace.go:171","msg":"trace[1281784147] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:593; }","duration":"2.768205326s","start":"2026-05-10T00:48:57.981622Z","end":"2026-05-10T00:49:00.749827Z","steps":["trace[1281784147] 'agreement among raft nodes before linearized reading' (duration: 2.768029209s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:49:00.749888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:57.9816Z","time spent":"2.768277019s","remote":"127.0.0.1:46484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"info","ts":"2026-05-10T00:49:00.749711Z","caller":"traceutil/trace.go:171","msg":"trace[1456326660] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"4.346409121s","start":"2026-05-10T00:48:56.403274Z","end":"2026-05-10T00:49:00.749683Z","steps":["trace[1456326660] 'process raft request' (duration: 4.346187043s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:49:00.750115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:48:56.403248Z","time spent":"4.346793506s","remote":"127.0.0.1:46756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:49:21.128058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.817443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-10T00:49:21.128153Z","caller":"traceutil/trace.go:171","msg":"trace[1825005835] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:765; }","duration":"201.933856ms","start":"2026-05-10T00:49:20.926202Z","end":"2026-05-10T00:49:21.128136Z","steps":["trace[1825005835] 'count revisions from in-memory index tree' (duration: 201.706448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:49:21.128162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.767022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-10T00:49:21.128254Z","caller":"traceutil/trace.go:171","msg":"trace[1838568181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"304.909276ms","start":"2026-05-10T00:49:20.823322Z","end":"2026-05-10T00:49:21.128232Z","steps":["trace[1838568181] 'range keys from in-memory index tree' (duration: 304.602298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:49:21.128261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.988005ms","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-10T00:49:21.128306Z","caller":"traceutil/trace.go:171","msg":"trace[1976752561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"302.037096ms","start":"2026-05-10T00:49:20.826259Z","end":"2026-05-10T00:49:21.128296Z","steps":["trace[1976752561] 'range keys from in-memory index tree' (duration: 301.926183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:49:21.128312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:49:20.823303Z","time spent":"304.99767ms","remote":"127.0.0.1:46756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-10T00:49:21.128378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:49:20.82624Z","time spent":"302.083588ms","remote":"127.0.0.1:46756","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-10T00:49:21.128393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.132905ms","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-10T00:49:21.128468Z","caller":"traceutil/trace.go:171","msg":"trace[443993335] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:765; }","duration":"263.207568ms","start":"2026-05-10T00:49:20.86525Z","end":"2026-05-10T00:49:21.128458Z","steps":["trace[443993335] 'range keys from in-memory index tree' (duration: 263.125905ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:49:21.164772Z","caller":"traceutil/trace.go:171","msg":"trace[790437363] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"226.108583ms","start":"2026-05-10T00:49:20.938648Z","end":"2026-05-10T00:49:21.164757Z","steps":["trace[790437363] 'process raft request' (duration: 226.01746ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:49:22.037764Z","caller":"traceutil/trace.go:171","msg":"trace[361590209] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:797; }","duration":"1.047371896s","start":"2026-05-10T00:49:20.990368Z","end":"2026-05-10T00:49:22.03774Z","steps":["trace[361590209] 'read index received' (duration: 174.243955ms)","trace[361590209] 'applied index is now lower than readState.Index' (duration: 873.126851ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:49:22.037814Z","caller":"traceutil/trace.go:171","msg":"trace[1164147348] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"905.045943ms","start":"2026-05-10T00:49:21.132746Z","end":"2026-05-10T00:49:22.037792Z","steps":["trace[1164147348] 'process raft request' (duration: 904.933469ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:49:22.03787Z","caller":"traceutil/trace.go:171","msg":"trace[1269888694] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"905.052413ms","start":"2026-05-10T00:49:21.132809Z","end":"2026-05-10T00:49:22.037862Z","steps":["trace[1269888694] 'process raft request' (duration: 904.898798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:49:22.037966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:49:21.132802Z","time spent":"905.094654ms","remote":"127.0.0.1:46756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:49:22.037992Z","caller":"traceutil/trace.go:171","msg":"trace[1222682632] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"1.074716544s","start":"2026-05-10T00:49:20.963252Z","end":"2026-05-10T00:49:22.037969Z","steps":["trace[1222682632] 'process raft request' (duration: 1.073524828s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:49:22.038087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:49:20.963227Z","time spent":"1.074809436s","remote":"127.0.0.1:46756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:49:22.038242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047872292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-10T00:49:22.038172Z","caller":"traceutil/trace.go:171","msg":"trace[572790183] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"905.162676ms","start":"2026-05-10T00:49:21.132723Z","end":"2026-05-10T00:49:22.037886Z","steps":["trace[572790183] 'process raft request' (duration: 904.913879ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:49:22.038278Z","caller":"traceutil/trace.go:171","msg":"trace[252228323] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:770; }","duration":"1.047956954s","start":"2026-05-10T00:49:20.990311Z","end":"2026-05-10T00:49:22.038268Z","steps":["trace[252228323] 'agreement among raft nodes before linearized reading' (duration: 1.047854141s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:49:22.038442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:49:20.990284Z","time spent":"1.04814767s","remote":"127.0.0.1:47004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":75,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-10T00:49:22.038624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:49:21.132702Z","time spent":"905.64513ms","remote":"127.0.0.1:46722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:49:22.038392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:49:21.132732Z","time spent":"905.127706ms","remote":"127.0.0.1:46756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:49:22.119938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.022951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:49:22.120934Z","caller":"traceutil/trace.go:171","msg":"trace[432317886] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:771; }","duration":"730.089653ms","start":"2026-05-10T00:49:21.39083Z","end":"2026-05-10T00:49:22.12092Z","steps":["trace[432317886] 'agreement among raft nodes before linearized reading' (duration: 729.010901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:49:22.121031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:49:21.390809Z","time spent":"730.206767ms","remote":"127.0.0.1:46994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-10T00:49:22.120174Z","caller":"traceutil/trace.go:171","msg":"trace[1795972713] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"862.992901ms","start":"2026-05-10T00:49:21.257106Z","end":"2026-05-10T00:49:22.120099Z","steps":["trace[1795972713] 'process raft request' (duration: 862.611549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:49:22.120417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.254808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-10T00:49:22.121578Z","caller":"traceutil/trace.go:171","msg":"trace[874269798] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:771; }","duration":"341.516217ms","start":"2026-05-10T00:49:21.780048Z","end":"2026-05-10T00:49:22.121564Z","steps":["trace[874269798] 'agreement among raft nodes before linearized reading' (duration: 340.215688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:49:22.121675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:49:21.779986Z","time spent":"341.670862ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-05-10T00:49:22.12171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:49:21.257066Z","time spent":"864.160046ms","remote":"127.0.0.1:46756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:49:22.12087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.866606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:49:22.12195Z","caller":"traceutil/trace.go:171","msg":"trace[851999784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:771; }","duration":"550.133534ms","start":"2026-05-10T00:49:21.571805Z","end":"2026-05-10T00:49:22.121939Z","steps":["trace[851999784] 'agreement among raft nodes before linearized reading' (duration: 548.490845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:49:22.122012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:49:21.571778Z","time spent":"550.221757ms","remote":"127.0.0.1:46458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-10T00:50:24.534677Z","caller":"traceutil/trace.go:171","msg":"trace[1936825291] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"112.690072ms","start":"2026-05-10T00:50:24.42194Z","end":"2026-05-10T00:50:24.53463Z","steps":["trace[1936825291] 'process raft request' (duration: 112.470618ms)"],"step_count":1}