{"level":"warn","ts":"2026-05-05T09:46:30.625838Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T09:46:30.625936Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.91: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.91:2380","--initial-cluster=instance=https://199.19.213.91:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.91:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.91:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-05T09:46:30.626011Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T09:46:30.626021Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.91:2380"]} {"level":"info","ts":"2026-05-05T09:46:30.62604Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T09:46:30.62674Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"]} {"level":"info","ts":"2026-05-05T09:46:30.626836Z","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.91:2380"],"listen-peer-urls":["https://199.19.213.91:2380"],"advertise-client-urls":["https://199.19.213.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.91:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-05T09:46:30.634813Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.778587ms"} {"level":"info","ts":"2026-05-05T09:46:30.649318Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2c24222821500990","cluster-id":"f38faa6ba493daad"} {"level":"info","ts":"2026-05-05T09:46:30.649424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T09:46:30.649463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became follower at term 0"} {"level":"info","ts":"2026-05-05T09:46:30.649485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2c24222821500990 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T09:46:30.649522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became follower at term 1"} {"level":"info","ts":"2026-05-05T09:46:30.649588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=(3180704792583342480)"} {"level":"warn","ts":"2026-05-05T09:46:30.668102Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T09:46:30.67392Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T09:46:30.67605Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-05T09:46:30.678684Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2c24222821500990","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T09:46:30.678961Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2c24222821500990","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T09:46:30.679305Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T09:46:30.679435Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T09:46:30.679453Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T09:46:30.684567Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T09:46:30.684824Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.91:2380"} {"level":"info","ts":"2026-05-05T09:46:30.684892Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.91:2380"} {"level":"info","ts":"2026-05-05T09:46:30.684887Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2c24222821500990","initial-advertise-peer-urls":["https://199.19.213.91:2380"],"listen-peer-urls":["https://199.19.213.91:2380"],"advertise-client-urls":["https://199.19.213.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T09:46:30.684936Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T09:46:30.690911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=(3180704792583342480)"} {"level":"info","ts":"2026-05-05T09:46:30.691129Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f38faa6ba493daad","local-member-id":"2c24222821500990","added-peer-id":"2c24222821500990","added-peer-peer-urls":["https://199.19.213.91:2380"]} {"level":"info","ts":"2026-05-05T09:46:31.35068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T09:46:31.350737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T09:46:31.350761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 received MsgPreVoteResp from 2c24222821500990 at term 1"} {"level":"info","ts":"2026-05-05T09:46:31.350775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became candidate at term 2"} {"level":"info","ts":"2026-05-05T09:46:31.350781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 received MsgVoteResp from 2c24222821500990 at term 2"} {"level":"info","ts":"2026-05-05T09:46:31.350796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became leader at term 2"} {"level":"info","ts":"2026-05-05T09:46:31.350803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2c24222821500990 elected leader 2c24222821500990 at term 2"} {"level":"info","ts":"2026-05-05T09:46:31.351684Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:46:31.352578Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T09:46:31.352582Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2c24222821500990","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.91:2379]}","request-path":"/0/members/2c24222821500990/attributes","cluster-id":"f38faa6ba493daad","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T09:46:31.352667Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T09:46:31.352855Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f38faa6ba493daad","local-member-id":"2c24222821500990","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:46:31.352963Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:46:31.353002Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:46:31.353123Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T09:46:31.353236Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T09:46:31.356256Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.91:2379"} {"level":"info","ts":"2026-05-05T09:46:31.356344Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T09:47:05.979804Z","caller":"traceutil/trace.go:171","msg":"trace[1126390262] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"106.594136ms","start":"2026-05-05T09:47:05.873184Z","end":"2026-05-05T09:47:05.979778Z","steps":["trace[1126390262] 'process raft request' (duration: 106.39204ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:47:45.543822Z","caller":"traceutil/trace.go:171","msg":"trace[1991717712] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"196.983642ms","start":"2026-05-05T09:47:45.346816Z","end":"2026-05-05T09:47:45.5438Z","steps":["trace[1991717712] 'read index received' (duration: 196.838118ms)","trace[1991717712] 'applied index is now lower than readState.Index' (duration: 144.584µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:47:45.543942Z","caller":"traceutil/trace.go:171","msg":"trace[1387939350] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"281.132228ms","start":"2026-05-05T09:47:45.262792Z","end":"2026-05-05T09:47:45.543924Z","steps":["trace[1387939350] 'process raft request' (duration: 280.874422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:47:45.544093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.263119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T09:47:45.544211Z","caller":"traceutil/trace.go:171","msg":"trace[490604143] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:711; }","duration":"197.456594ms","start":"2026-05-05T09:47:45.34672Z","end":"2026-05-05T09:47:45.544177Z","steps":["trace[490604143] 'agreement among raft nodes before linearized reading' (duration: 197.228598ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:47:45.713278Z","caller":"traceutil/trace.go:171","msg":"trace[2099439455] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"153.633954ms","start":"2026-05-05T09:47:45.559561Z","end":"2026-05-05T09:47:45.713195Z","steps":["trace[2099439455] 'process raft request' (duration: 64.615645ms)","trace[2099439455] 'compare' (duration: 88.850824ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:47:46.226848Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224429454983017,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T09:47:46.929165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.762712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:47:46.929298Z","caller":"traceutil/trace.go:171","msg":"trace[316745002] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:744; }","duration":"1.203137852s","start":"2026-05-05T09:47:45.72614Z","end":"2026-05-05T09:47:46.929278Z","steps":["trace[316745002] 'read index received' (duration: 872.244162ms)","trace[316745002] 'applied index is now lower than readState.Index' (duration: 330.89205ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:47:46.92932Z","caller":"traceutil/trace.go:171","msg":"trace[1616459513] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"1.210017148s","start":"2026-05-05T09:47:45.719273Z","end":"2026-05-05T09:47:46.92929Z","steps":["trace[1616459513] 'process raft request' (duration: 879.126838ms)","trace[1616459513] 'compare' (duration: 329.500496ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:47:46.92945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203297866s","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-05-05T09:47:46.929493Z","caller":"traceutil/trace.go:171","msg":"trace[616572137] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:713; }","duration":"1.203369098s","start":"2026-05-05T09:47:45.72611Z","end":"2026-05-05T09:47:46.929479Z","steps":["trace[616572137] 'agreement among raft nodes before linearized reading' (duration: 1.203277496s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:47:46.92955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:47:45.726101Z","time spent":"1.203428969s","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-05T09:47:46.929584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:47:45.719246Z","time spent":"1.210196163s","remote":"127.0.0.1:48858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:47:46.929769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.06326318s","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-05-05T09:47:46.929814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.96286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T09:47:46.929863Z","caller":"traceutil/trace.go:171","msg":"trace[541808131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:713; }","duration":"1.063397713s","start":"2026-05-05T09:47:45.866452Z","end":"2026-05-05T09:47:46.929849Z","steps":["trace[541808131] 'agreement among raft nodes before linearized reading' (duration: 1.063142247s)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:47:46.929894Z","caller":"traceutil/trace.go:171","msg":"trace[81671911] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:713; }","duration":"731.078902ms","start":"2026-05-05T09:47:46.198794Z","end":"2026-05-05T09:47:46.929872Z","steps":["trace[81671911] 'agreement among raft nodes before linearized reading' (duration: 730.837987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:47:46.929903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:47:45.866429Z","time spent":"1.063460735s","remote":"127.0.0.1:48858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T09:47:46.929939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:47:46.198774Z","time spent":"731.155845ms","remote":"127.0.0.1:48858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T09:47:48.856603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.793524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:47:48.856679Z","caller":"traceutil/trace.go:171","msg":"trace[1009272780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:750; }","duration":"167.926397ms","start":"2026-05-05T09:47:48.688734Z","end":"2026-05-05T09:47:48.856661Z","steps":["trace[1009272780] 'range keys from in-memory index tree' (duration: 167.694952ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:48:02.846353Z","caller":"traceutil/trace.go:171","msg":"trace[484593278] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"403.355461ms","start":"2026-05-05T09:48:02.442977Z","end":"2026-05-05T09:48:02.846333Z","steps":["trace[484593278] 'process raft request' (duration: 403.215017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:48:02.846496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:48:02.442953Z","time spent":"403.474514ms","remote":"127.0.0.1:48858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:48:02.854507Z","caller":"traceutil/trace.go:171","msg":"trace[862781836] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"261.899806ms","start":"2026-05-05T09:48:02.592575Z","end":"2026-05-05T09:48:02.854475Z","steps":["trace[862781836] 'process raft request' (duration: 261.751543ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:48:10.580603Z","caller":"traceutil/trace.go:171","msg":"trace[2079103936] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"147.735926ms","start":"2026-05-05T09:48:10.432842Z","end":"2026-05-05T09:48:10.580578Z","steps":["trace[2079103936] 'process raft request' (duration: 145.358679ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:48:38.559364Z","caller":"traceutil/trace.go:171","msg":"trace[963229136] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"180.039093ms","start":"2026-05-05T09:48:38.379298Z","end":"2026-05-05T09:48:38.559337Z","steps":["trace[963229136] 'process raft request' (duration: 179.796787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:48:39.066908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.385533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:48:39.066973Z","caller":"traceutil/trace.go:171","msg":"trace[1343449783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1088; }","duration":"376.506246ms","start":"2026-05-05T09:48:38.690453Z","end":"2026-05-05T09:48:39.066959Z","steps":["trace[1343449783] 'range keys from in-memory index tree' (duration: 376.263359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:48:39.067007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:48:38.690428Z","time spent":"376.572537ms","remote":"127.0.0.1:48592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T09:49:02.288213Z","caller":"traceutil/trace.go:171","msg":"trace[1898379206] linearizableReadLoop","detail":"{readStateIndex:1214; appliedIndex:1213; }","duration":"158.100461ms","start":"2026-05-05T09:49:02.13008Z","end":"2026-05-05T09:49:02.288181Z","steps":["trace[1898379206] 'read index received' (duration: 157.668081ms)","trace[1898379206] 'applied index is now lower than readState.Index' (duration: 431.16µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:49:02.288574Z","caller":"traceutil/trace.go:171","msg":"trace[1845152155] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"275.382326ms","start":"2026-05-05T09:49:02.012896Z","end":"2026-05-05T09:49:02.288279Z","steps":["trace[1845152155] 'process raft request' (duration: 275.020538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:02.288611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.426749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T09:49:02.288926Z","caller":"traceutil/trace.go:171","msg":"trace[882326296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1165; }","duration":"158.878271ms","start":"2026-05-05T09:49:02.130029Z","end":"2026-05-05T09:49:02.288907Z","steps":["trace[882326296] 'agreement among raft nodes before linearized reading' (duration: 158.304886ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:02.476616Z","caller":"traceutil/trace.go:171","msg":"trace[749777378] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"183.295701ms","start":"2026-05-05T09:49:02.293288Z","end":"2026-05-05T09:49:02.476583Z","steps":["trace[749777378] 'process raft request' (duration: 160.706795ms)","trace[749777378] 'compare' (duration: 22.455173ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:49:03.636915Z","caller":"traceutil/trace.go:171","msg":"trace[629633743] linearizableReadLoop","detail":"{readStateIndex:1216; appliedIndex:1215; }","duration":"322.399845ms","start":"2026-05-05T09:49:03.314488Z","end":"2026-05-05T09:49:03.636888Z","steps":["trace[629633743] 'read index received' (duration: 322.116868ms)","trace[629633743] 'applied index is now lower than readState.Index' (duration: 281.717µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:49:03.636963Z","caller":"traceutil/trace.go:171","msg":"trace[872276133] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"416.302176ms","start":"2026-05-05T09:49:03.220629Z","end":"2026-05-05T09:49:03.636931Z","steps":["trace[872276133] 'process raft request' (duration: 416.108621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:03.637169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.49565ms","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-05-05T09:49:03.637182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:03.220614Z","time spent":"416.414058ms","remote":"127.0.0.1:48858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:49:03.637262Z","caller":"traceutil/trace.go:171","msg":"trace[918293429] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1167; }","duration":"290.660423ms","start":"2026-05-05T09:49:03.346585Z","end":"2026-05-05T09:49:03.637246Z","steps":["trace[918293429] 'agreement among raft nodes before linearized reading' (duration: 290.439118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:03.637315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.782393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T09:49:03.637476Z","caller":"traceutil/trace.go:171","msg":"trace[1263169178] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1167; }","duration":"323.03571ms","start":"2026-05-05T09:49:03.314421Z","end":"2026-05-05T09:49:03.637457Z","steps":["trace[1263169178] 'agreement among raft nodes before linearized reading' (duration: 322.605959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:03.637538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:03.314392Z","time spent":"323.136382ms","remote":"127.0.0.1:48858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T09:49:03.637386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.430892ms","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-05-05T09:49:03.637642Z","caller":"traceutil/trace.go:171","msg":"trace[154996160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1167; }","duration":"228.735789ms","start":"2026-05-05T09:49:03.408883Z","end":"2026-05-05T09:49:03.637618Z","steps":["trace[154996160] 'agreement among raft nodes before linearized reading' (duration: 228.221787ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:03.976154Z","caller":"traceutil/trace.go:171","msg":"trace[1231279276] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"333.230331ms","start":"2026-05-05T09:49:03.642898Z","end":"2026-05-05T09:49:03.976129Z","steps":["trace[1231279276] 'process raft request' (duration: 333.064057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:03.976469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:03.642882Z","time spent":"333.361744ms","remote":"127.0.0.1:48858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:49:03.976552Z","caller":"traceutil/trace.go:171","msg":"trace[329268289] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"331.844327ms","start":"2026-05-05T09:49:03.644516Z","end":"2026-05-05T09:49:03.976361Z","steps":["trace[329268289] 'process raft request' (duration: 331.5758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:03.97685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:03.644503Z","time spent":"332.133095ms","remote":"127.0.0.1:48858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:49:04.598282Z","caller":"traceutil/trace.go:171","msg":"trace[666667555] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"112.948459ms","start":"2026-05-05T09:49:04.48531Z","end":"2026-05-05T09:49:04.598259Z","steps":["trace[666667555] 'process raft request' (duration: 112.58721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:17.16083Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224429454984221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T09:49:17.41178Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.079472796s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T09:49:17.412166Z","caller":"traceutil/trace.go:171","msg":"trace[1449612031] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"1.08002266s","start":"2026-05-05T09:49:16.332128Z","end":"2026-05-05T09:49:17.41215Z","steps":["trace[1449612031] 'process raft request' (duration: 1.079844926s)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:17.412152Z","caller":"traceutil/trace.go:171","msg":"trace[39212308] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1316; }","duration":"751.699539ms","start":"2026-05-05T09:49:16.66043Z","end":"2026-05-05T09:49:17.412129Z","steps":["trace[39212308] 'read index received' (duration: 751.511705ms)","trace[39212308] 'applied index is now lower than readState.Index' (duration: 186.814µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:49:17.412258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:16.332092Z","time spent":"1.080113411s","remote":"127.0.0.1:48858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:49:17.41233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.897604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T09:49:17.41238Z","caller":"traceutil/trace.go:171","msg":"trace[1762724968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1263; }","duration":"752.007377ms","start":"2026-05-05T09:49:16.660361Z","end":"2026-05-05T09:49:17.412368Z","steps":["trace[1762724968] 'agreement among raft nodes before linearized reading' (duration: 751.848613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:17.412418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:16.660336Z","time spent":"752.072598ms","remote":"127.0.0.1:48858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T09:49:17.541997Z","caller":"traceutil/trace.go:171","msg":"trace[744429732] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"126.287318ms","start":"2026-05-05T09:49:17.415684Z","end":"2026-05-05T09:49:17.541971Z","steps":["trace[744429732] 'process raft request' (duration: 124.254779ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:33.428481Z","caller":"traceutil/trace.go:171","msg":"trace[428834829] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"523.082493ms","start":"2026-05-05T09:49:32.905374Z","end":"2026-05-05T09:49:33.428456Z","steps":["trace[428834829] 'process raft request' (duration: 522.97212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:33.428597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:32.905349Z","time spent":"523.188015ms","remote":"127.0.0.1:48764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11371,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:49:33.707041Z","caller":"traceutil/trace.go:171","msg":"trace[1643027449] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"273.233225ms","start":"2026-05-05T09:49:33.433783Z","end":"2026-05-05T09:49:33.707016Z","steps":["trace[1643027449] 'process raft request' (duration: 273.052391ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:33.766616Z","caller":"traceutil/trace.go:171","msg":"trace[250810301] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"332.302109ms","start":"2026-05-05T09:49:33.434288Z","end":"2026-05-05T09:49:33.76659Z","steps":["trace[250810301] 'process raft request' (duration: 332.102304ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:33.766858Z","caller":"traceutil/trace.go:171","msg":"trace[1935545172] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"326.666729ms","start":"2026-05-05T09:49:33.44014Z","end":"2026-05-05T09:49:33.766807Z","steps":["trace[1935545172] 'process raft request' (duration: 326.357801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:33.767046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:33.440114Z","time spent":"326.835423ms","remote":"127.0.0.1:49036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:49:33.766979Z","caller":"traceutil/trace.go:171","msg":"trace[1831664663] linearizableReadLoop","detail":"{readStateIndex:1392; appliedIndex:1388; }","duration":"199.54071ms","start":"2026-05-05T09:49:33.567363Z","end":"2026-05-05T09:49:33.766903Z","steps":["trace[1831664663] 'read index received' (duration: 139.424901ms)","trace[1831664663] 'applied index is now lower than readState.Index' (duration: 60.112949ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:49:33.767196Z","caller":"traceutil/trace.go:171","msg":"trace[945438184] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"304.588065ms","start":"2026-05-05T09:49:33.462563Z","end":"2026-05-05T09:49:33.767151Z","steps":["trace[945438184] 'process raft request' (duration: 303.97767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:33.767279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:33.434281Z","time spent":"332.549924ms","remote":"127.0.0.1:48870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:49:33.767346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.941181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T09:49:33.767817Z","caller":"traceutil/trace.go:171","msg":"trace[731904861] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1335; }","duration":"200.490874ms","start":"2026-05-05T09:49:33.567309Z","end":"2026-05-05T09:49:33.7678Z","steps":["trace[731904861] 'agreement among raft nodes before linearized reading' (duration: 199.874749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:33.770205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:33.462545Z","time spent":"304.994135ms","remote":"127.0.0.1:48858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:49:33.770929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.890778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T09:49:33.770983Z","caller":"traceutil/trace.go:171","msg":"trace[570074478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1335; }","duration":"166.943959ms","start":"2026-05-05T09:49:33.604023Z","end":"2026-05-05T09:49:33.770967Z","steps":["trace[570074478] 'agreement among raft nodes before linearized reading' (duration: 166.763514ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:34.432532Z","caller":"traceutil/trace.go:171","msg":"trace[1363094833] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"161.031563ms","start":"2026-05-05T09:49:34.271474Z","end":"2026-05-05T09:49:34.432506Z","steps":["trace[1363094833] 'process raft request' (duration: 160.86975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:34.979668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.186348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-05-05T09:49:34.979761Z","caller":"traceutil/trace.go:171","msg":"trace[586151661] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1343; }","duration":"543.326991ms","start":"2026-05-05T09:49:34.436413Z","end":"2026-05-05T09:49:34.97974Z","steps":["trace[586151661] 'range keys from in-memory index tree' (duration: 543.075485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:34.979803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:34.436393Z","time spent":"543.400833ms","remote":"127.0.0.1:48858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-05T09:49:34.980154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.643047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:49:34.980216Z","caller":"traceutil/trace.go:171","msg":"trace[1624579969] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"541.450085ms","start":"2026-05-05T09:49:34.438753Z","end":"2026-05-05T09:49:34.980203Z","steps":["trace[1624579969] 'process raft request' (duration: 212.709215ms)","trace[1624579969] 'compare' (duration: 328.537525ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:49:34.980257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:34.438728Z","time spent":"541.510917ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T09:49:34.979887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.180172ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:49:34.98049Z","caller":"traceutil/trace.go:171","msg":"trace[1590351390] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1343; }","duration":"494.819717ms","start":"2026-05-05T09:49:34.485651Z","end":"2026-05-05T09:49:34.980471Z","steps":["trace[1590351390] 'range keys from in-memory index tree' (duration: 494.16273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:35.270942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.06549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:49:35.271132Z","caller":"traceutil/trace.go:171","msg":"trace[1823747902] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"286.186163ms","start":"2026-05-05T09:49:34.984926Z","end":"2026-05-05T09:49:35.271112Z","steps":["trace[1823747902] 'process raft request' (duration: 286.120552ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:35.271138Z","caller":"traceutil/trace.go:171","msg":"trace[137284145] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"817.492959ms","start":"2026-05-05T09:49:34.453574Z","end":"2026-05-05T09:49:35.271067Z","steps":["trace[137284145] 'process raft request' (duration: 648.237474ms)","trace[137284145] 'compare' (duration: 168.959428ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:49:35.271163Z","caller":"traceutil/trace.go:171","msg":"trace[81498705] linearizableReadLoop","detail":"{readStateIndex:1402; appliedIndex:1400; }","duration":"674.816418ms","start":"2026-05-05T09:49:34.596315Z","end":"2026-05-05T09:49:35.271132Z","steps":["trace[81498705] 'read index received' (duration: 55.264671ms)","trace[81498705] 'applied index is now lower than readState.Index' (duration: 619.549397ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:49:35.271363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:34.453551Z","time spent":"817.708314ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T09:49:35.271392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.061013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.91\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-05T09:49:35.271413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.230163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:49:35.271439Z","caller":"traceutil/trace.go:171","msg":"trace[621357648] range","detail":"{range_begin:/registry/masterleases/199.19.213.91; range_end:; response_count:1; response_revision:1346; }","duration":"675.151286ms","start":"2026-05-05T09:49:34.596278Z","end":"2026-05-05T09:49:35.271429Z","steps":["trace[621357648] 'agreement among raft nodes before linearized reading' (duration: 674.940091ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:35.271463Z","caller":"traceutil/trace.go:171","msg":"trace[1833922526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1346; }","duration":"583.308895ms","start":"2026-05-05T09:49:34.68813Z","end":"2026-05-05T09:49:35.271438Z","steps":["trace[1833922526] 'agreement among raft nodes before linearized reading' (duration: 583.248164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:35.27145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.03434ms","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":"warn","ts":"2026-05-05T09:49:35.271474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:34.596262Z","time spent":"675.204727ms","remote":"127.0.0.1:48630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.91\" "} {"level":"warn","ts":"2026-05-05T09:49:35.271497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:34.688106Z","time spent":"583.381937ms","remote":"127.0.0.1:48592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T09:49:35.271513Z","caller":"traceutil/trace.go:171","msg":"trace[641844932] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1346; }","duration":"490.153543ms","start":"2026-05-05T09:49:34.781343Z","end":"2026-05-05T09:49:35.271497Z","steps":["trace[641844932] 'agreement among raft nodes before linearized reading' (duration: 490.04157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:35.27155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:34.781317Z","time spent":"490.223905ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-05T09:49:35.486577Z","caller":"traceutil/trace.go:171","msg":"trace[121660538] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"173.477119ms","start":"2026-05-05T09:49:35.313078Z","end":"2026-05-05T09:49:35.486555Z","steps":["trace[121660538] 'process raft request' (duration: 98.3423ms)","trace[121660538] 'compare' (duration: 75.037587ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:49:35.893751Z","caller":"traceutil/trace.go:171","msg":"trace[380412434] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"118.999098ms","start":"2026-05-05T09:49:35.774714Z","end":"2026-05-05T09:49:35.893713Z","steps":["trace[380412434] 'process raft request' (duration: 118.683021ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:36.142348Z","caller":"traceutil/trace.go:171","msg":"trace[1342344943] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1407; }","duration":"219.254276ms","start":"2026-05-05T09:49:35.923068Z","end":"2026-05-05T09:49:36.142322Z","steps":["trace[1342344943] 'read index received' (duration: 207.061686ms)","trace[1342344943] 'applied index is now lower than readState.Index' (duration: 12.19131ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:49:36.142544Z","caller":"traceutil/trace.go:171","msg":"trace[14176439] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"244.22793ms","start":"2026-05-05T09:49:35.898303Z","end":"2026-05-05T09:49:36.142531Z","steps":["trace[14176439] 'process raft request' (duration: 231.791924ms)","trace[14176439] 'compare' (duration: 11.77746ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:49:36.142733Z","caller":"traceutil/trace.go:171","msg":"trace[1940833140] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"244.402325ms","start":"2026-05-05T09:49:35.898321Z","end":"2026-05-05T09:49:36.142723Z","steps":["trace[1940833140] 'process raft request' (duration: 243.910123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:36.142837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.185059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T09:49:36.142884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.786262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:49:36.142912Z","caller":"traceutil/trace.go:171","msg":"trace[550353899] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1351; }","duration":"192.297512ms","start":"2026-05-05T09:49:35.950595Z","end":"2026-05-05T09:49:36.142892Z","steps":["trace[550353899] 'agreement among raft nodes before linearized reading' (duration: 192.143378ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:36.142922Z","caller":"traceutil/trace.go:171","msg":"trace[1322278234] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1351; }","duration":"187.875623ms","start":"2026-05-05T09:49:35.955034Z","end":"2026-05-05T09:49:36.14291Z","steps":["trace[1322278234] 'agreement among raft nodes before linearized reading' (duration: 187.783541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:36.142949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.880921ms","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-05-05T09:49:36.143002Z","caller":"traceutil/trace.go:171","msg":"trace[901643501] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1351; }","duration":"219.999843ms","start":"2026-05-05T09:49:35.922989Z","end":"2026-05-05T09:49:36.142989Z","steps":["trace[901643501] 'agreement among raft nodes before linearized reading' (duration: 219.833239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:48.198417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.360044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T09:49:48.198891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.709263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:49:48.199139Z","caller":"traceutil/trace.go:171","msg":"trace[396225459] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1431; }","duration":"538.101113ms","start":"2026-05-05T09:49:47.660973Z","end":"2026-05-05T09:49:48.199074Z","steps":["trace[396225459] 'count revisions from in-memory index tree' (duration: 537.254752ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:48.199233Z","caller":"traceutil/trace.go:171","msg":"trace[772082074] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1431; }","duration":"462.098693ms","start":"2026-05-05T09:49:47.737104Z","end":"2026-05-05T09:49:48.199203Z","steps":["trace[772082074] 'count revisions from in-memory index tree' (duration: 461.476637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:48.199245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:47.660941Z","time spent":"538.281987ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":11,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-05-05T09:49:48.199412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:47.737076Z","time spent":"462.224055ms","remote":"127.0.0.1:36674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-05T09:49:48.327033Z","caller":"traceutil/trace.go:171","msg":"trace[80293573] linearizableReadLoop","detail":"{readStateIndex:1492; appliedIndex:1491; }","duration":"132.817689ms","start":"2026-05-05T09:49:48.194195Z","end":"2026-05-05T09:49:48.327012Z","steps":["trace[80293573] 'read index received' (duration: 132.614804ms)","trace[80293573] 'applied index is now lower than readState.Index' (duration: 201.855µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:49:48.327144Z","caller":"traceutil/trace.go:171","msg":"trace[435829712] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"396.421127ms","start":"2026-05-05T09:49:47.930714Z","end":"2026-05-05T09:49:48.327135Z","steps":["trace[435829712] 'process raft request' (duration: 396.171251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:48.327238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:47.930687Z","time spent":"396.485859ms","remote":"127.0.0.1:48858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:49:48.327424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.837664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T09:49:48.3275Z","caller":"traceutil/trace.go:171","msg":"trace[538364572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1432; }","duration":"131.942867ms","start":"2026-05-05T09:49:48.19554Z","end":"2026-05-05T09:49:48.327483Z","steps":["trace[538364572] 'agreement among raft nodes before linearized reading' (duration: 131.728502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:48.327662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.451772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-05T09:49:48.327662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.495945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T09:49:48.327696Z","caller":"traceutil/trace.go:171","msg":"trace[1540500270] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1432; }","duration":"113.511894ms","start":"2026-05-05T09:49:48.214175Z","end":"2026-05-05T09:49:48.327687Z","steps":["trace[1540500270] 'agreement among raft nodes before linearized reading' (duration: 113.421472ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:48.327718Z","caller":"traceutil/trace.go:171","msg":"trace[1844910321] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1432; }","duration":"133.579617ms","start":"2026-05-05T09:49:48.194114Z","end":"2026-05-05T09:49:48.327693Z","steps":["trace[1844910321] 'agreement among raft nodes before linearized reading' (duration: 133.443074ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:22.757623Z","caller":"traceutil/trace.go:171","msg":"trace[245704748] transaction","detail":"{read_only:false; response_revision:1592; number_of_response:1; }","duration":"128.538154ms","start":"2026-05-05T09:50:22.629063Z","end":"2026-05-05T09:50:22.757601Z","steps":["trace[245704748] 'process raft request' (duration: 128.366029ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:22.757826Z","caller":"traceutil/trace.go:171","msg":"trace[959511653] linearizableReadLoop","detail":"{readStateIndex:1661; appliedIndex:1657; }","duration":"126.155612ms","start":"2026-05-05T09:50:22.631655Z","end":"2026-05-05T09:50:22.75781Z","steps":["trace[959511653] 'read index received' (duration: 47.617622ms)","trace[959511653] 'applied index is now lower than readState.Index' (duration: 78.53744ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:22.757894Z","caller":"traceutil/trace.go:171","msg":"trace[373937635] transaction","detail":"{read_only:false; response_revision:1593; number_of_response:1; }","duration":"128.762649ms","start":"2026-05-05T09:50:22.629117Z","end":"2026-05-05T09:50:22.75788Z","steps":["trace[373937635] 'process raft request' (duration: 128.43079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:22.757949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.277645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-05T09:50:22.757935Z","caller":"traceutil/trace.go:171","msg":"trace[314918156] transaction","detail":"{read_only:false; response_revision:1594; number_of_response:1; }","duration":"128.778829ms","start":"2026-05-05T09:50:22.629118Z","end":"2026-05-05T09:50:22.757897Z","steps":["trace[314918156] 'process raft request' (duration: 128.617765ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:22.757974Z","caller":"traceutil/trace.go:171","msg":"trace[219468879] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1594; }","duration":"126.315876ms","start":"2026-05-05T09:50:22.631651Z","end":"2026-05-05T09:50:22.757967Z","steps":["trace[219468879] 'agreement among raft nodes before linearized reading' (duration: 126.215124ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:52:57.229243Z","caller":"traceutil/trace.go:171","msg":"trace[912776579] transaction","detail":"{read_only:false; response_revision:2324; number_of_response:1; }","duration":"166.677141ms","start":"2026-05-05T09:52:57.062514Z","end":"2026-05-05T09:52:57.229191Z","steps":["trace[912776579] 'process raft request' (duration: 166.523037ms)"],"step_count":1}