{"level":"warn","ts":"2026-04-11T17:41:33.01297Z","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-11T17:41:33.013067Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.86:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.86:2380","--initial-cluster=instance=https://199.19.213.86:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.86:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.86: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-11T17:41:33.013146Z","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-11T17:41:33.013155Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-04-11T17:41:33.013178Z","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-11T17:41:33.013954Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"]} {"level":"info","ts":"2026-04-11T17:41:33.014157Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.86: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-11T17:41:33.019069Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.613532ms"} {"level":"info","ts":"2026-04-11T17:41:33.032192Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"849a6ffb5e81c109","cluster-id":"d4d86b1dd8829199"} {"level":"info","ts":"2026-04-11T17:41:33.032278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=()"} {"level":"info","ts":"2026-04-11T17:41:33.032305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 0"} {"level":"info","ts":"2026-04-11T17:41:33.032318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 849a6ffb5e81c109 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-11T17:41:33.032326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 1"} {"level":"info","ts":"2026-04-11T17:41:33.032362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"warn","ts":"2026-04-11T17:41:33.043201Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-11T17:41:33.044358Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-11T17:41:33.045429Z","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-11T17:41:33.047151Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"849a6ffb5e81c109","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-11T17:41:33.047327Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"849a6ffb5e81c109","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-11T17:41:33.047478Z","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-11T17:41:33.047532Z","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-11T17:41:33.047541Z","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-11T17:41:33.048155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"info","ts":"2026-04-11T17:41:33.051474Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","added-peer-id":"849a6ffb5e81c109","added-peer-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-04-11T17:41:33.053558Z","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-11T17:41:33.053756Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"849a6ffb5e81c109","initial-advertise-peer-urls":["https://199.19.213.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-11T17:41:33.053793Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-11T17:41:33.053851Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-04-11T17:41:33.053867Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-04-11T17:41:33.233553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 is starting a new election at term 1"} {"level":"info","ts":"2026-04-11T17:41:33.233622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-11T17:41:33.233663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgPreVoteResp from 849a6ffb5e81c109 at term 1"} {"level":"info","ts":"2026-04-11T17:41:33.23369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became candidate at term 2"} {"level":"info","ts":"2026-04-11T17:41:33.233708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgVoteResp from 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-04-11T17:41:33.233728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became leader at term 2"} {"level":"info","ts":"2026-04-11T17:41:33.233753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 849a6ffb5e81c109 elected leader 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-04-11T17:41:33.235375Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"849a6ffb5e81c109","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.86:2379]}","request-path":"/0/members/849a6ffb5e81c109/attributes","cluster-id":"d4d86b1dd8829199","publish-timeout":"7s"} {"level":"info","ts":"2026-04-11T17:41:33.235554Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:41:33.235595Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:41:33.235794Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:41:33.237058Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:41:33.237188Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:41:33.237233Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:41:33.240591Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.86:2379"} {"level":"info","ts":"2026-04-11T17:41:33.240647Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-11T17:41:33.245333Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-11T17:41:33.245359Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-11T17:41:43.135398Z","caller":"traceutil/trace.go:171","msg":"trace[443485417] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"387.156132ms","start":"2026-04-11T17:41:42.748212Z","end":"2026-04-11T17:41:43.135368Z","steps":["trace[443485417] 'process raft request' (duration: 365.459308ms)","trace[443485417] 'compare' (duration: 21.563581ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:41:43.136171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:42.748177Z","time spent":"387.397895ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":205,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T17:41:43.731227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.674477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:41:43.731382Z","caller":"traceutil/trace.go:171","msg":"trace[1563926143] linearizableReadLoop","detail":"{readStateIndex:312; appliedIndex:311; }","duration":"533.184141ms","start":"2026-04-11T17:41:43.198178Z","end":"2026-04-11T17:41:43.731362Z","steps":["trace[1563926143] 'read index received' (duration: 133.970471ms)","trace[1563926143] 'applied index is now lower than readState.Index' (duration: 399.21217ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:41:43.731519Z","caller":"traceutil/trace.go:171","msg":"trace[1720998069] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"569.20444ms","start":"2026-04-11T17:41:43.162275Z","end":"2026-04-11T17:41:43.73148Z","steps":["trace[1720998069] 'process raft request' (duration: 169.959717ms)","trace[1720998069] 'compare' (duration: 398.542874ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:41:43.731677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.189987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-04-11T17:41:43.731866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.498304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-11T17:41:43.731915Z","caller":"traceutil/trace.go:171","msg":"trace[1949676583] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:300; }","duration":"292.568711ms","start":"2026-04-11T17:41:43.439332Z","end":"2026-04-11T17:41:43.731901Z","steps":["trace[1949676583] 'agreement among raft nodes before linearized reading' (duration: 292.472331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:43.732034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.487886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-11T17:41:43.732129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.375981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-11T17:41:43.732144Z","caller":"traceutil/trace.go:171","msg":"trace[1094412746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:300; }","duration":"341.670854ms","start":"2026-04-11T17:41:43.390448Z","end":"2026-04-11T17:41:43.732119Z","steps":["trace[1094412746] 'agreement among raft nodes before linearized reading' (duration: 341.386786ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:43.732181Z","caller":"traceutil/trace.go:171","msg":"trace[706373880] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:300; }","duration":"505.426416ms","start":"2026-04-11T17:41:43.226729Z","end":"2026-04-11T17:41:43.732156Z","steps":["trace[706373880] 'agreement among raft nodes before linearized reading' (duration: 505.379402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:43.73213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.883588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-11T17:41:43.732209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.390428Z","time spent":"341.770224ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-11T17:41:43.732225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.226719Z","time spent":"505.497903ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-04-11T17:41:43.732012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.472007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-11T17:41:43.732308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.141435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-11T17:41:43.732311Z","caller":"traceutil/trace.go:171","msg":"trace[1675005927] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:300; }","duration":"514.808061ms","start":"2026-04-11T17:41:43.217484Z","end":"2026-04-11T17:41:43.732292Z","steps":["trace[1675005927] 'agreement among raft nodes before linearized reading' (duration: 514.38874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:43.732341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.028636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-11T17:41:43.732348Z","caller":"traceutil/trace.go:171","msg":"trace[152969193] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:300; }","duration":"429.227023ms","start":"2026-04-11T17:41:43.303111Z","end":"2026-04-11T17:41:43.732338Z","steps":["trace[152969193] 'agreement among raft nodes before linearized reading' (duration: 429.176808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:43.73237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.217467Z","time spent":"514.884137ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-04-11T17:41:43.732395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.303079Z","time spent":"429.309461ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-04-11T17:41:43.732396Z","caller":"traceutil/trace.go:171","msg":"trace[717347237] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:300; }","duration":"525.128506ms","start":"2026-04-11T17:41:43.207255Z","end":"2026-04-11T17:41:43.732384Z","steps":["trace[717347237] 'agreement among raft nodes before linearized reading' (duration: 525.027736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:43.732449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.207233Z","time spent":"525.203563ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-04-11T17:41:43.732285Z","caller":"traceutil/trace.go:171","msg":"trace[723485676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:300; }","duration":"306.051954ms","start":"2026-04-11T17:41:43.426176Z","end":"2026-04-11T17:41:43.732228Z","steps":["trace[723485676] 'agreement among raft nodes before linearized reading' (duration: 305.605721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:43.732523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.42615Z","time spent":"306.355023ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-11T17:41:43.73209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.27457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-04-11T17:41:43.732621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.902231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-11T17:41:43.732687Z","caller":"traceutil/trace.go:171","msg":"trace[1315999107] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:300; }","duration":"486.888191ms","start":"2026-04-11T17:41:43.245788Z","end":"2026-04-11T17:41:43.732676Z","steps":["trace[1315999107] 'agreement among raft nodes before linearized reading' (duration: 486.277982ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:43.732245Z","caller":"traceutil/trace.go:171","msg":"trace[567837708] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:300; }","duration":"527.873673ms","start":"2026-04-11T17:41:43.204349Z","end":"2026-04-11T17:41:43.732223Z","steps":["trace[567837708] 'agreement among raft nodes before linearized reading' (duration: 527.132831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:43.73273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.245776Z","time spent":"486.944346ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-04-11T17:41:43.732751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.204231Z","time spent":"528.510855ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-04-11T17:41:43.731693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.162265Z","time spent":"569.331692ms","remote":"127.0.0.1:32786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:41:43.732254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.888872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-11T17:41:43.73293Z","caller":"traceutil/trace.go:171","msg":"trace[603432600] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:300; }","duration":"525.578669ms","start":"2026-04-11T17:41:43.207335Z","end":"2026-04-11T17:41:43.732913Z","steps":["trace[603432600] 'agreement among raft nodes before linearized reading' (duration: 524.883361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:43.732991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.207319Z","time spent":"525.655347ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":242,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-04-11T17:41:43.731672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.395161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-11T17:41:43.733111Z","caller":"traceutil/trace.go:171","msg":"trace[1633612173] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:300; }","duration":"534.949453ms","start":"2026-04-11T17:41:43.198148Z","end":"2026-04-11T17:41:43.733097Z","steps":["trace[1633612173] 'agreement among raft nodes before linearized reading' (duration: 533.322995ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:43.733114Z","caller":"traceutil/trace.go:171","msg":"trace[370500391] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:300; }","duration":"463.99125ms","start":"2026-04-11T17:41:43.268692Z","end":"2026-04-11T17:41:43.732684Z","steps":["trace[370500391] 'agreement among raft nodes before linearized reading' (duration: 463.07099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:43.733151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.198136Z","time spent":"535.004828ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-04-11T17:41:43.733157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.268678Z","time spent":"464.468497ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-04-11T17:41:44.114287Z","caller":"traceutil/trace.go:171","msg":"trace[1510890790] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:312; }","duration":"376.339315ms","start":"2026-04-11T17:41:43.737917Z","end":"2026-04-11T17:41:44.114256Z","steps":["trace[1510890790] 'read index received' (duration: 283.046895ms)","trace[1510890790] 'applied index is now lower than readState.Index' (duration: 93.29108ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:41:44.114369Z","caller":"traceutil/trace.go:171","msg":"trace[1249872494] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"377.388058ms","start":"2026-04-11T17:41:43.736957Z","end":"2026-04-11T17:41:44.114345Z","steps":["trace[1249872494] 'process raft request' (duration: 283.993138ms)","trace[1249872494] 'compare' (duration: 92.970168ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:41:44.114489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.554247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-04-11T17:41:44.114506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.736944Z","time spent":"377.477016ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:41:44.11455Z","caller":"traceutil/trace.go:171","msg":"trace[233838121] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:301; }","duration":"376.653716ms","start":"2026-04-11T17:41:43.737881Z","end":"2026-04-11T17:41:44.114534Z","steps":["trace[233838121] 'agreement among raft nodes before linearized reading' (duration: 376.536135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:44.114594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.737865Z","time spent":"376.716312ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":242,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-04-11T17:41:44.20909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.077117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-04-11T17:41:44.209155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.947334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-04-11T17:41:44.209169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.196253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-11T17:41:44.209208Z","caller":"traceutil/trace.go:171","msg":"trace[1080824] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:303; }","duration":"471.006219ms","start":"2026-04-11T17:41:43.738181Z","end":"2026-04-11T17:41:44.209187Z","steps":["trace[1080824] 'agreement among raft nodes before linearized reading' (duration: 470.919141ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:44.209249Z","caller":"traceutil/trace.go:171","msg":"trace[198398841] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:303; }","duration":"411.331906ms","start":"2026-04-11T17:41:43.7979Z","end":"2026-04-11T17:41:44.209232Z","steps":["trace[198398841] 'agreement among raft nodes before linearized reading' (duration: 411.16022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:44.209267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.882098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-11T17:41:44.209272Z","caller":"traceutil/trace.go:171","msg":"trace[1293992801] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:303; }","duration":"471.285997ms","start":"2026-04-11T17:41:43.737968Z","end":"2026-04-11T17:41:44.209254Z","steps":["trace[1293992801] 'agreement among raft nodes before linearized reading' (duration: 471.036752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:44.209291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.797878Z","time spent":"411.404773ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-04-11T17:41:44.209278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.738174Z","time spent":"471.090908ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-04-11T17:41:44.20932Z","caller":"traceutil/trace.go:171","msg":"trace[1483905428] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:303; }","duration":"462.938303ms","start":"2026-04-11T17:41:43.746365Z","end":"2026-04-11T17:41:44.209303Z","steps":["trace[1483905428] 'agreement among raft nodes before linearized reading' (duration: 462.863496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:44.209329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.737962Z","time spent":"471.354274ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":258,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-04-11T17:41:44.209365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.746358Z","time spent":"462.99955ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-04-11T17:41:44.209398Z","caller":"traceutil/trace.go:171","msg":"trace[1043804585] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"470.946694ms","start":"2026-04-11T17:41:43.738411Z","end":"2026-04-11T17:41:44.209358Z","steps":["trace[1043804585] 'process raft request' (duration: 470.405791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:44.209183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.934052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-11T17:41:44.209424Z","caller":"traceutil/trace.go:171","msg":"trace[2068915868] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"466.320643ms","start":"2026-04-11T17:41:43.743019Z","end":"2026-04-11T17:41:44.20934Z","steps":["trace[2068915868] 'process raft request' (duration: 465.919844ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:44.209497Z","caller":"traceutil/trace.go:171","msg":"trace[922890991] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:303; }","duration":"471.272306ms","start":"2026-04-11T17:41:43.738208Z","end":"2026-04-11T17:41:44.20948Z","steps":["trace[922890991] 'agreement among raft nodes before linearized reading' (duration: 470.888178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:44.209545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.660578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-11T17:41:44.209548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.738398Z","time spent":"471.068295ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:41:44.209601Z","caller":"traceutil/trace.go:171","msg":"trace[1042037600] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:303; }","duration":"361.756457ms","start":"2026-04-11T17:41:43.847831Z","end":"2026-04-11T17:41:44.209588Z","steps":["trace[1042037600] 'agreement among raft nodes before linearized reading' (duration: 361.667398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:44.209665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.847807Z","time spent":"361.845265ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-04-11T17:41:44.209563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.738201Z","time spent":"471.347972ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-04-11T17:41:44.209499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.468257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-11T17:41:44.209882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.8321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-11T17:41:44.209951Z","caller":"traceutil/trace.go:171","msg":"trace[1019693043] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:303; }","duration":"471.93806ms","start":"2026-04-11T17:41:43.737996Z","end":"2026-04-11T17:41:44.209934Z","steps":["trace[1019693043] 'agreement among raft nodes before linearized reading' (duration: 471.806737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:44.209999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.73799Z","time spent":"471.997326ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-04-11T17:41:44.209881Z","caller":"traceutil/trace.go:171","msg":"trace[1441028836] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:303; }","duration":"470.862536ms","start":"2026-04-11T17:41:43.739007Z","end":"2026-04-11T17:41:44.20987Z","steps":["trace[1441028836] 'agreement among raft nodes before linearized reading' (duration: 470.464387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:44.210265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.738998Z","time spent":"471.252454ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-04-11T17:41:44.21618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:43.743011Z","time spent":"466.551256ms","remote":"127.0.0.1:32962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:41:45.382762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.405548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:41:45.382886Z","caller":"traceutil/trace.go:171","msg":"trace[422844742] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"1.263218753s","start":"2026-04-11T17:41:44.119643Z","end":"2026-04-11T17:41:45.382861Z","steps":["trace[422844742] 'process raft request' (duration: 413.641967ms)","trace[422844742] 'compare' (duration: 849.205849ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:41:45.382957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:44.119624Z","time spent":"1.263301451s","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T17:41:45.619476Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.086117678s","expected-duration":"1s"} {"level":"info","ts":"2026-04-11T17:41:45.624771Z","caller":"traceutil/trace.go:171","msg":"trace[11305805] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"1.406038325s","start":"2026-04-11T17:41:44.218711Z","end":"2026-04-11T17:41:45.624749Z","steps":["trace[11305805] 'process raft request' (duration: 1.400989432s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:45.624937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:44.21868Z","time spent":"1.406156666s","remote":"127.0.0.1:32786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:41:45.62511Z","caller":"traceutil/trace.go:171","msg":"trace[2007025183] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"1.405251378s","start":"2026-04-11T17:41:44.219838Z","end":"2026-04-11T17:41:45.62509Z","steps":["trace[2007025183] 'process raft request' (duration: 1.404810505s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:45.625213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:44.21982Z","time spent":"1.405352688s","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:41:45.626211Z","caller":"traceutil/trace.go:171","msg":"trace[461248826] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"1.404391295s","start":"2026-04-11T17:41:44.2218Z","end":"2026-04-11T17:41:45.626192Z","steps":["trace[461248826] 'process raft request' (duration: 1.40291072s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:45.626445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:44.221792Z","time spent":"1.404474552s","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:41:45.626475Z","caller":"traceutil/trace.go:171","msg":"trace[1201658924] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"1.4030076s","start":"2026-04-11T17:41:44.223451Z","end":"2026-04-11T17:41:45.626458Z","steps":["trace[1201658924] 'process raft request' (duration: 1.402450845s)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:45.626556Z","caller":"traceutil/trace.go:171","msg":"trace[1514686758] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"1.403570554s","start":"2026-04-11T17:41:44.222954Z","end":"2026-04-11T17:41:45.626525Z","steps":["trace[1514686758] 'process raft request' (duration: 1.402850764s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:45.6266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:44.223444Z","time spent":"1.403070145s","remote":"127.0.0.1:32864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4354,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:41:45.626674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:44.222948Z","time spent":"1.403668914s","remote":"127.0.0.1:32852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:41:45.626839Z","caller":"traceutil/trace.go:171","msg":"trace[865678434] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"1.404495124s","start":"2026-04-11T17:41:44.222337Z","end":"2026-04-11T17:41:45.626832Z","steps":["trace[865678434] 'process raft request' (duration: 1.40342881s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:45.626873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:44.222331Z","time spent":"1.404527368s","remote":"127.0.0.1:33212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:41:45.626647Z","caller":"traceutil/trace.go:171","msg":"trace[1887582692] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"1.404505666s","start":"2026-04-11T17:41:44.222126Z","end":"2026-04-11T17:41:45.626632Z","steps":["trace[1887582692] 'process raft request' (duration: 1.403573494s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:45.627062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:44.222119Z","time spent":"1.40486474s","remote":"127.0.0.1:60982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:41:45.627131Z","caller":"traceutil/trace.go:171","msg":"trace[274306952] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"1.403881065s","start":"2026-04-11T17:41:44.223243Z","end":"2026-04-11T17:41:45.627124Z","steps":["trace[274306952] 'process raft request' (duration: 1.40260689s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:45.627169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:44.223235Z","time spent":"1.403919259s","remote":"127.0.0.1:33198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:41:45.627236Z","caller":"traceutil/trace.go:171","msg":"trace[2097242916] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"240.037114ms","start":"2026-04-11T17:41:45.38717Z","end":"2026-04-11T17:41:45.627207Z","steps":["trace[2097242916] 'process raft request' (duration: 239.969907ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:45.627332Z","caller":"traceutil/trace.go:171","msg":"trace[95236653] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"1.402493949s","start":"2026-04-11T17:41:44.22483Z","end":"2026-04-11T17:41:45.627324Z","steps":["trace[95236653] 'process raft request' (duration: 1.401254978s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:45.627383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:44.224823Z","time spent":"1.402527413s","remote":"127.0.0.1:32874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4777,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:41:45.627401Z","caller":"traceutil/trace.go:171","msg":"trace[205359393] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:315; }","duration":"1.289128111s","start":"2026-04-11T17:41:44.338256Z","end":"2026-04-11T17:41:45.627384Z","steps":["trace[205359393] 'read index received' (duration: 195.085089ms)","trace[205359393] 'applied index is now lower than readState.Index' (duration: 1.094041392s)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:41:45.627576Z","caller":"traceutil/trace.go:171","msg":"trace[1069596076] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"1.403271715s","start":"2026-04-11T17:41:44.224289Z","end":"2026-04-11T17:41:45.627561Z","steps":["trace[1069596076] 'process raft request' (duration: 1.401710233s)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:45.6277Z","caller":"traceutil/trace.go:171","msg":"trace[1181381319] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"265.354617ms","start":"2026-04-11T17:41:45.362316Z","end":"2026-04-11T17:41:45.627671Z","steps":["trace[1181381319] 'process raft request' (duration: 264.697973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:45.627709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:44.224283Z","time spent":"1.403334491s","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:41:45.627891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.289639852s","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-04-11T17:41:45.627902Z","caller":"traceutil/trace.go:171","msg":"trace[499166844] transaction","detail":"{read_only:false; number_of_response:1; response_revision:316; }","duration":"1.401318425s","start":"2026-04-11T17:41:44.226567Z","end":"2026-04-11T17:41:45.627885Z","steps":["trace[499166844] 'process raft request' (duration: 1.399601737s)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:45.627921Z","caller":"traceutil/trace.go:171","msg":"trace[1752822675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:318; }","duration":"1.289709588s","start":"2026-04-11T17:41:44.338205Z","end":"2026-04-11T17:41:45.627914Z","steps":["trace[1752822675] 'agreement among raft nodes before linearized reading' (duration: 1.289655713s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:45.627947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:44.338186Z","time spent":"1.289757063s","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-11T17:41:45.628025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:44.22654Z","time spent":"1.401403763s","remote":"127.0.0.1:32864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30,"response count":0,"response size":4403,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:41:45.628108Z","caller":"traceutil/trace.go:171","msg":"trace[101617075] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"1.404515956s","start":"2026-04-11T17:41:44.223586Z","end":"2026-04-11T17:41:45.628102Z","steps":["trace[101617075] 'process raft request' (duration: 1.402370717s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:45.628144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:44.223579Z","time spent":"1.404544479s","remote":"127.0.0.1:32962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:41:45.628426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.703412ms","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-11T17:41:45.628451Z","caller":"traceutil/trace.go:171","msg":"trace[521254140] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:318; }","duration":"986.770489ms","start":"2026-04-11T17:41:44.641675Z","end":"2026-04-11T17:41:45.628445Z","steps":["trace[521254140] 'agreement among raft nodes before linearized reading' (duration: 986.731855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:45.628469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:44.641654Z","time spent":"986.809213ms","remote":"127.0.0.1:32970","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-11T17:41:45.628538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.962753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-04-11T17:41:45.628637Z","caller":"traceutil/trace.go:171","msg":"trace[2072465833] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:318; }","duration":"319.145931ms","start":"2026-04-11T17:41:45.309474Z","end":"2026-04-11T17:41:45.62862Z","steps":["trace[2072465833] 'agreement among raft nodes before linearized reading' (duration: 318.951152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:45.628819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:45.309454Z","time spent":"319.225119ms","remote":"127.0.0.1:32874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4346,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-04-11T17:41:45.629816Z","caller":"traceutil/trace.go:171","msg":"trace[1826246820] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"1.402509821s","start":"2026-04-11T17:41:44.224987Z","end":"2026-04-11T17:41:45.627497Z","steps":["trace[1826246820] 'process raft request' (duration: 1.401143008s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:45.630378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:44.224981Z","time spent":"1.404889783s","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:41:45.885965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.886404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:41:45.886109Z","caller":"traceutil/trace.go:171","msg":"trace[1297042831] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"197.964926ms","start":"2026-04-11T17:41:45.688123Z","end":"2026-04-11T17:41:45.886088Z","steps":["trace[1297042831] 'process raft request' (duration: 96.872323ms)","trace[1297042831] 'compare' (duration: 100.733358ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:41:45.91032Z","caller":"traceutil/trace.go:171","msg":"trace[1847567032] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"180.383178ms","start":"2026-04-11T17:41:45.729901Z","end":"2026-04-11T17:41:45.910284Z","steps":["trace[1847567032] 'process raft request' (duration: 180.143915ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:45.911315Z","caller":"traceutil/trace.go:171","msg":"trace[1519185803] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"183.104234ms","start":"2026-04-11T17:41:45.727718Z","end":"2026-04-11T17:41:45.910822Z","steps":["trace[1519185803] 'process raft request' (duration: 182.204516ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:45.911803Z","caller":"traceutil/trace.go:171","msg":"trace[1441314039] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"181.529791ms","start":"2026-04-11T17:41:45.730232Z","end":"2026-04-11T17:41:45.911761Z","steps":["trace[1441314039] 'process raft request' (duration: 179.960777ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:45.912752Z","caller":"traceutil/trace.go:171","msg":"trace[74384186] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:342; }","duration":"180.793868ms","start":"2026-04-11T17:41:45.731896Z","end":"2026-04-11T17:41:45.91269Z","steps":["trace[74384186] 'read index received' (duration: 53.112798ms)","trace[74384186] 'applied index is now lower than readState.Index' (duration: 127.586681ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:41:45.912923Z","caller":"traceutil/trace.go:171","msg":"trace[1594490978] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"181.359583ms","start":"2026-04-11T17:41:45.731248Z","end":"2026-04-11T17:41:45.912608Z","steps":["trace[1594490978] 'process raft request' (duration: 179.58351ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:45.913569Z","caller":"traceutil/trace.go:171","msg":"trace[569057072] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"181.687416ms","start":"2026-04-11T17:41:45.731827Z","end":"2026-04-11T17:41:45.913514Z","steps":["trace[569057072] 'process raft request' (duration: 180.308321ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:45.913801Z","caller":"traceutil/trace.go:171","msg":"trace[1759877406] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"178.292684ms","start":"2026-04-11T17:41:45.735448Z","end":"2026-04-11T17:41:45.913741Z","steps":["trace[1759877406] 'process raft request' (duration: 177.005098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:45.915582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.646847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-04-11T17:41:45.915661Z","caller":"traceutil/trace.go:171","msg":"trace[83471596] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:336; }","duration":"183.748987ms","start":"2026-04-11T17:41:45.73189Z","end":"2026-04-11T17:41:45.915639Z","steps":["trace[83471596] 'agreement among raft nodes before linearized reading' (duration: 182.186124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:45.970051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.351139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-04-11T17:41:45.970163Z","caller":"traceutil/trace.go:171","msg":"trace[1374870435] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:337; }","duration":"208.554779ms","start":"2026-04-11T17:41:45.761582Z","end":"2026-04-11T17:41:45.970137Z","steps":["trace[1374870435] 'agreement among raft nodes before linearized reading' (duration: 208.1551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:46.415825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.695697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:41:46.416049Z","caller":"traceutil/trace.go:171","msg":"trace[2146571824] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"496.639375ms","start":"2026-04-11T17:41:45.919377Z","end":"2026-04-11T17:41:46.416016Z","steps":["trace[2146571824] 'process raft request' (duration: 318.685693ms)","trace[2146571824] 'compare' (duration: 177.501007ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:41:46.416164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:45.919361Z","time spent":"496.763236ms","remote":"127.0.0.1:60982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:41:46.416409Z","caller":"traceutil/trace.go:171","msg":"trace[1851857956] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"491.531556ms","start":"2026-04-11T17:41:45.924846Z","end":"2026-04-11T17:41:46.416378Z","steps":["trace[1851857956] 'process raft request' (duration: 491.082832ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:46.41649Z","caller":"traceutil/trace.go:171","msg":"trace[700890137] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:350; }","duration":"446.869484ms","start":"2026-04-11T17:41:45.969607Z","end":"2026-04-11T17:41:46.416476Z","steps":["trace[700890137] 'read index received' (duration: 268.527284ms)","trace[700890137] 'applied index is now lower than readState.Index' (duration: 178.341189ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:41:46.416564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:45.92483Z","time spent":"491.658418ms","remote":"127.0.0.1:60982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:41:46.416544Z","caller":"traceutil/trace.go:171","msg":"trace[716646169] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"491.291592ms","start":"2026-04-11T17:41:45.925221Z","end":"2026-04-11T17:41:46.416513Z","steps":["trace[716646169] 'process raft request' (duration: 491.136897ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:46.416715Z","caller":"traceutil/trace.go:171","msg":"trace[861274858] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"491.574669ms","start":"2026-04-11T17:41:45.925115Z","end":"2026-04-11T17:41:46.416689Z","steps":["trace[861274858] 'process raft request' (duration: 491.183641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:46.416708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:45.925216Z","time spent":"491.401103ms","remote":"127.0.0.1:33186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:41:46.416777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:45.925103Z","time spent":"491.634416ms","remote":"127.0.0.1:32874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T17:41:46.416862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.122489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-fl27x\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-04-11T17:41:46.416914Z","caller":"traceutil/trace.go:171","msg":"trace[1553246216] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-fl27x; range_end:; response_count:1; response_revision:342; }","duration":"498.200647ms","start":"2026-04-11T17:41:45.9187Z","end":"2026-04-11T17:41:46.416901Z","steps":["trace[1553246216] 'agreement among raft nodes before linearized reading' (duration: 498.000007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:46.416952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:45.918676Z","time spent":"498.268913ms","remote":"127.0.0.1:32874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":3463,"request content":"key:\"/registry/pods/kube-system/kube-proxy-fl27x\" "} {"level":"info","ts":"2026-04-11T17:41:46.416639Z","caller":"traceutil/trace.go:171","msg":"trace[192205341] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"489.538801ms","start":"2026-04-11T17:41:45.927072Z","end":"2026-04-11T17:41:46.416611Z","steps":["trace[192205341] 'process raft request' (duration: 489.349442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:46.417282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:45.927066Z","time spent":"490.158812ms","remote":"127.0.0.1:32874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:41:46.514056Z","caller":"traceutil/trace.go:171","msg":"trace[1836007559] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"536.349424ms","start":"2026-04-11T17:41:45.977685Z","end":"2026-04-11T17:41:46.514034Z","steps":["trace[1836007559] 'process raft request' (duration: 536.230543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:46.514243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:45.977659Z","time spent":"536.51297ms","remote":"127.0.0.1:32864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4410,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:41:46.514379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.618074ms","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-04-11T17:41:46.514461Z","caller":"traceutil/trace.go:171","msg":"trace[1626240018] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:343; }","duration":"396.753667ms","start":"2026-04-11T17:41:46.117689Z","end":"2026-04-11T17:41:46.514442Z","steps":["trace[1626240018] 'agreement among raft nodes before linearized reading' (duration: 396.596632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:46.514444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.477282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"warn","ts":"2026-04-11T17:41:46.514501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:46.117663Z","time spent":"396.830204ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-11T17:41:46.514549Z","caller":"traceutil/trace.go:171","msg":"trace[1802084574] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:343; }","duration":"440.659509ms","start":"2026-04-11T17:41:46.073866Z","end":"2026-04-11T17:41:46.514525Z","steps":["trace[1802084574] 'agreement among raft nodes before linearized reading' (duration: 440.423246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:46.514603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:46.073839Z","time spent":"440.756969ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":209,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-04-11T17:41:46.514897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.759162ms","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-04-11T17:41:46.515029Z","caller":"traceutil/trace.go:171","msg":"trace[910210849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:343; }","duration":"295.895105ms","start":"2026-04-11T17:41:46.219085Z","end":"2026-04-11T17:41:46.51498Z","steps":["trace[910210849] 'agreement among raft nodes before linearized reading' (duration: 295.632799ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:46.865667Z","caller":"traceutil/trace.go:171","msg":"trace[194433479] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"443.708729ms","start":"2026-04-11T17:41:46.421916Z","end":"2026-04-11T17:41:46.865624Z","steps":["trace[194433479] 'process raft request' (duration: 425.11362ms)","trace[194433479] 'compare' (duration: 18.307571ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:41:46.865897Z","caller":"traceutil/trace.go:171","msg":"trace[209161304] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"439.492577ms","start":"2026-04-11T17:41:46.426388Z","end":"2026-04-11T17:41:46.865881Z","steps":["trace[209161304] 'process raft request' (duration: 439.191307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:46.865973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:46.426378Z","time spent":"439.551023ms","remote":"127.0.0.1:33198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:41:46.865799Z","caller":"traceutil/trace.go:171","msg":"trace[1181765758] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"443.697818ms","start":"2026-04-11T17:41:46.422069Z","end":"2026-04-11T17:41:46.865767Z","steps":["trace[1181765758] 'process raft request' (duration: 443.460204ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:46.865991Z","caller":"traceutil/trace.go:171","msg":"trace[1345741146] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"431.569361ms","start":"2026-04-11T17:41:46.434346Z","end":"2026-04-11T17:41:46.865915Z","steps":["trace[1345741146] 'process raft request' (duration: 431.366751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:46.866262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:46.434333Z","time spent":"431.846659ms","remote":"127.0.0.1:32874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4447,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:41:46.866236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:46.422063Z","time spent":"444.045121ms","remote":"127.0.0.1:60982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:41:46.866406Z","caller":"traceutil/trace.go:171","msg":"trace[1818925737] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"431.996343ms","start":"2026-04-11T17:41:46.434396Z","end":"2026-04-11T17:41:46.866392Z","steps":["trace[1818925737] 'process raft request' (duration: 431.470481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:46.866591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:46.434392Z","time spent":"432.055899ms","remote":"127.0.0.1:32874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:41:46.866617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.892302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.86\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-11T17:41:46.866661Z","caller":"traceutil/trace.go:171","msg":"trace[342189057] range","detail":"{range_begin:/registry/masterleases/199.19.213.86; range_end:; response_count:1; response_revision:348; }","duration":"445.97394ms","start":"2026-04-11T17:41:46.420674Z","end":"2026-04-11T17:41:46.866648Z","steps":["trace[342189057] 'agreement among raft nodes before linearized reading' (duration: 445.893272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:46.86583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:46.421904Z","time spent":"443.845342ms","remote":"127.0.0.1:60982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T17:41:46.866702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:46.420653Z","time spent":"446.040726ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.86\" "} {"level":"info","ts":"2026-04-11T17:41:46.866498Z","caller":"traceutil/trace.go:171","msg":"trace[1537265761] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:356; }","duration":"352.035644ms","start":"2026-04-11T17:41:46.51424Z","end":"2026-04-11T17:41:46.866276Z","steps":["trace[1537265761] 'read index received' (duration: 332.805794ms)","trace[1537265761] 'applied index is now lower than readState.Index' (duration: 19.22823ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:41:46.875367Z","caller":"traceutil/trace.go:171","msg":"trace[1946857846] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"355.160939ms","start":"2026-04-11T17:41:46.520169Z","end":"2026-04-11T17:41:46.87533Z","steps":["trace[1946857846] 'process raft request' (duration: 354.875252ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:46.875629Z","caller":"traceutil/trace.go:171","msg":"trace[1486035458] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"355.428765ms","start":"2026-04-11T17:41:46.520184Z","end":"2026-04-11T17:41:46.875613Z","steps":["trace[1486035458] 'process raft request' (duration: 355.094812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:46.875683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:46.520154Z","time spent":"355.315624ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:41:46.875751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.817075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-fl27x\" ","response":"range_response_count:1 size:4462"} {"level":"info","ts":"2026-04-11T17:41:46.87581Z","caller":"traceutil/trace.go:171","msg":"trace[1414751197] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-fl27x; range_end:; response_count:1; response_revision:350; }","duration":"359.970339ms","start":"2026-04-11T17:41:46.515829Z","end":"2026-04-11T17:41:46.875799Z","steps":["trace[1414751197] 'agreement among raft nodes before linearized reading' (duration: 359.818845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:46.875863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:46.515812Z","time spent":"360.041677ms","remote":"127.0.0.1:32874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":4486,"request content":"key:\"/registry/pods/kube-system/kube-proxy-fl27x\" "} {"level":"warn","ts":"2026-04-11T17:41:46.87588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:46.520161Z","time spent":"355.533606ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:41:49.014614Z","caller":"traceutil/trace.go:171","msg":"trace[1261753868] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"130.770702ms","start":"2026-04-11T17:41:48.883819Z","end":"2026-04-11T17:41:49.01459Z","steps":["trace[1261753868] 'process raft request' (duration: 130.627643ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:49.285351Z","caller":"traceutil/trace.go:171","msg":"trace[1905104394] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"401.230118ms","start":"2026-04-11T17:41:48.884092Z","end":"2026-04-11T17:41:49.285322Z","steps":["trace[1905104394] 'process raft request' (duration: 318.709748ms)","trace[1905104394] 'compare' (duration: 82.363951ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:41:49.285516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:48.884078Z","time spent":"401.357038ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:41:50.448566Z","caller":"traceutil/trace.go:171","msg":"trace[2080005172] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"278.637393ms","start":"2026-04-11T17:41:50.169895Z","end":"2026-04-11T17:41:50.448532Z","steps":["trace[2080005172] 'process raft request' (duration: 278.499513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:50.806323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.231667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-04-11T17:41:50.806415Z","caller":"traceutil/trace.go:171","msg":"trace[1839689545] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:372; }","duration":"354.368407ms","start":"2026-04-11T17:41:50.452018Z","end":"2026-04-11T17:41:50.806386Z","steps":["trace[1839689545] 'range keys from in-memory index tree' (duration: 354.131424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:50.806638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:50.451997Z","time spent":"354.627028ms","remote":"127.0.0.1:32874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5350,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-04-11T17:41:51.315936Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822087032793929,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-11T17:41:51.342173Z","caller":"traceutil/trace.go:171","msg":"trace[17923353] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:387; }","duration":"526.86084ms","start":"2026-04-11T17:41:50.815287Z","end":"2026-04-11T17:41:51.342148Z","steps":["trace[17923353] 'read index received' (duration: 526.693921ms)","trace[17923353] 'applied index is now lower than readState.Index' (duration: 165.829µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:41:51.342283Z","caller":"traceutil/trace.go:171","msg":"trace[1624513427] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"528.151336ms","start":"2026-04-11T17:41:50.814099Z","end":"2026-04-11T17:41:51.34225Z","steps":["trace[1624513427] 'process raft request' (duration: 527.852897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:51.342493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.030856ms","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-04-11T17:41:51.342565Z","caller":"traceutil/trace.go:171","msg":"trace[1268894140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:373; }","duration":"324.149157ms","start":"2026-04-11T17:41:51.018404Z","end":"2026-04-11T17:41:51.342553Z","steps":["trace[1268894140] 'agreement among raft nodes before linearized reading' (duration: 324.021236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:51.342628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:51.018357Z","time spent":"324.25846ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-11T17:41:51.343077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.764216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:41:51.343169Z","caller":"traceutil/trace.go:171","msg":"trace[1630967764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:373; }","duration":"527.893617ms","start":"2026-04-11T17:41:50.815252Z","end":"2026-04-11T17:41:51.343145Z","steps":["trace[1630967764] 'agreement among raft nodes before linearized reading' (duration: 527.161998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:51.343388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:50.815236Z","time spent":"528.13399ms","remote":"127.0.0.1:60912","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-11T17:41:51.343155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:50.814075Z","time spent":"528.284507ms","remote":"127.0.0.1:32874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:41:51.832542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.164029ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-11T17:41:55.812703Z","caller":"traceutil/trace.go:171","msg":"trace[256945819] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"143.253653ms","start":"2026-04-11T17:41:55.669426Z","end":"2026-04-11T17:41:55.812679Z","steps":["trace[256945819] 'process raft request' (duration: 143.112572ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:55.848197Z","caller":"traceutil/trace.go:171","msg":"trace[1369428311] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"129.254224ms","start":"2026-04-11T17:41:55.718919Z","end":"2026-04-11T17:41:55.848174Z","steps":["trace[1369428311] 'process raft request' (duration: 129.085564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:01.933903Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822087032794036,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-11T17:42:02.16512Z","caller":"traceutil/trace.go:171","msg":"trace[20432111] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:445; }","duration":"731.584436ms","start":"2026-04-11T17:42:01.433504Z","end":"2026-04-11T17:42:02.165088Z","steps":["trace[20432111] 'read index received' (duration: 731.27843ms)","trace[20432111] 'applied index is now lower than readState.Index' (duration: 304.956µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:42:02.165183Z","caller":"traceutil/trace.go:171","msg":"trace[467610858] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"743.404579ms","start":"2026-04-11T17:42:01.421748Z","end":"2026-04-11T17:42:02.165152Z","steps":["trace[467610858] 'process raft request' (duration: 743.043796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:02.165305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:01.421727Z","time spent":"743.515774ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:42:02.165321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.801266ms","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-04-11T17:42:02.165359Z","caller":"traceutil/trace.go:171","msg":"trace[1990507071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:429; }","duration":"731.895761ms","start":"2026-04-11T17:42:01.433451Z","end":"2026-04-11T17:42:02.165347Z","steps":["trace[1990507071] 'agreement among raft nodes before linearized reading' (duration: 731.790916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:02.165394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:01.433432Z","time spent":"731.954698ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-11T17:42:02.604155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.451792ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-11T17:42:02.604375Z","caller":"traceutil/trace.go:171","msg":"trace[1078829471] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"435.736071ms","start":"2026-04-11T17:42:02.168623Z","end":"2026-04-11T17:42:02.604359Z","steps":["trace[1078829471] 'process raft request' (duration: 435.624496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:02.604469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:02.168607Z","time spent":"435.807139ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:42:02.604383Z","caller":"traceutil/trace.go:171","msg":"trace[1628434483] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:446; }","duration":"439.143926ms","start":"2026-04-11T17:42:02.165206Z","end":"2026-04-11T17:42:02.60435Z","steps":["trace[1628434483] 'read index received' (duration: 288.605588ms)","trace[1628434483] 'applied index is now lower than readState.Index' (duration: 150.535838ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:42:02.604771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.042431ms","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-11T17:42:02.604841Z","caller":"traceutil/trace.go:171","msg":"trace[1264111185] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:430; }","duration":"614.170525ms","start":"2026-04-11T17:42:01.990652Z","end":"2026-04-11T17:42:02.604823Z","steps":["trace[1264111185] 'agreement among raft nodes before linearized reading' (duration: 613.972374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:02.604885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:01.990628Z","time spent":"614.248461ms","remote":"127.0.0.1:32970","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-11T17:42:02.605064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.200255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:42:02.605121Z","caller":"traceutil/trace.go:171","msg":"trace[329764811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:430; }","duration":"371.435404ms","start":"2026-04-11T17:42:02.23367Z","end":"2026-04-11T17:42:02.605105Z","steps":["trace[329764811] 'agreement among raft nodes before linearized reading' (duration: 371.213534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:02.605167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:02.233653Z","time spent":"371.504692ms","remote":"127.0.0.1:60908","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-11T17:42:02.604897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.564319ms","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-04-11T17:42:02.605301Z","caller":"traceutil/trace.go:171","msg":"trace[1918914155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:430; }","duration":"636.110144ms","start":"2026-04-11T17:42:01.969175Z","end":"2026-04-11T17:42:02.605285Z","steps":["trace[1918914155] 'agreement among raft nodes before linearized reading' (duration: 635.578708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:02.605365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:01.969143Z","time spent":"636.20764ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-11T17:42:08.517751Z","caller":"traceutil/trace.go:171","msg":"trace[1787974095] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:461; }","duration":"303.106997ms","start":"2026-04-11T17:42:08.214603Z","end":"2026-04-11T17:42:08.51771Z","steps":["trace[1787974095] 'read index received' (duration: 302.783328ms)","trace[1787974095] 'applied index is now lower than readState.Index' (duration: 321.859µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:42:08.517926Z","caller":"traceutil/trace.go:171","msg":"trace[2010285534] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"316.555724ms","start":"2026-04-11T17:42:08.201361Z","end":"2026-04-11T17:42:08.517916Z","steps":["trace[2010285534] 'process raft request' (duration: 316.150288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:08.518167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:08.201341Z","time spent":"316.623841ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:42:08.518208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.549691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:42:08.518287Z","caller":"traceutil/trace.go:171","msg":"trace[28181957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:443; }","duration":"285.697726ms","start":"2026-04-11T17:42:08.232568Z","end":"2026-04-11T17:42:08.518266Z","steps":["trace[28181957] 'agreement among raft nodes before linearized reading' (duration: 285.535081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:08.518468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.811702ms","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-04-11T17:42:08.518648Z","caller":"traceutil/trace.go:171","msg":"trace[967622750] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:443; }","duration":"304.045354ms","start":"2026-04-11T17:42:08.214553Z","end":"2026-04-11T17:42:08.518599Z","steps":["trace[967622750] 'agreement among raft nodes before linearized reading' (duration: 303.729025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:08.518734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:08.214535Z","time spent":"304.181139ms","remote":"127.0.0.1:60998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-04-11T17:42:08.823031Z","caller":"traceutil/trace.go:171","msg":"trace[392905451] linearizableReadLoop","detail":"{readStateIndex:463; appliedIndex:462; }","duration":"195.735922ms","start":"2026-04-11T17:42:08.627271Z","end":"2026-04-11T17:42:08.823007Z","steps":["trace[392905451] 'read index received' (duration: 195.070935ms)","trace[392905451] 'applied index is now lower than readState.Index' (duration: 664.007µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:42:08.82317Z","caller":"traceutil/trace.go:171","msg":"trace[171140736] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"291.98396ms","start":"2026-04-11T17:42:08.53113Z","end":"2026-04-11T17:42:08.823114Z","steps":["trace[171140736] 'process raft request' (duration: 291.555494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:08.823269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.979244ms","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-04-11T17:42:08.823318Z","caller":"traceutil/trace.go:171","msg":"trace[2003655515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:444; }","duration":"196.087ms","start":"2026-04-11T17:42:08.627216Z","end":"2026-04-11T17:42:08.823303Z","steps":["trace[2003655515] 'agreement among raft nodes before linearized reading' (duration: 195.926736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:08.823504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.70948ms","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-04-11T17:42:08.823612Z","caller":"traceutil/trace.go:171","msg":"trace[759981442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:444; }","duration":"137.888173ms","start":"2026-04-11T17:42:08.685699Z","end":"2026-04-11T17:42:08.823587Z","steps":["trace[759981442] 'agreement among raft nodes before linearized reading' (duration: 137.638932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:08.823916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.45422ms","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-11T17:42:08.824076Z","caller":"traceutil/trace.go:171","msg":"trace[90352847] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:444; }","duration":"157.913524ms","start":"2026-04-11T17:42:08.666136Z","end":"2026-04-11T17:42:08.824049Z","steps":["trace[90352847] 'agreement among raft nodes before linearized reading' (duration: 157.133531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:11.334133Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822087032794173,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T17:42:11.639534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.265823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:42:11.639956Z","caller":"traceutil/trace.go:171","msg":"trace[800969451] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:533; }","duration":"806.106884ms","start":"2026-04-11T17:42:10.833833Z","end":"2026-04-11T17:42:11.63994Z","steps":["trace[800969451] 'read index received' (duration: 686.254838ms)","trace[800969451] 'applied index is now lower than readState.Index' (duration: 119.851016ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:42:11.639973Z","caller":"traceutil/trace.go:171","msg":"trace[1381296164] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"1.029537877s","start":"2026-04-11T17:42:10.610405Z","end":"2026-04-11T17:42:11.639942Z","steps":["trace[1381296164] 'process raft request' (duration: 909.780068ms)","trace[1381296164] 'compare' (duration: 118.974672ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:42:11.640118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:10.610385Z","time spent":"1.029651363s","remote":"127.0.0.1:60952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:42:11.640216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.381475ms","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-04-11T17:42:11.64025Z","caller":"traceutil/trace.go:171","msg":"trace[1814847372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"806.442644ms","start":"2026-04-11T17:42:10.833797Z","end":"2026-04-11T17:42:11.640239Z","steps":["trace[1814847372] 'agreement among raft nodes before linearized reading' (duration: 806.380756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:11.640281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:10.833781Z","time spent":"806.493072ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-11T17:42:11.640296Z","caller":"traceutil/trace.go:171","msg":"trace[901022091] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"951.943649ms","start":"2026-04-11T17:42:10.688282Z","end":"2026-04-11T17:42:11.640226Z","steps":["trace[901022091] 'process raft request' (duration: 951.597709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:11.640546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:10.688252Z","time spent":"952.185801ms","remote":"127.0.0.1:60952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:42:11.640357Z","caller":"traceutil/trace.go:171","msg":"trace[1748101855] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.017954864s","start":"2026-04-11T17:42:10.622386Z","end":"2026-04-11T17:42:11.640341Z","steps":["trace[1748101855] 'process raft request' (duration: 1.017363481s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:11.640742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:10.622368Z","time spent":"1.018330694s","remote":"127.0.0.1:60952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:42:11.652227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.039307ms","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-04-11T17:42:11.652317Z","caller":"traceutil/trace.go:171","msg":"trace[1328591042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"818.155633ms","start":"2026-04-11T17:42:10.83415Z","end":"2026-04-11T17:42:11.652306Z","steps":["trace[1328591042] 'agreement among raft nodes before linearized reading' (duration: 818.019957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:11.652343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:10.834145Z","time spent":"818.193112ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-11T17:42:11.652314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.996926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:42:11.652407Z","caller":"traceutil/trace.go:171","msg":"trace[579564683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"242.128682ms","start":"2026-04-11T17:42:11.410261Z","end":"2026-04-11T17:42:11.652389Z","steps":["trace[579564683] 'agreement among raft nodes before linearized reading' (duration: 241.997466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:11.652543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.262851ms","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-11T17:42:11.652634Z","caller":"traceutil/trace.go:171","msg":"trace[183559070] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:515; }","duration":"817.371888ms","start":"2026-04-11T17:42:10.835242Z","end":"2026-04-11T17:42:11.652614Z","steps":["trace[183559070] 'agreement among raft nodes before linearized reading' (duration: 817.173334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:11.652687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:10.835238Z","time spent":"817.440066ms","remote":"127.0.0.1:32970","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-11T17:42:18.533426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.862872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:42:18.533577Z","caller":"traceutil/trace.go:171","msg":"trace[911574381] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"782.806793ms","start":"2026-04-11T17:42:17.750745Z","end":"2026-04-11T17:42:18.533552Z","steps":["trace[911574381] 'process raft request' (duration: 174.722544ms)","trace[911574381] 'compare' (duration: 607.750125ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:42:18.533742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:17.750722Z","time spent":"782.974351ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:42:18.552953Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822087032794401,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-11T17:42:18.631238Z","caller":"traceutil/trace.go:171","msg":"trace[367182347] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"880.409774ms","start":"2026-04-11T17:42:17.750794Z","end":"2026-04-11T17:42:18.631204Z","steps":["trace[367182347] 'process raft request' (duration: 880.04742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:18.631425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:17.750773Z","time spent":"880.545672ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:42:18.631792Z","caller":"traceutil/trace.go:171","msg":"trace[2071182820] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"579.064037ms","start":"2026-04-11T17:42:18.052708Z","end":"2026-04-11T17:42:18.631772Z","steps":["trace[2071182820] 'read index received' (duration: 578.781482ms)","trace[2071182820] 'applied index is now lower than readState.Index' (duration: 281.495µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:42:18.631915Z","caller":"traceutil/trace.go:171","msg":"trace[516874192] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"825.832527ms","start":"2026-04-11T17:42:17.80607Z","end":"2026-04-11T17:42:18.631902Z","steps":["trace[516874192] 'process raft request' (duration: 825.103599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:18.631986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:17.805995Z","time spent":"825.947925ms","remote":"127.0.0.1:32770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:42:18.63217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.293076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:42:18.632236Z","caller":"traceutil/trace.go:171","msg":"trace[1774015557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"399.377685ms","start":"2026-04-11T17:42:18.232842Z","end":"2026-04-11T17:42:18.63222Z","steps":["trace[1774015557] 'agreement among raft nodes before linearized reading' (duration: 399.286247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:18.632318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:18.232826Z","time spent":"399.481932ms","remote":"127.0.0.1:60908","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-11T17:42:18.632345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.628027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-d89vf\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-11T17:42:18.632381Z","caller":"traceutil/trace.go:171","msg":"trace[262449425] range","detail":"{range_begin:/registry/pods/kube-system/cilium-d89vf; range_end:; response_count:1; response_revision:594; }","duration":"579.697036ms","start":"2026-04-11T17:42:18.052673Z","end":"2026-04-11T17:42:18.63237Z","steps":["trace[262449425] 'agreement among raft nodes before linearized reading' (duration: 579.570669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:18.632411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:18.052633Z","time spent":"579.770385ms","remote":"127.0.0.1:32874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-d89vf\" "} {"level":"warn","ts":"2026-04-11T17:42:19.256176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.918095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T17:42:19.256335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.983352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:42:19.256254Z","caller":"traceutil/trace.go:171","msg":"trace[195901656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"620.006283ms","start":"2026-04-11T17:42:18.636231Z","end":"2026-04-11T17:42:19.256237Z","steps":["trace[195901656] 'range keys from in-memory index tree' (duration: 619.846776ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:19.256411Z","caller":"traceutil/trace.go:171","msg":"trace[959675478] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"618.830345ms","start":"2026-04-11T17:42:18.637566Z","end":"2026-04-11T17:42:19.256396Z","steps":["trace[959675478] 'process raft request' (duration: 27.711955ms)","trace[959675478] 'compare' (duration: 590.786395ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:42:19.256464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:18.637556Z","time spent":"618.882054ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:42:19.256446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:18.636192Z","time spent":"620.24063ms","remote":"127.0.0.1:60912","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-11T17:42:19.311024Z","caller":"traceutil/trace.go:171","msg":"trace[687722707] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"661.996893ms","start":"2026-04-11T17:42:18.649001Z","end":"2026-04-11T17:42:19.310998Z","steps":["trace[687722707] 'process raft request' (duration: 661.789386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:19.311162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:18.648889Z","time spent":"662.20314ms","remote":"127.0.0.1:32874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:42:19.354761Z","caller":"traceutil/trace.go:171","msg":"trace[1276889823] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"203.933874ms","start":"2026-04-11T17:42:19.150798Z","end":"2026-04-11T17:42:19.354732Z","steps":["trace[1276889823] 'process raft request' (duration: 203.781316ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:19.354778Z","caller":"traceutil/trace.go:171","msg":"trace[912268274] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"669.914253ms","start":"2026-04-11T17:42:18.684836Z","end":"2026-04-11T17:42:19.35475Z","steps":["trace[912268274] 'process raft request' (duration: 669.612187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:19.354886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:18.684821Z","time spent":"670.01681ms","remote":"127.0.0.1:32946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T17:42:20.250291Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822087032794415,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T17:42:20.751117Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822087032794415,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T17:42:21.252048Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822087032794415,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T17:42:21.449945Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.047330158s","expected-duration":"1s"} {"level":"info","ts":"2026-04-11T17:42:21.450471Z","caller":"traceutil/trace.go:171","msg":"trace[1964525144] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"2.047897401s","start":"2026-04-11T17:42:19.402547Z","end":"2026-04-11T17:42:21.450444Z","steps":["trace[1964525144] 'process raft request' (duration: 2.047713303s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:21.450604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:19.402535Z","time spent":"2.04799657s","remote":"127.0.0.1:32874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:42:21.450626Z","caller":"traceutil/trace.go:171","msg":"trace[1304526653] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"1.700404269s","start":"2026-04-11T17:42:19.750186Z","end":"2026-04-11T17:42:21.45059Z","steps":["trace[1304526653] 'read index received' (duration: 1.699964295s)","trace[1304526653] 'applied index is now lower than readState.Index' (duration: 434.654µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:42:21.450896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.218601603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T17:42:21.450917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.700715105s","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-04-11T17:42:21.450958Z","caller":"traceutil/trace.go:171","msg":"trace[1480851428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"1.21875641s","start":"2026-04-11T17:42:20.232192Z","end":"2026-04-11T17:42:21.450948Z","steps":["trace[1480851428] 'agreement among raft nodes before linearized reading' (duration: 1.218606282s)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:21.451Z","caller":"traceutil/trace.go:171","msg":"trace[947724109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"1.700840294s","start":"2026-04-11T17:42:19.750141Z","end":"2026-04-11T17:42:21.450981Z","steps":["trace[947724109] 'agreement among raft nodes before linearized reading' (duration: 1.700542518s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:21.451021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.645799ms","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-11T17:42:21.451051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:19.750125Z","time spent":"1.700917413s","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-11T17:42:21.451064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.095155ms","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-04-11T17:42:21.451131Z","caller":"traceutil/trace.go:171","msg":"trace[1630835480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"913.211774ms","start":"2026-04-11T17:42:20.537904Z","end":"2026-04-11T17:42:21.451115Z","steps":["trace[1630835480] 'agreement among raft nodes before linearized reading' (duration: 913.010267ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:21.451085Z","caller":"traceutil/trace.go:171","msg":"trace[136675476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"814.757337ms","start":"2026-04-11T17:42:20.636312Z","end":"2026-04-11T17:42:21.451069Z","steps":["trace[136675476] 'agreement among raft nodes before linearized reading' (duration: 814.576979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:21.45117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:20.53787Z","time spent":"913.292863ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-11T17:42:21.451198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:20.636291Z","time spent":"814.899415ms","remote":"127.0.0.1:32970","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-11T17:42:21.45114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:20.232169Z","time spent":"1.218928938s","remote":"127.0.0.1:60912","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-11T17:42:21.451297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.020188ms","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-04-11T17:42:21.451476Z","caller":"traceutil/trace.go:171","msg":"trace[2070972832] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"191.201016ms","start":"2026-04-11T17:42:21.260187Z","end":"2026-04-11T17:42:21.451388Z","steps":["trace[2070972832] 'agreement among raft nodes before linearized reading' (duration: 190.658933ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:21.557379Z","caller":"traceutil/trace.go:171","msg":"trace[1012089325] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"100.210113ms","start":"2026-04-11T17:42:21.457147Z","end":"2026-04-11T17:42:21.557357Z","steps":["trace[1012089325] 'process raft request' (duration: 63.697636ms)","trace[1012089325] 'compare' (duration: 36.415208ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:42:21.565114Z","caller":"traceutil/trace.go:171","msg":"trace[950738675] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"107.720386ms","start":"2026-04-11T17:42:21.457379Z","end":"2026-04-11T17:42:21.5651Z","steps":["trace[950738675] 'process raft request' (duration: 107.607577ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:21.56543Z","caller":"traceutil/trace.go:171","msg":"trace[61090114] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"107.885704ms","start":"2026-04-11T17:42:21.457528Z","end":"2026-04-11T17:42:21.565414Z","steps":["trace[61090114] 'process raft request' (duration: 107.717816ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:21.565563Z","caller":"traceutil/trace.go:171","msg":"trace[1704361020] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"107.894374ms","start":"2026-04-11T17:42:21.45766Z","end":"2026-04-11T17:42:21.565554Z","steps":["trace[1704361020] 'process raft request' (duration: 107.707167ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:21.565599Z","caller":"traceutil/trace.go:171","msg":"trace[1457423726] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"107.122884ms","start":"2026-04-11T17:42:21.458458Z","end":"2026-04-11T17:42:21.565581Z","steps":["trace[1457423726] 'process raft request' (duration: 107.007895ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:21.565651Z","caller":"traceutil/trace.go:171","msg":"trace[1465129741] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:623; }","duration":"105.573354ms","start":"2026-04-11T17:42:21.46006Z","end":"2026-04-11T17:42:21.565634Z","steps":["trace[1465129741] 'read index received' (duration: 60.730764ms)","trace[1465129741] 'applied index is now lower than readState.Index' (duration: 44.84189ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:42:21.565737Z","caller":"traceutil/trace.go:171","msg":"trace[1535026809] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"105.764091ms","start":"2026-04-11T17:42:21.459925Z","end":"2026-04-11T17:42:21.565689Z","steps":["trace[1535026809] 'process raft request' (duration: 105.617953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:21.565762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.686653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:42:21.565934Z","caller":"traceutil/trace.go:171","msg":"trace[1817475364] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"105.890799ms","start":"2026-04-11T17:42:21.460032Z","end":"2026-04-11T17:42:21.565923Z","steps":["trace[1817475364] 'agreement among raft nodes before linearized reading' (duration: 105.687632ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:21.565385Z","caller":"traceutil/trace.go:171","msg":"trace[524942089] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"107.892304ms","start":"2026-04-11T17:42:21.457459Z","end":"2026-04-11T17:42:21.565351Z","steps":["trace[524942089] 'process raft request' (duration: 107.599468ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:21.56541Z","caller":"traceutil/trace.go:171","msg":"trace[1509248477] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"107.855684ms","start":"2026-04-11T17:42:21.457537Z","end":"2026-04-11T17:42:21.565393Z","steps":["trace[1509248477] 'process raft request' (duration: 107.795345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:23.59855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.850017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:42:23.598631Z","caller":"traceutil/trace.go:171","msg":"trace[1203064072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"365.973136ms","start":"2026-04-11T17:42:23.232641Z","end":"2026-04-11T17:42:23.598614Z","steps":["trace[1203064072] 'range keys from in-memory index tree' (duration: 365.791348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:23.598678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:23.232623Z","time spent":"366.043725ms","remote":"127.0.0.1:60912","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-11T17:42:23.598673Z","caller":"traceutil/trace.go:171","msg":"trace[756524002] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"275.287694ms","start":"2026-04-11T17:42:23.323362Z","end":"2026-04-11T17:42:23.598649Z","steps":["trace[756524002] 'process raft request' (duration: 272.347393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:37.491977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822087032794717,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T17:42:37.588791Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.133643811s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-11T17:42:37.589181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:36.455029Z","time spent":"1.134146875s","remote":"127.0.0.1:60930","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-11T17:42:37.857143Z","caller":"traceutil/trace.go:171","msg":"trace[596782941] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"1.063206225s","start":"2026-04-11T17:42:36.793911Z","end":"2026-04-11T17:42:37.857117Z","steps":["trace[596782941] 'process raft request' (duration: 1.062961903s)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:37.857502Z","caller":"traceutil/trace.go:171","msg":"trace[1770175452] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"691.165579ms","start":"2026-04-11T17:42:37.166313Z","end":"2026-04-11T17:42:37.857478Z","steps":["trace[1770175452] 'process raft request' (duration: 690.739466ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:37.857472Z","caller":"traceutil/trace.go:171","msg":"trace[1217213995] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:788; }","duration":"866.63245ms","start":"2026-04-11T17:42:36.99081Z","end":"2026-04-11T17:42:37.857442Z","steps":["trace[1217213995] 'read index received' (duration: 598.212212ms)","trace[1217213995] 'applied index is now lower than readState.Index' (duration: 268.418228ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:42:37.857609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:37.166263Z","time spent":"691.30225ms","remote":"127.0.0.1:32946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:42:37.857852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.016973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-11T17:42:37.857946Z","caller":"traceutil/trace.go:171","msg":"trace[1904579770] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:762; }","duration":"867.165604ms","start":"2026-04-11T17:42:36.990762Z","end":"2026-04-11T17:42:37.857927Z","steps":["trace[1904579770] 'agreement among raft nodes before linearized reading' (duration: 866.855511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:37.858027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:36.990744Z","time spent":"867.242994ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-11T17:42:37.85745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:36.793885Z","time spent":"1.063426957s","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:42:37.867497Z","caller":"traceutil/trace.go:171","msg":"trace[1986275518] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"276.965127ms","start":"2026-04-11T17:42:37.590508Z","end":"2026-04-11T17:42:37.867473Z","steps":["trace[1986275518] 'process raft request' (duration: 276.750035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:37.867538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.422652ms","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-11T17:42:37.86761Z","caller":"traceutil/trace.go:171","msg":"trace[1549317338] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:763; }","duration":"696.506922ms","start":"2026-04-11T17:42:37.17109Z","end":"2026-04-11T17:42:37.867597Z","steps":["trace[1549317338] 'agreement among raft nodes before linearized reading' (duration: 696.356131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:37.867695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:37.171032Z","time spent":"696.652943ms","remote":"127.0.0.1:33174","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-11T17:42:37.86774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.574083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-11T17:42:37.867754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.864069ms","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-04-11T17:42:37.8678Z","caller":"traceutil/trace.go:171","msg":"trace[1776836147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:763; }","duration":"197.688024ms","start":"2026-04-11T17:42:37.670097Z","end":"2026-04-11T17:42:37.867785Z","steps":["trace[1776836147] 'agreement among raft nodes before linearized reading' (duration: 197.457832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:37.867774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.955038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:42:37.867874Z","caller":"traceutil/trace.go:171","msg":"trace[535756121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:763; }","duration":"191.02971ms","start":"2026-04-11T17:42:37.676809Z","end":"2026-04-11T17:42:37.867839Z","steps":["trace[535756121] 'agreement among raft nodes before linearized reading' (duration: 190.639097ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:37.867919Z","caller":"traceutil/trace.go:171","msg":"trace[1781109192] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:763; }","duration":"351.16441ms","start":"2026-04-11T17:42:37.516743Z","end":"2026-04-11T17:42:37.867907Z","steps":["trace[1781109192] 'agreement among raft nodes before linearized reading' (duration: 350.795027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:37.867959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:37.51672Z","time spent":"351.22329ms","remote":"127.0.0.1:32984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-11T17:42:37.867864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.230263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:42:37.868129Z","caller":"traceutil/trace.go:171","msg":"trace[33662710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"636.509625ms","start":"2026-04-11T17:42:37.231595Z","end":"2026-04-11T17:42:37.868104Z","steps":["trace[33662710] 'agreement among raft nodes before linearized reading' (duration: 636.246783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:37.868176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:37.231578Z","time spent":"636.589006ms","remote":"127.0.0.1:60908","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-11T17:42:37.868301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.294928ms","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-11T17:42:37.868331Z","caller":"traceutil/trace.go:171","msg":"trace[1926166489] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:764; }","duration":"183.336338ms","start":"2026-04-11T17:42:37.684987Z","end":"2026-04-11T17:42:37.868323Z","steps":["trace[1926166489] 'agreement among raft nodes before linearized reading' (duration: 183.229147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:37.868379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.110025ms","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-04-11T17:42:37.868444Z","caller":"traceutil/trace.go:171","msg":"trace[523453026] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:764; }","duration":"184.213025ms","start":"2026-04-11T17:42:37.684215Z","end":"2026-04-11T17:42:37.868428Z","steps":["trace[523453026] 'agreement among raft nodes before linearized reading' (duration: 183.292987ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:44:24.765631Z","caller":"traceutil/trace.go:171","msg":"trace[756102003] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"294.283771ms","start":"2026-04-11T17:44:24.471323Z","end":"2026-04-11T17:44:24.765606Z","steps":["trace[756102003] 'process raft request' (duration: 294.214217ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:44:24.765656Z","caller":"traceutil/trace.go:171","msg":"trace[177813064] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"294.41332ms","start":"2026-04-11T17:44:24.47122Z","end":"2026-04-11T17:44:24.765633Z","steps":["trace[177813064] 'process raft request' (duration: 294.185205ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:44:24.76578Z","caller":"traceutil/trace.go:171","msg":"trace[1500122947] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"293.636337ms","start":"2026-04-11T17:44:24.472116Z","end":"2026-04-11T17:44:24.765752Z","steps":["trace[1500122947] 'process raft request' (duration: 293.459475ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:44:24.779043Z","caller":"traceutil/trace.go:171","msg":"trace[1978075050] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"168.246025ms","start":"2026-04-11T17:44:24.610787Z","end":"2026-04-11T17:44:24.779033Z","steps":["trace[1978075050] 'process raft request' (duration: 168.159359ms)"],"step_count":1}