{"level":"warn","ts":"2026-07-24T21:25:09.04349Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T21:25:09.043607Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-07-24T21:25:09.043697Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T21:25:09.043714Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-07-24T21:25:09.043782Z","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-07-24T21:25:09.044572Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-07-24T21:25:09.04471Z","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.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-07-24T21:25:09.050748Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.528713ms"} {"level":"info","ts":"2026-07-24T21:25:09.057652Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-07-24T21:25:09.057798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T21:25:09.057888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-07-24T21:25:09.057946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-24T21:25:09.058008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-07-24T21:25:09.05811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-07-24T21:25:09.06503Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T21:25:09.066979Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T21:25:09.06832Z","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-07-24T21:25:09.070324Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-24T21:25:09.070841Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-24T21:25:09.070877Z","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-07-24T21:25:09.071039Z","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-07-24T21:25:09.071067Z","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-07-24T21:25:09.072752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-07-24T21:25:09.073011Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-07-24T21:25:09.075114Z","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-07-24T21:25:09.075213Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-07-24T21:25:09.075361Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-07-24T21:25:09.075813Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-24T21:25:09.075888Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T21:25:10.059206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T21:25:10.059264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T21:25:10.0593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-07-24T21:25:10.05932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-07-24T21:25:10.059331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-07-24T21:25:10.059353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-07-24T21:25:10.059367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-07-24T21:25:10.18274Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T21:25:10.201309Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-07-24T21:25:10.201373Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T21:25:10.201356Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T21:25:10.2017Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T21:25:10.201827Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T21:25:10.201823Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T21:25:10.201911Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T21:25:10.201877Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T21:25:10.205131Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-07-24T21:25:10.206084Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-24T21:25:13.345326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.392368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:25:13.345748Z","caller":"traceutil/trace.go:171","msg":"trace[1654553115] transaction","detail":"{read_only:false; response_revision:187; number_of_response:1; }","duration":"488.23991ms","start":"2026-07-24T21:25:12.857468Z","end":"2026-07-24T21:25:13.345708Z","steps":["trace[1654553115] 'process raft request' (duration: 382.743987ms)","trace[1654553115] 'compare' (duration: 104.163149ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:25:13.345945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:12.857453Z","time spent":"488.388329ms","remote":"127.0.0.1:57430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":588,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T21:25:13.349014Z","caller":"traceutil/trace.go:171","msg":"trace[175904705] transaction","detail":"{read_only:false; response_revision:189; number_of_response:1; }","duration":"163.414361ms","start":"2026-07-24T21:25:13.185574Z","end":"2026-07-24T21:25:13.348988Z","steps":["trace[175904705] 'process raft request' (duration: 163.349441ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:25:13.349134Z","caller":"traceutil/trace.go:171","msg":"trace[1157262796] transaction","detail":"{read_only:false; response_revision:188; number_of_response:1; }","duration":"307.985989ms","start":"2026-07-24T21:25:13.04107Z","end":"2026-07-24T21:25:13.349056Z","steps":["trace[1157262796] 'process raft request' (duration: 307.736451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:13.349485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:13.041025Z","time spent":"308.299338ms","remote":"127.0.0.1:57274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T21:25:14.762428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.177960545s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:25:14.762656Z","caller":"traceutil/trace.go:171","msg":"trace[289107354] transaction","detail":"{read_only:false; response_revision:191; number_of_response:1; }","duration":"1.4018291s","start":"2026-07-24T21:25:13.360727Z","end":"2026-07-24T21:25:14.762556Z","steps":["trace[289107354] 'process raft request' (duration: 223.667626ms)","trace[289107354] 'compare' (duration: 1.177811825s)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:25:14.762533Z","caller":"traceutil/trace.go:171","msg":"trace[1806174497] linearizableReadLoop","detail":"{readStateIndex:196; appliedIndex:195; }","duration":"1.220566194s","start":"2026-07-24T21:25:13.541949Z","end":"2026-07-24T21:25:14.762515Z","steps":["trace[1806174497] 'read index received' (duration: 42.393231ms)","trace[1806174497] 'applied index is now lower than readState.Index' (duration: 1.178171853s)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:25:14.762876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220890382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:25:14.763103Z","caller":"traceutil/trace.go:171","msg":"trace[386704879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:191; }","duration":"1.221205781s","start":"2026-07-24T21:25:13.541886Z","end":"2026-07-24T21:25:14.763092Z","steps":["trace[386704879] 'agreement among raft nodes before linearized reading' (duration: 1.220862102s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:14.763138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:13.541857Z","time spent":"1.221272001s","remote":"127.0.0.1:57080","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-07-24T21:25:14.763207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19593082s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:25:14.763274Z","caller":"traceutil/trace.go:171","msg":"trace[129798007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:191; }","duration":"1.19602375s","start":"2026-07-24T21:25:13.567234Z","end":"2026-07-24T21:25:14.763258Z","steps":["trace[129798007] 'agreement among raft nodes before linearized reading' (duration: 1.195911831s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:14.763332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:13.567223Z","time spent":"1.19609595s","remote":"127.0.0.1:57064","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-07-24T21:25:14.763516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.697193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:25:14.763558Z","caller":"traceutil/trace.go:171","msg":"trace[1262639016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:191; }","duration":"415.775073ms","start":"2026-07-24T21:25:14.347773Z","end":"2026-07-24T21:25:14.763548Z","steps":["trace[1262639016] 'agreement among raft nodes before linearized reading' (duration: 415.717443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:14.763591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:14.347751Z","time spent":"415.834413ms","remote":"127.0.0.1:57368","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":"warn","ts":"2026-07-24T21:25:14.763609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.957257ms","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-07-24T21:25:14.764502Z","caller":"traceutil/trace.go:171","msg":"trace[1640387245] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:191; }","duration":"733.649665ms","start":"2026-07-24T21:25:14.030559Z","end":"2026-07-24T21:25:14.764209Z","steps":["trace[1640387245] 'agreement among raft nodes before linearized reading' (duration: 732.951627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:14.764562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:14.03054Z","time spent":"734.007303ms","remote":"127.0.0.1:57368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-24T21:25:14.763866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:13.360718Z","time spent":"1.402260848s","remote":"127.0.0.1:57430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":752,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T21:25:15.269403Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972054921397185,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T21:25:16.01156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.00903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:25:16.011771Z","caller":"traceutil/trace.go:171","msg":"trace[2098069747] linearizableReadLoop","detail":"{readStateIndex:198; appliedIndex:196; }","duration":"1.242995146s","start":"2026-07-24T21:25:14.768759Z","end":"2026-07-24T21:25:16.011754Z","steps":["trace[2098069747] 'read index received' (duration: 596.723725ms)","trace[2098069747] 'applied index is now lower than readState.Index' (duration: 646.270351ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:25:16.011877Z","caller":"traceutil/trace.go:171","msg":"trace[654672338] transaction","detail":"{read_only:false; response_revision:193; number_of_response:1; }","duration":"1.243757373s","start":"2026-07-24T21:25:14.768108Z","end":"2026-07-24T21:25:16.011865Z","steps":["trace[654672338] 'process raft request' (duration: 1.243554614s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:16.011939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:14.768089Z","time spent":"1.243821643s","remote":"127.0.0.1:57368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T21:25:16.012118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243347325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:25:16.012158Z","caller":"traceutil/trace.go:171","msg":"trace[362550715] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller; range_end:; response_count:0; response_revision:193; }","duration":"1.243416635s","start":"2026-07-24T21:25:14.768727Z","end":"2026-07-24T21:25:16.012144Z","steps":["trace[362550715] 'agreement among raft nodes before linearized reading' (duration: 1.243331495s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:16.012191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:14.768717Z","time spent":"1.243464775s","remote":"127.0.0.1:57430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller\" "} {"level":"info","ts":"2026-07-24T21:25:16.012065Z","caller":"traceutil/trace.go:171","msg":"trace[706352689] transaction","detail":"{read_only:false; response_revision:192; number_of_response:1; }","duration":"1.244214452s","start":"2026-07-24T21:25:14.767738Z","end":"2026-07-24T21:25:16.011952Z","steps":["trace[706352689] 'process raft request' (duration: 597.733582ms)","trace[706352689] 'compare' (duration: 645.200263ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:25:16.012378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.437825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:25:16.012413Z","caller":"traceutil/trace.go:171","msg":"trace[1450603265] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:193; }","duration":"405.506794ms","start":"2026-07-24T21:25:15.606898Z","end":"2026-07-24T21:25:16.012405Z","steps":["trace[1450603265] 'agreement among raft nodes before linearized reading' (duration: 405.454654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:16.012441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:15.606869Z","time spent":"405.565524ms","remote":"127.0.0.1:57368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T21:25:16.012564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.596158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:25:16.012597Z","caller":"traceutil/trace.go:171","msg":"trace[1400448622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:193; }","duration":"235.649458ms","start":"2026-07-24T21:25:15.776939Z","end":"2026-07-24T21:25:16.012589Z","steps":["trace[1400448622] 'agreement among raft nodes before linearized reading' (duration: 235.605378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:16.012673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.154901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:25:16.012703Z","caller":"traceutil/trace.go:171","msg":"trace[1629954806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:193; }","duration":"245.221241ms","start":"2026-07-24T21:25:15.767475Z","end":"2026-07-24T21:25:16.012696Z","steps":["trace[1629954806] 'agreement among raft nodes before linearized reading' (duration: 245.181781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:16.0128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:14.76772Z","time spent":"1.24464727s","remote":"127.0.0.1:57368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T21:25:27.252767Z","caller":"traceutil/trace.go:171","msg":"trace[1004016592] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"113.008876ms","start":"2026-07-24T21:25:27.139729Z","end":"2026-07-24T21:25:27.252737Z","steps":["trace[1004016592] 'process raft request' (duration: 112.828045ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:25:34.319371Z","caller":"traceutil/trace.go:171","msg":"trace[92101548] linearizableReadLoop","detail":"{readStateIndex:448; appliedIndex:446; }","duration":"116.749221ms","start":"2026-07-24T21:25:34.202598Z","end":"2026-07-24T21:25:34.319347Z","steps":["trace[92101548] 'read index received' (duration: 75.779419ms)","trace[92101548] 'applied index is now lower than readState.Index' (duration: 40.968912ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:25:34.319478Z","caller":"traceutil/trace.go:171","msg":"trace[17299797] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"117.052915ms","start":"2026-07-24T21:25:34.202389Z","end":"2026-07-24T21:25:34.319442Z","steps":["trace[17299797] 'process raft request' (duration: 116.851572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:34.319582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.955944ms","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-07-24T21:25:34.31965Z","caller":"traceutil/trace.go:171","msg":"trace[674816208] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:432; }","duration":"117.048155ms","start":"2026-07-24T21:25:34.202582Z","end":"2026-07-24T21:25:34.31963Z","steps":["trace[674816208] 'agreement among raft nodes before linearized reading' (duration: 116.904773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:34.540042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.094432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:25:34.540392Z","caller":"traceutil/trace.go:171","msg":"trace[313249435] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"214.52921ms","start":"2026-07-24T21:25:34.325816Z","end":"2026-07-24T21:25:34.540345Z","steps":["trace[313249435] 'process raft request' (duration: 214.425899ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:25:34.540392Z","caller":"traceutil/trace.go:171","msg":"trace[637021903] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"215.492012ms","start":"2026-07-24T21:25:34.324864Z","end":"2026-07-24T21:25:34.540356Z","steps":["trace[637021903] 'process raft request' (duration: 37.845505ms)","trace[637021903] 'compare' (duration: 176.969139ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:25:36.89352Z","caller":"traceutil/trace.go:171","msg":"trace[228742735] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"213.473734ms","start":"2026-07-24T21:25:36.679829Z","end":"2026-07-24T21:25:36.893303Z","steps":["trace[228742735] 'process raft request' (duration: 213.047458ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:25:52.334952Z","caller":"traceutil/trace.go:171","msg":"trace[1335200421] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:522; }","duration":"183.586052ms","start":"2026-07-24T21:25:52.151337Z","end":"2026-07-24T21:25:52.334923Z","steps":["trace[1335200421] 'read index received' (duration: 110.426113ms)","trace[1335200421] 'applied index is now lower than readState.Index' (duration: 73.158259ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:25:52.335151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.758207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:25:52.335206Z","caller":"traceutil/trace.go:171","msg":"trace[1557813234] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:501; }","duration":"183.90391ms","start":"2026-07-24T21:25:52.151282Z","end":"2026-07-24T21:25:52.335186Z","steps":["trace[1557813234] 'agreement among raft nodes before linearized reading' (duration: 183.764897ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:25:52.494698Z","caller":"traceutil/trace.go:171","msg":"trace[163425184] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:523; }","duration":"137.859693ms","start":"2026-07-24T21:25:52.356816Z","end":"2026-07-24T21:25:52.494675Z","steps":["trace[163425184] 'read index received' (duration: 137.200998ms)","trace[163425184] 'applied index is now lower than readState.Index' (duration: 657.735µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:25:52.494902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.068968ms","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-07-24T21:25:52.494959Z","caller":"traceutil/trace.go:171","msg":"trace[215969049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:502; }","duration":"138.186051ms","start":"2026-07-24T21:25:52.356756Z","end":"2026-07-24T21:25:52.494942Z","steps":["trace[215969049] 'agreement among raft nodes before linearized reading' (duration: 138.042768ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:25:52.495133Z","caller":"traceutil/trace.go:171","msg":"trace[321050491] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"155.853546ms","start":"2026-07-24T21:25:52.338905Z","end":"2026-07-24T21:25:52.494758Z","steps":["trace[321050491] 'process raft request' (duration: 155.465017ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:25:54.775105Z","caller":"traceutil/trace.go:171","msg":"trace[2053811721] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"538.392998ms","start":"2026-07-24T21:25:54.236688Z","end":"2026-07-24T21:25:54.775081Z","steps":["trace[2053811721] 'process raft request' (duration: 538.240254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:54.775243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:54.236664Z","time spent":"538.518531ms","remote":"127.0.0.1:57128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T21:25:54.782858Z","caller":"traceutil/trace.go:171","msg":"trace[472495449] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"540.665343ms","start":"2026-07-24T21:25:54.242169Z","end":"2026-07-24T21:25:54.782834Z","steps":["trace[472495449] 'process raft request' (duration: 540.448677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:54.782979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:54.242149Z","time spent":"540.772565ms","remote":"127.0.0.1:57128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T21:25:54.783106Z","caller":"traceutil/trace.go:171","msg":"trace[856735596] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"534.062675ms","start":"2026-07-24T21:25:54.249034Z","end":"2026-07-24T21:25:54.783097Z","steps":["trace[856735596] 'process raft request' (duration: 533.745057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:54.783151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:54.249025Z","time spent":"534.104186ms","remote":"127.0.0.1:57128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T21:25:54.783163Z","caller":"traceutil/trace.go:171","msg":"trace[673510914] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"532.359394ms","start":"2026-07-24T21:25:54.250775Z","end":"2026-07-24T21:25:54.783134Z","steps":["trace[673510914] 'process raft request' (duration: 532.159339ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:25:54.78322Z","caller":"traceutil/trace.go:171","msg":"trace[676356874] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"537.465546ms","start":"2026-07-24T21:25:54.245722Z","end":"2026-07-24T21:25:54.783188Z","steps":["trace[676356874] 'process raft request' (duration: 537.011745ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:25:54.783327Z","caller":"traceutil/trace.go:171","msg":"trace[598536362] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"532.433325ms","start":"2026-07-24T21:25:54.250845Z","end":"2026-07-24T21:25:54.783278Z","steps":["trace[598536362] 'process raft request' (duration: 532.175259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:54.783609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:54.245714Z","time spent":"537.845345ms","remote":"127.0.0.1:57128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T21:25:54.783684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:54.250839Z","time spent":"532.821534ms","remote":"127.0.0.1:57128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T21:25:54.783472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:54.250758Z","time spent":"532.65127ms","remote":"127.0.0.1:57128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T21:25:54.784019Z","caller":"traceutil/trace.go:171","msg":"trace[1973080384] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"529.116926ms","start":"2026-07-24T21:25:54.254891Z","end":"2026-07-24T21:25:54.784008Z","steps":["trace[1973080384] 'process raft request' (duration: 528.165573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:54.784062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:54.254872Z","time spent":"529.172128ms","remote":"127.0.0.1:57128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T21:25:54.784134Z","caller":"traceutil/trace.go:171","msg":"trace[539387559] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"529.156567ms","start":"2026-07-24T21:25:54.254959Z","end":"2026-07-24T21:25:54.784116Z","steps":["trace[539387559] 'process raft request' (duration: 528.996794ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:25:54.784188Z","caller":"traceutil/trace.go:171","msg":"trace[662714092] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"510.314826ms","start":"2026-07-24T21:25:54.273863Z","end":"2026-07-24T21:25:54.784178Z","steps":["trace[662714092] 'process raft request' (duration: 510.154682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:54.784245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:54.273844Z","time spent":"510.369228ms","remote":"127.0.0.1:57128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T21:25:54.784296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:54.254954Z","time spent":"529.25369ms","remote":"127.0.0.1:57128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T21:25:54.784414Z","caller":"traceutil/trace.go:171","msg":"trace[1715756649] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"478.438512ms","start":"2026-07-24T21:25:54.305965Z","end":"2026-07-24T21:25:54.784404Z","steps":["trace[1715756649] 'process raft request' (duration: 478.181406ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:25:54.784458Z","caller":"traceutil/trace.go:171","msg":"trace[1984800151] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"493.762709ms","start":"2026-07-24T21:25:54.290685Z","end":"2026-07-24T21:25:54.784447Z","steps":["trace[1984800151] 'process raft request' (duration: 493.407561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:54.784466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:54.305943Z","time spent":"478.502773ms","remote":"127.0.0.1:57128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T21:25:54.784511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:54.290646Z","time spent":"493.843031ms","remote":"127.0.0.1:57128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T21:25:54.784544Z","caller":"traceutil/trace.go:171","msg":"trace[1583282825] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:562; }","duration":"262.486008ms","start":"2026-07-24T21:25:54.522048Z","end":"2026-07-24T21:25:54.784535Z","steps":["trace[1583282825] 'read index received' (duration: 252.94834ms)","trace[1583282825] 'applied index is now lower than readState.Index' (duration: 9.536458ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:25:54.78461Z","caller":"traceutil/trace.go:171","msg":"trace[1932797302] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"375.649ms","start":"2026-07-24T21:25:54.408953Z","end":"2026-07-24T21:25:54.784602Z","steps":["trace[1932797302] 'process raft request' (duration: 375.259481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:54.784651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:54.408936Z","time spent":"375.694551ms","remote":"127.0.0.1:57128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T21:25:54.78561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.182555ms","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-07-24T21:25:54.785654Z","caller":"traceutil/trace.go:171","msg":"trace[1857546428] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:553; }","duration":"235.257756ms","start":"2026-07-24T21:25:54.550384Z","end":"2026-07-24T21:25:54.785642Z","steps":["trace[1857546428] 'agreement among raft nodes before linearized reading' (duration: 234.228171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:54.785771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.718088ms","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-07-24T21:25:54.785825Z","caller":"traceutil/trace.go:171","msg":"trace[1469190891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:553; }","duration":"263.80782ms","start":"2026-07-24T21:25:54.522008Z","end":"2026-07-24T21:25:54.785816Z","steps":["trace[1469190891] 'agreement among raft nodes before linearized reading' (duration: 262.738705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:54.78647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.998508ms","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-07-24T21:25:54.786508Z","caller":"traceutil/trace.go:171","msg":"trace[2018420476] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:553; }","duration":"121.070141ms","start":"2026-07-24T21:25:54.66543Z","end":"2026-07-24T21:25:54.7865Z","steps":["trace[2018420476] 'agreement among raft nodes before linearized reading' (duration: 119.324219ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:26:01.115753Z","caller":"traceutil/trace.go:171","msg":"trace[1004378679] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"441.312881ms","start":"2026-07-24T21:26:00.674415Z","end":"2026-07-24T21:26:01.115728Z","steps":["trace[1004378679] 'read index received' (duration: 439.567433ms)","trace[1004378679] 'applied index is now lower than readState.Index' (duration: 1.744397ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:26:01.115933Z","caller":"traceutil/trace.go:171","msg":"trace[2092310283] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"635.796274ms","start":"2026-07-24T21:26:00.480125Z","end":"2026-07-24T21:26:01.115921Z","steps":["trace[2092310283] 'process raft request' (duration: 634.036146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:01.116669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.021663ms","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-07-24T21:26:01.11683Z","caller":"traceutil/trace.go:171","msg":"trace[2144795177] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:647; }","duration":"278.201458ms","start":"2026-07-24T21:26:00.838585Z","end":"2026-07-24T21:26:01.116786Z","steps":["trace[2144795177] 'agreement among raft nodes before linearized reading' (duration: 277.362665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:01.116713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.514933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cgsm9\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-24T21:26:01.116932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.119569ms","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-07-24T21:26:01.116963Z","caller":"traceutil/trace.go:171","msg":"trace[1114176823] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cgsm9; range_end:; response_count:1; response_revision:647; }","duration":"442.746319ms","start":"2026-07-24T21:26:00.674175Z","end":"2026-07-24T21:26:01.116922Z","steps":["trace[1114176823] 'agreement among raft nodes before linearized reading' (duration: 442.39987ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:26:01.117011Z","caller":"traceutil/trace.go:171","msg":"trace[1708087787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:647; }","duration":"274.222812ms","start":"2026-07-24T21:26:00.842757Z","end":"2026-07-24T21:26:01.11698Z","steps":["trace[1708087787] 'agreement among raft nodes before linearized reading' (duration: 273.987225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:01.117007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:26:00.674074Z","time spent":"442.920014ms","remote":"127.0.0.1:57274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-cgsm9\" "} {"level":"warn","ts":"2026-07-24T21:26:01.117068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.874286ms","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-07-24T21:26:01.117161Z","caller":"traceutil/trace.go:171","msg":"trace[52227384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"272.997658ms","start":"2026-07-24T21:26:00.844118Z","end":"2026-07-24T21:26:01.117116Z","steps":["trace[52227384] 'agreement among raft nodes before linearized reading' (duration: 272.644489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:01.116669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:26:00.480095Z","time spent":"635.901646ms","remote":"127.0.0.1:57164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:26:01.116706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.933023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-24T21:26:01.117514Z","caller":"traceutil/trace.go:171","msg":"trace[1261277357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"271.724805ms","start":"2026-07-24T21:26:00.845747Z","end":"2026-07-24T21:26:01.117472Z","steps":["trace[1261277357] 'agreement among raft nodes before linearized reading' (duration: 270.242275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:01.709733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.459676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:26:01.709826Z","caller":"traceutil/trace.go:171","msg":"trace[309839510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"589.59613ms","start":"2026-07-24T21:26:01.120207Z","end":"2026-07-24T21:26:01.709803Z","steps":["trace[309839510] 'range keys from in-memory index tree' (duration: 589.303881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:01.709873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:26:01.120187Z","time spent":"589.676672ms","remote":"127.0.0.1:57064","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-07-24T21:26:02.288004Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.163662161s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T21:26:02.288476Z","caller":"traceutil/trace.go:171","msg":"trace[115917947] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.164261648s","start":"2026-07-24T21:26:01.124185Z","end":"2026-07-24T21:26:02.288446Z","steps":["trace[115917947] 'process raft request' (duration: 1.16400338s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:02.288654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:26:01.124158Z","time spent":"1.164420522s","remote":"127.0.0.1:57368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:26:02.612555Z","caller":"traceutil/trace.go:171","msg":"trace[2052645560] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.4874465s","start":"2026-07-24T21:26:01.125078Z","end":"2026-07-24T21:26:02.612525Z","steps":["trace[2052645560] 'process raft request' (duration: 1.487044908s)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:26:02.612596Z","caller":"traceutil/trace.go:171","msg":"trace[15840370] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"1.487309346s","start":"2026-07-24T21:26:01.125257Z","end":"2026-07-24T21:26:02.612566Z","steps":["trace[15840370] 'process raft request' (duration: 1.487147991s)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:26:02.61265Z","caller":"traceutil/trace.go:171","msg":"trace[1680498267] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"1.487291265s","start":"2026-07-24T21:26:01.12533Z","end":"2026-07-24T21:26:02.612621Z","steps":["trace[1680498267] 'process raft request' (duration: 1.487139041s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:02.612708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:26:01.125045Z","time spent":"1.487585563s","remote":"127.0.0.1:57368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:26:02.61275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:26:01.125247Z","time spent":"1.487415628s","remote":"127.0.0.1:57368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:26:02.612797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:26:01.125322Z","time spent":"1.487395168s","remote":"127.0.0.1:57368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:26:02.612882Z","caller":"traceutil/trace.go:171","msg":"trace[110553215] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"1.474449845s","start":"2026-07-24T21:26:01.138422Z","end":"2026-07-24T21:26:02.612872Z","steps":["trace[110553215] 'process raft request' (duration: 1.474107806s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:02.612932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:26:01.138403Z","time spent":"1.474502596s","remote":"127.0.0.1:57274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:26:02.613002Z","caller":"traceutil/trace.go:171","msg":"trace[1560011963] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"1.436051499s","start":"2026-07-24T21:26:01.176942Z","end":"2026-07-24T21:26:02.612994Z","steps":["trace[1560011963] 'process raft request' (duration: 1.435632317s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:02.613046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:26:01.176925Z","time spent":"1.43610151s","remote":"127.0.0.1:57538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T21:26:02.836726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.100307ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-24T21:26:02.836845Z","caller":"traceutil/trace.go:171","msg":"trace[1319676727] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:673; }","duration":"769.964716ms","start":"2026-07-24T21:26:02.06686Z","end":"2026-07-24T21:26:02.836825Z","steps":["trace[1319676727] 'read index received' (duration: 221.32878ms)","trace[1319676727] 'applied index is now lower than readState.Index' (duration: 548.634166ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:26:02.836975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.10499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:26:02.83706Z","caller":"traceutil/trace.go:171","msg":"trace[76453702] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"770.237133ms","start":"2026-07-24T21:26:02.066804Z","end":"2026-07-24T21:26:02.837041Z","steps":["trace[76453702] 'agreement among raft nodes before linearized reading' (duration: 770.10355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:02.837099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:26:02.066773Z","time spent":"770.317746ms","remote":"127.0.0.1:57080","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-07-24T21:26:03.337647Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972054921398128,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T21:26:03.599185Z","caller":"traceutil/trace.go:171","msg":"trace[1252352599] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"762.277061ms","start":"2026-07-24T21:26:02.836878Z","end":"2026-07-24T21:26:03.599155Z","steps":["trace[1252352599] 'read index received' (duration: 761.780537ms)","trace[1252352599] 'applied index is now lower than readState.Index' (duration: 490.374µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:26:03.599278Z","caller":"traceutil/trace.go:171","msg":"trace[715623117] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"845.827606ms","start":"2026-07-24T21:26:02.753417Z","end":"2026-07-24T21:26:03.599245Z","steps":["trace[715623117] 'process raft request' (duration: 845.381223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:03.599527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.15077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T21:26:03.599545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.113569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:26:03.599605Z","caller":"traceutil/trace.go:171","msg":"trace[1883722026] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:654; }","duration":"974.354765ms","start":"2026-07-24T21:26:02.625227Z","end":"2026-07-24T21:26:03.599582Z","steps":["trace[1883722026] 'agreement among raft nodes before linearized reading' (duration: 974.096428ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:26:03.599631Z","caller":"traceutil/trace.go:171","msg":"trace[1911610683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"761.241373ms","start":"2026-07-24T21:26:02.838368Z","end":"2026-07-24T21:26:03.599609Z","steps":["trace[1911610683] 'agreement among raft nodes before linearized reading' (duration: 761.092809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:03.599653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:26:02.62521Z","time spent":"974.429218ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-24T21:26:03.599667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:26:02.753395Z","time spent":"845.950489ms","remote":"127.0.0.1:57368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:26:03.599677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:26:02.838352Z","time spent":"761.317556ms","remote":"127.0.0.1:57064","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-07-24T21:26:19.967211Z","caller":"traceutil/trace.go:171","msg":"trace[1194665078] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"491.416856ms","start":"2026-07-24T21:26:19.475772Z","end":"2026-07-24T21:26:19.967189Z","steps":["trace[1194665078] 'process raft request' (duration: 491.269421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:19.967505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:26:19.475746Z","time spent":"491.53063ms","remote":"127.0.0.1:57368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:26:19.976462Z","caller":"traceutil/trace.go:171","msg":"trace[574398898] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"370.870559ms","start":"2026-07-24T21:26:19.605578Z","end":"2026-07-24T21:26:19.976449Z","steps":["trace[574398898] 'process raft request' (duration: 370.818367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:19.976551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:26:19.605565Z","time spent":"370.938251ms","remote":"127.0.0.1:57368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:26:19.976705Z","caller":"traceutil/trace.go:171","msg":"trace[1708452751] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"174.596248ms","start":"2026-07-24T21:26:19.802085Z","end":"2026-07-24T21:26:19.976681Z","steps":["trace[1708452751] 'process raft request' (duration: 174.333529ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:26:19.976473Z","caller":"traceutil/trace.go:171","msg":"trace[13831811] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"378.958675ms","start":"2026-07-24T21:26:19.597484Z","end":"2026-07-24T21:26:19.976443Z","steps":["trace[13831811] 'process raft request' (duration: 378.830501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:19.977278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:26:19.597461Z","time spent":"379.416671ms","remote":"127.0.0.1:57260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:28:03.452071Z","caller":"traceutil/trace.go:171","msg":"trace[1467809107] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"186.15825ms","start":"2026-07-24T21:28:03.265865Z","end":"2026-07-24T21:28:03.452023Z","steps":["trace[1467809107] 'process raft request' (duration: 166.498739ms)","trace[1467809107] 'compare' (duration: 19.417828ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:28:43.446705Z","caller":"traceutil/trace.go:171","msg":"trace[599730139] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"109.793657ms","start":"2026-07-24T21:28:43.336885Z","end":"2026-07-24T21:28:43.446679Z","steps":["trace[599730139] 'process raft request' (duration: 109.621868ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:28:47.139377Z","caller":"traceutil/trace.go:171","msg":"trace[1248980437] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"100.590199ms","start":"2026-07-24T21:28:47.038742Z","end":"2026-07-24T21:28:47.139332Z","steps":["trace[1248980437] 'process raft request' (duration: 100.333125ms)"],"step_count":1}