{"level":"warn","ts":"2026-04-27T20:58:36.84298Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T20:58:36.843067Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-04-27T20:58:36.843134Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T20:58:36.843145Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-27T20:58:36.843162Z","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-04-27T20:58:36.843797Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-04-27T20:58:36.843893Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-04-27T20:58:36.848079Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.934734ms"} {"level":"info","ts":"2026-04-27T20:58:36.854174Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-04-27T20:58:36.854269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T20:58:36.8543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-04-27T20:58:36.854329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T20:58:36.854341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-04-27T20:58:36.8544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-04-27T20:58:36.863469Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T20:58:36.866605Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T20:58:36.868788Z","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-04-27T20:58:36.871909Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T20:58:36.874103Z","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-04-27T20:58:36.874388Z","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-04-27T20:58:36.874402Z","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-04-27T20:58:36.875923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-04-27T20:58:36.876459Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-27T20:58:36.876549Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T20:58:36.877002Z","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-04-27T20:58:36.877181Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T20:58:36.877209Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T20:58:36.877308Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-27T20:58:36.877327Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-27T20:58:36.954758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T20:58:36.954814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T20:58:36.954836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-04-27T20:58:36.95485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-04-27T20:58:36.954861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-27T20:58:36.954873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-04-27T20:58:36.954883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-27T20:58:36.955764Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T20:58:36.95584Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T20:58:36.955974Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T20:58:36.95599Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T20:58:36.956212Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T20:58:36.956237Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T20:58:36.956551Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T20:58:36.956706Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T20:58:36.956766Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T20:58:36.958022Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-04-27T20:58:36.959716Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T20:58:53.169376Z","caller":"traceutil/trace.go:171","msg":"trace[1645048525] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"216.224157ms","start":"2026-04-27T20:58:52.953134Z","end":"2026-04-27T20:58:53.169358Z","steps":["trace[1645048525] 'process raft request' (duration: 216.128754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:53.59035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.797033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:58:53.590474Z","caller":"traceutil/trace.go:171","msg":"trace[1340779540] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:388; }","duration":"483.249378ms","start":"2026-04-27T20:58:53.107209Z","end":"2026-04-27T20:58:53.590458Z","steps":["trace[1340779540] 'read index received' (duration: 62.121669ms)","trace[1340779540] 'applied index is now lower than readState.Index' (duration: 421.126419ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:58:53.590559Z","caller":"traceutil/trace.go:171","msg":"trace[1312595092] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"637.188411ms","start":"2026-04-27T20:58:52.95336Z","end":"2026-04-27T20:58:53.590548Z","steps":["trace[1312595092] 'process raft request' (duration: 401.616489ms)","trace[1312595092] 'compare' (duration: 234.530766ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:58:53.590622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:52.953332Z","time spent":"637.254093ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:58:53.590812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.108918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-04-27T20:58:53.590898Z","caller":"traceutil/trace.go:171","msg":"trace[1032577079] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:376; }","duration":"219.224291ms","start":"2026-04-27T20:58:53.37164Z","end":"2026-04-27T20:58:53.590864Z","steps":["trace[1032577079] 'agreement among raft nodes before linearized reading' (duration: 219.019536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:53.591065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.830772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-27T20:58:53.59141Z","caller":"traceutil/trace.go:171","msg":"trace[1657120449] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:376; }","duration":"484.003476ms","start":"2026-04-27T20:58:53.107152Z","end":"2026-04-27T20:58:53.591156Z","steps":["trace[1657120449] 'agreement among raft nodes before linearized reading' (duration: 483.615037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:53.591488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:53.107121Z","time spent":"484.349415ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-27T20:58:53.769495Z","caller":"traceutil/trace.go:171","msg":"trace[1938458862] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"174.086859ms","start":"2026-04-27T20:58:53.595384Z","end":"2026-04-27T20:58:53.769471Z","steps":["trace[1938458862] 'process raft request' (duration: 173.948676ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:58:53.774102Z","caller":"traceutil/trace.go:171","msg":"trace[633686299] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"166.986041ms","start":"2026-04-27T20:58:53.6071Z","end":"2026-04-27T20:58:53.774086Z","steps":["trace[633686299] 'process raft request' (duration: 166.890299ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:59:07.797119Z","caller":"traceutil/trace.go:171","msg":"trace[1651039145] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:559; }","duration":"147.125532ms","start":"2026-04-27T20:59:07.649968Z","end":"2026-04-27T20:59:07.797094Z","steps":["trace[1651039145] 'read index received' (duration: 146.507456ms)","trace[1651039145] 'applied index is now lower than readState.Index' (duration: 616.965µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:59:07.797303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.327397ms","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-04-27T20:59:07.797365Z","caller":"traceutil/trace.go:171","msg":"trace[1391633900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"147.401789ms","start":"2026-04-27T20:59:07.649952Z","end":"2026-04-27T20:59:07.797354Z","steps":["trace[1391633900] 'agreement among raft nodes before linearized reading' (duration: 147.247035ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:59:07.797178Z","caller":"traceutil/trace.go:171","msg":"trace[1771550502] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"206.920453ms","start":"2026-04-27T20:59:07.590229Z","end":"2026-04-27T20:59:07.79715Z","steps":["trace[1771550502] 'process raft request' (duration: 206.568574ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:59:13.941461Z","caller":"traceutil/trace.go:171","msg":"trace[582560695] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"111.403626ms","start":"2026-04-27T20:59:13.830039Z","end":"2026-04-27T20:59:13.941443Z","steps":["trace[582560695] 'process raft request' (duration: 111.113369ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:59:14.082541Z","caller":"traceutil/trace.go:171","msg":"trace[1448218257] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"136.891126ms","start":"2026-04-27T20:59:13.945615Z","end":"2026-04-27T20:59:14.082506Z","steps":["trace[1448218257] 'process raft request' (duration: 44.087117ms)","trace[1448218257] 'compare' (duration: 92.643675ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:59:14.418109Z","caller":"traceutil/trace.go:171","msg":"trace[152528035] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"294.379938ms","start":"2026-04-27T20:59:14.123705Z","end":"2026-04-27T20:59:14.418085Z","steps":["trace[152528035] 'process raft request' (duration: 294.198773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:59:15.202413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.428002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:59:15.202467Z","caller":"traceutil/trace.go:171","msg":"trace[2147100601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"320.528215ms","start":"2026-04-27T20:59:14.881926Z","end":"2026-04-27T20:59:15.202455Z","steps":["trace[2147100601] 'range keys from in-memory index tree' (duration: 320.30442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:59:15.202489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:59:14.881901Z","time spent":"320.583136ms","remote":"127.0.0.1:33222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T20:59:15.202657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.128693ms","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-04-27T20:59:15.202728Z","caller":"traceutil/trace.go:171","msg":"trace[7222281] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"465.207275ms","start":"2026-04-27T20:59:14.737503Z","end":"2026-04-27T20:59:15.20271Z","steps":["trace[7222281] 'range keys from in-memory index tree' (duration: 464.949809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:59:15.20277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:59:14.737442Z","time spent":"465.319018ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T20:59:29.498621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.480613ms","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-04-27T20:59:29.498746Z","caller":"traceutil/trace.go:171","msg":"trace[2052787294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:737; }","duration":"108.656388ms","start":"2026-04-27T20:59:29.390065Z","end":"2026-04-27T20:59:29.498721Z","steps":["trace[2052787294] 'range keys from in-memory index tree' (duration: 108.250947ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:05.606043Z","caller":"traceutil/trace.go:171","msg":"trace[1111117754] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"241.007157ms","start":"2026-04-27T21:00:05.365012Z","end":"2026-04-27T21:00:05.606019Z","steps":["trace[1111117754] 'process raft request' (duration: 240.864373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:06.370053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.912736ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-27T21:00:06.370137Z","caller":"traceutil/trace.go:171","msg":"trace[695524597] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:998; }","duration":"872.262322ms","start":"2026-04-27T21:00:05.497859Z","end":"2026-04-27T21:00:06.370121Z","steps":["trace[695524597] 'read index received' (duration: 108.084392ms)","trace[695524597] 'applied index is now lower than readState.Index' (duration: 764.17669ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:00:06.370297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.436787ms","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-04-27T21:00:06.37032Z","caller":"traceutil/trace.go:171","msg":"trace[393130315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:965; }","duration":"872.496318ms","start":"2026-04-27T21:00:05.497814Z","end":"2026-04-27T21:00:06.370311Z","steps":["trace[393130315] 'agreement among raft nodes before linearized reading' (duration: 872.386055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:06.370343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:05.497797Z","time spent":"872.54053ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T21:00:06.370523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.722231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-27T21:00:06.370561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.173725ms","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-04-27T21:00:06.370617Z","caller":"traceutil/trace.go:171","msg":"trace[106907281] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:965; }","duration":"354.865564ms","start":"2026-04-27T21:00:06.015732Z","end":"2026-04-27T21:00:06.370598Z","steps":["trace[106907281] 'agreement among raft nodes before linearized reading' (duration: 354.69565ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:06.370645Z","caller":"traceutil/trace.go:171","msg":"trace[1938398164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:965; }","duration":"719.309949ms","start":"2026-04-27T21:00:05.651315Z","end":"2026-04-27T21:00:06.370625Z","steps":["trace[1938398164] 'agreement among raft nodes before linearized reading' (duration: 719.071493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:06.370673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:06.015716Z","time spent":"354.944057ms","remote":"127.0.0.1:33408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:00:06.370707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:05.651292Z","time spent":"719.405421ms","remote":"127.0.0.1:33506","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-04-27T21:00:06.370784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.088881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:00:06.370811Z","caller":"traceutil/trace.go:171","msg":"trace[1631427424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"489.148261ms","start":"2026-04-27T21:00:05.881655Z","end":"2026-04-27T21:00:06.370803Z","steps":["trace[1631427424] 'agreement among raft nodes before linearized reading' (duration: 489.1104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:06.37083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:05.881638Z","time spent":"489.187763ms","remote":"127.0.0.1:33222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T21:00:06.370991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.940942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:00:06.371034Z","caller":"traceutil/trace.go:171","msg":"trace[1747737770] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:965; }","duration":"510.016515ms","start":"2026-04-27T21:00:05.861005Z","end":"2026-04-27T21:00:06.371022Z","steps":["trace[1747737770] 'agreement among raft nodes before linearized reading' (duration: 509.945453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:06.371054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.627092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-27T21:00:06.371075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:05.86099Z","time spent":"510.074166ms","remote":"127.0.0.1:33380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-04-27T21:00:06.371204Z","caller":"traceutil/trace.go:171","msg":"trace[1805830681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:965; }","duration":"145.654483ms","start":"2026-04-27T21:00:06.22542Z","end":"2026-04-27T21:00:06.371074Z","steps":["trace[1805830681] 'agreement among raft nodes before linearized reading' (duration: 145.585721ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:27.15565Z","caller":"traceutil/trace.go:171","msg":"trace[1980782330] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"126.913855ms","start":"2026-04-27T21:00:27.028702Z","end":"2026-04-27T21:00:27.155616Z","steps":["trace[1980782330] 'process raft request' (duration: 126.71483ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:29.662746Z","caller":"traceutil/trace.go:171","msg":"trace[782929692] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"498.863596ms","start":"2026-04-27T21:00:29.163857Z","end":"2026-04-27T21:00:29.662721Z","steps":["trace[782929692] 'process raft request' (duration: 498.704302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:29.662913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:29.163833Z","time spent":"498.996618ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:00:29.893348Z","caller":"traceutil/trace.go:171","msg":"trace[571234575] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"134.605966ms","start":"2026-04-27T21:00:29.758703Z","end":"2026-04-27T21:00:29.893309Z","steps":["trace[571234575] 'process raft request' (duration: 121.98587ms)","trace[571234575] 'compare' (duration: 12.497963ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:00:42.595075Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095493327503278,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T21:00:43.096044Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095493327503278,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T21:00:43.323757Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.370504683s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T21:00:43.323995Z","caller":"traceutil/trace.go:171","msg":"trace[849046736] linearizableReadLoop","detail":"{readStateIndex:1179; appliedIndex:1178; }","duration":"1.229734233s","start":"2026-04-27T21:00:42.094247Z","end":"2026-04-27T21:00:43.323982Z","steps":["trace[849046736] 'read index received' (duration: 1.22960438s)","trace[849046736] 'applied index is now lower than readState.Index' (duration: 129.303µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:00:43.324093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229824615s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T21:00:43.324115Z","caller":"traceutil/trace.go:171","msg":"trace[1780566109] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1135; }","duration":"1.229889517s","start":"2026-04-27T21:00:42.094217Z","end":"2026-04-27T21:00:43.324107Z","steps":["trace[1780566109] 'agreement among raft nodes before linearized reading' (duration: 1.229812135s)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:43.324086Z","caller":"traceutil/trace.go:171","msg":"trace[1684400957] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"1.370921413s","start":"2026-04-27T21:00:41.953134Z","end":"2026-04-27T21:00:43.324056Z","steps":["trace[1684400957] 'process raft request' (duration: 1.370755429s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:43.324136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:42.094203Z","time spent":"1.229928458s","remote":"127.0.0.1:33702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:00:43.324186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.15866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-27T21:00:43.324226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:41.953103Z","time spent":"1.371053376s","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:00:43.32427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.500725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:00:43.324296Z","caller":"traceutil/trace.go:171","msg":"trace[1019027532] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1135; }","duration":"344.558057ms","start":"2026-04-27T21:00:42.979732Z","end":"2026-04-27T21:00:43.32429Z","steps":["trace[1019027532] 'agreement among raft nodes before linearized reading' (duration: 344.518976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:43.324318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:42.979717Z","time spent":"344.595837ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:00:43.324372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.04488ms","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-04-27T21:00:43.324389Z","caller":"traceutil/trace.go:171","msg":"trace[966869555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1135; }","duration":"583.082111ms","start":"2026-04-27T21:00:42.741302Z","end":"2026-04-27T21:00:43.324385Z","steps":["trace[966869555] 'agreement among raft nodes before linearized reading' (duration: 583.016949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:43.324402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:42.741296Z","time spent":"583.102722ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T21:00:43.324445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.366757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:00:43.324455Z","caller":"traceutil/trace.go:171","msg":"trace[1525068105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1135; }","duration":"441.404649ms","start":"2026-04-27T21:00:42.883047Z","end":"2026-04-27T21:00:43.324452Z","steps":["trace[1525068105] 'agreement among raft nodes before linearized reading' (duration: 441.387138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:43.324466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:42.883032Z","time spent":"441.431589ms","remote":"127.0.0.1:33222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T21:00:43.324232Z","caller":"traceutil/trace.go:171","msg":"trace[1128534732] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1135; }","duration":"730.237511ms","start":"2026-04-27T21:00:42.593983Z","end":"2026-04-27T21:00:43.324221Z","steps":["trace[1128534732] 'agreement among raft nodes before linearized reading' (duration: 730.079737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:43.324505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:42.59397Z","time spent":"730.526119ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T21:00:43.324557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.759358ms","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-04-27T21:00:43.324586Z","caller":"traceutil/trace.go:171","msg":"trace[514712212] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1135; }","duration":"583.81761ms","start":"2026-04-27T21:00:42.74076Z","end":"2026-04-27T21:00:43.324578Z","steps":["trace[514712212] 'agreement among raft nodes before linearized reading' (duration: 583.716567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:43.324611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:42.740742Z","time spent":"583.863041ms","remote":"127.0.0.1:33506","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":"warn","ts":"2026-04-27T21:00:43.324636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.748859ms","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-04-27T21:00:43.324651Z","caller":"traceutil/trace.go:171","msg":"trace[135197474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1135; }","duration":"679.79657ms","start":"2026-04-27T21:00:42.64485Z","end":"2026-04-27T21:00:43.324647Z","steps":["trace[135197474] 'agreement among raft nodes before linearized reading' (duration: 679.752039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:43.324663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:42.644835Z","time spent":"679.825761ms","remote":"127.0.0.1:33506","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":"info","ts":"2026-04-27T21:00:59.553223Z","caller":"traceutil/trace.go:171","msg":"trace[1583443473] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"386.226528ms","start":"2026-04-27T21:00:59.166978Z","end":"2026-04-27T21:00:59.553205Z","steps":["trace[1583443473] 'process raft request' (duration: 386.126195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:59.553332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:59.16696Z","time spent":"386.315699ms","remote":"127.0.0.1:33418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5456,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:00:59.760027Z","caller":"traceutil/trace.go:171","msg":"trace[1119113926] linearizableReadLoop","detail":"{readStateIndex:1253; appliedIndex:1252; }","duration":"318.68562ms","start":"2026-04-27T21:00:59.44132Z","end":"2026-04-27T21:00:59.760006Z","steps":["trace[1119113926] 'read index received' (duration: 111.995574ms)","trace[1119113926] 'applied index is now lower than readState.Index' (duration: 206.689125ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:00:59.760121Z","caller":"traceutil/trace.go:171","msg":"trace[1496235395] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"358.669947ms","start":"2026-04-27T21:00:59.401439Z","end":"2026-04-27T21:00:59.760109Z","steps":["trace[1496235395] 'process raft request' (duration: 332.904411ms)","trace[1496235395] 'compare' (duration: 25.397799ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:00:59.760201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:59.401423Z","time spent":"358.712382ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:00:59.760295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.848018ms","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-04-27T21:00:59.760358Z","caller":"traceutil/trace.go:171","msg":"trace[60950371] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1206; }","duration":"300.94103ms","start":"2026-04-27T21:00:59.459401Z","end":"2026-04-27T21:00:59.760343Z","steps":["trace[60950371] 'agreement among raft nodes before linearized reading' (duration: 300.769858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:59.760395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:59.45939Z","time spent":"300.998047ms","remote":"127.0.0.1:33506","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":"warn","ts":"2026-04-27T21:00:59.760505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.082824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-27T21:00:59.76055Z","caller":"traceutil/trace.go:171","msg":"trace[1253887241] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1206; }","duration":"201.159693ms","start":"2026-04-27T21:00:59.559383Z","end":"2026-04-27T21:00:59.760542Z","steps":["trace[1253887241] 'agreement among raft nodes before linearized reading' (duration: 201.073793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:59.760632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.801738ms","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-04-27T21:00:59.760665Z","caller":"traceutil/trace.go:171","msg":"trace[1992451075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1206; }","duration":"289.875727ms","start":"2026-04-27T21:00:59.470777Z","end":"2026-04-27T21:00:59.760653Z","steps":["trace[1992451075] 'agreement among raft nodes before linearized reading' (duration: 289.795727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:59.76074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.419004ms","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-04-27T21:00:59.760766Z","caller":"traceutil/trace.go:171","msg":"trace[295481973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1206; }","duration":"319.484602ms","start":"2026-04-27T21:00:59.441273Z","end":"2026-04-27T21:00:59.760758Z","steps":["trace[295481973] 'agreement among raft nodes before linearized reading' (duration: 319.389059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:59.760788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:59.441255Z","time spent":"319.523406ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T21:00:59.760876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.879706ms","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-04-27T21:00:59.760924Z","caller":"traceutil/trace.go:171","msg":"trace[571332028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1206; }","duration":"290.950114ms","start":"2026-04-27T21:00:59.469961Z","end":"2026-04-27T21:00:59.760911Z","steps":["trace[571332028] 'agreement among raft nodes before linearized reading' (duration: 290.810256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:00.60812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.09231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T21:01:00.60823Z","caller":"traceutil/trace.go:171","msg":"trace[278513157] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1261; }","duration":"193.937619ms","start":"2026-04-27T21:01:00.414273Z","end":"2026-04-27T21:01:00.608211Z","steps":["trace[278513157] 'read index received' (duration: 88.570105ms)","trace[278513157] 'applied index is now lower than readState.Index' (duration: 105.365824ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:01:00.608416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.134784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-27T21:01:00.608445Z","caller":"traceutil/trace.go:171","msg":"trace[1476496087] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:1215; }","duration":"194.203172ms","start":"2026-04-27T21:01:00.414231Z","end":"2026-04-27T21:01:00.608434Z","steps":["trace[1476496087] 'agreement among raft nodes before linearized reading' (duration: 194.02829ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:00.608712Z","caller":"traceutil/trace.go:171","msg":"trace[1801139248] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"282.485022ms","start":"2026-04-27T21:01:00.326213Z","end":"2026-04-27T21:01:00.608698Z","steps":["trace[1801139248] 'process raft request' (duration: 176.734769ms)","trace[1801139248] 'compare' (duration: 104.744606ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:01:00.897778Z","caller":"traceutil/trace.go:171","msg":"trace[38843073] linearizableReadLoop","detail":"{readStateIndex:1264; appliedIndex:1263; }","duration":"284.586437ms","start":"2026-04-27T21:01:00.613171Z","end":"2026-04-27T21:01:00.897757Z","steps":["trace[38843073] 'read index received' (duration: 203.961689ms)","trace[38843073] 'applied index is now lower than readState.Index' (duration: 80.623667ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:01:00.897915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.729255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-27T21:01:00.897951Z","caller":"traceutil/trace.go:171","msg":"trace[2058971282] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1216; }","duration":"284.798984ms","start":"2026-04-27T21:01:00.613143Z","end":"2026-04-27T21:01:00.897942Z","steps":["trace[2058971282] 'agreement among raft nodes before linearized reading' (duration: 284.719864ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:00.904673Z","caller":"traceutil/trace.go:171","msg":"trace[1560621694] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"288.513275ms","start":"2026-04-27T21:01:00.616128Z","end":"2026-04-27T21:01:00.904641Z","steps":["trace[1560621694] 'process raft request' (duration: 288.410802ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:00.904846Z","caller":"traceutil/trace.go:171","msg":"trace[973597962] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"290.407456ms","start":"2026-04-27T21:01:00.614425Z","end":"2026-04-27T21:01:00.904833Z","steps":["trace[973597962] 'process raft request' (duration: 289.962469ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:01.971465Z","caller":"traceutil/trace.go:171","msg":"trace[1911026017] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"146.59377ms","start":"2026-04-27T21:01:01.824853Z","end":"2026-04-27T21:01:01.971447Z","steps":["trace[1911026017] 'process raft request' (duration: 146.499218ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:01.976457Z","caller":"traceutil/trace.go:171","msg":"trace[189680117] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"151.143805ms","start":"2026-04-27T21:01:01.825288Z","end":"2026-04-27T21:01:01.976432Z","steps":["trace[189680117] 'process raft request' (duration: 150.981565ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:01.976718Z","caller":"traceutil/trace.go:171","msg":"trace[540306397] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"151.370475ms","start":"2026-04-27T21:01:01.825316Z","end":"2026-04-27T21:01:01.976686Z","steps":["trace[540306397] 'process raft request' (duration: 151.065506ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:12.788485Z","caller":"traceutil/trace.go:171","msg":"trace[1526019744] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1350; }","duration":"177.022929ms","start":"2026-04-27T21:01:12.611435Z","end":"2026-04-27T21:01:12.788457Z","steps":["trace[1526019744] 'read index received' (duration: 176.722662ms)","trace[1526019744] 'applied index is now lower than readState.Index' (duration: 299.027µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:01:12.788599Z","caller":"traceutil/trace.go:171","msg":"trace[101547135] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"262.491885ms","start":"2026-04-27T21:01:12.526071Z","end":"2026-04-27T21:01:12.788563Z","steps":["trace[101547135] 'process raft request' (duration: 262.186828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:12.788734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.267594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:01:12.789164Z","caller":"traceutil/trace.go:171","msg":"trace[1152148982] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1301; }","duration":"177.719474ms","start":"2026-04-27T21:01:12.611417Z","end":"2026-04-27T21:01:12.789136Z","steps":["trace[1152148982] 'agreement among raft nodes before linearized reading' (duration: 177.204413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:13.520833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.583818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-27T21:01:13.520978Z","caller":"traceutil/trace.go:171","msg":"trace[648290564] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1301; }","duration":"343.766843ms","start":"2026-04-27T21:01:13.177183Z","end":"2026-04-27T21:01:13.52095Z","steps":["trace[648290564] 'range keys from in-memory index tree' (duration: 343.401064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:13.521038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:13.177162Z","time spent":"343.858485ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-27T21:04:29.120792Z","caller":"traceutil/trace.go:171","msg":"trace[1564148752] transaction","detail":"{read_only:false; response_revision:2230; number_of_response:1; }","duration":"138.763268ms","start":"2026-04-27T21:04:28.981995Z","end":"2026-04-27T21:04:29.120758Z","steps":["trace[1564148752] 'process raft request' (duration: 129.239242ms)"],"step_count":1}