{"level":"warn","ts":"2026-05-05T11:20:18.02066Z","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-05T11:20:18.020766Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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-05T11:20:18.02086Z","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-05T11:20:18.020877Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-05-05T11:20:18.0209Z","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-05T11:20:18.022094Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-05-05T11:20:18.022198Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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-05T11:20:18.028947Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.449895ms"} {"level":"info","ts":"2026-05-05T11:20:18.034422Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-05-05T11:20:18.034522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T11:20:18.034546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-05-05T11:20:18.034558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T11:20:18.034574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-05-05T11:20:18.034614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-05-05T11:20:18.041186Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T11:20:18.042832Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T11:20:18.044125Z","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-05T11:20:18.046127Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T11:20:18.046282Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T11:20:18.046451Z","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-05T11:20:18.046525Z","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-05T11:20:18.046537Z","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-05T11:20:18.048735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-05-05T11:20:18.049189Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-05-05T11:20:18.050624Z","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-05T11:20:18.050839Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T11:20:18.050868Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T11:20:18.051549Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-05-05T11:20:18.051571Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-05-05T11:20:18.83501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T11:20:18.835075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T11:20:18.835093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-05-05T11:20:18.835106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-05-05T11:20:18.835113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-05-05T11:20:18.835137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-05-05T11:20:18.835148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-05-05T11:20:18.836657Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:20:18.837499Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T11:20:18.837742Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:20:18.837814Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:20:18.837834Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:20:18.837844Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T11:20:18.838122Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T11:20:18.838319Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T11:20:18.838336Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T11:20:18.84024Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T11:20:18.840723Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-05-05T11:20:38.635454Z","caller":"traceutil/trace.go:171","msg":"trace[1204559305] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"114.590104ms","start":"2026-05-05T11:20:38.520839Z","end":"2026-05-05T11:20:38.63543Z","steps":["trace[1204559305] 'process raft request' (duration: 114.468361ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:20:44.29331Z","caller":"traceutil/trace.go:171","msg":"trace[674377678] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"108.587693ms","start":"2026-05-05T11:20:44.184684Z","end":"2026-05-05T11:20:44.293272Z","steps":["trace[674377678] 'process raft request' (duration: 108.270324ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:20:44.417977Z","caller":"traceutil/trace.go:171","msg":"trace[980368472] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"155.596924ms","start":"2026-05-05T11:20:44.262347Z","end":"2026-05-05T11:20:44.417944Z","steps":["trace[980368472] 'process raft request' (duration: 103.419547ms)","trace[980368472] 'compare' (duration: 52.045763ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:20:48.031608Z","caller":"traceutil/trace.go:171","msg":"trace[2088486953] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"109.370166ms","start":"2026-05-05T11:20:47.922187Z","end":"2026-05-05T11:20:48.031557Z","steps":["trace[2088486953] 'process raft request' (duration: 104.133078ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:20:48.03214Z","caller":"traceutil/trace.go:171","msg":"trace[62935066] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"109.097607ms","start":"2026-05-05T11:20:47.923022Z","end":"2026-05-05T11:20:48.03212Z","steps":["trace[62935066] 'process raft request' (duration: 108.633414ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:20:48.809258Z","caller":"traceutil/trace.go:171","msg":"trace[825294503] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"244.399588ms","start":"2026-05-05T11:20:48.564834Z","end":"2026-05-05T11:20:48.809234Z","steps":["trace[825294503] 'process raft request' (duration: 231.252585ms)","trace[825294503] 'compare' (duration: 13.0429ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:20:48.810894Z","caller":"traceutil/trace.go:171","msg":"trace[1179041462] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"222.855238ms","start":"2026-05-05T11:20:48.588013Z","end":"2026-05-05T11:20:48.810868Z","steps":["trace[1179041462] 'process raft request' (duration: 222.502378ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:20:48.812042Z","caller":"traceutil/trace.go:171","msg":"trace[1834707019] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:553; }","duration":"120.086499ms","start":"2026-05-05T11:20:48.691934Z","end":"2026-05-05T11:20:48.81202Z","steps":["trace[1834707019] 'read index received' (duration: 104.24608ms)","trace[1834707019] 'applied index is now lower than readState.Index' (duration: 15.839099ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:20:48.812191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.289555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T11:20:48.81224Z","caller":"traceutil/trace.go:171","msg":"trace[966450241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:533; }","duration":"120.410869ms","start":"2026-05-05T11:20:48.691818Z","end":"2026-05-05T11:20:48.812229Z","steps":["trace[966450241] 'agreement among raft nodes before linearized reading' (duration: 120.291545ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:20:48.812643Z","caller":"traceutil/trace.go:171","msg":"trace[969223102] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"120.930653ms","start":"2026-05-05T11:20:48.691673Z","end":"2026-05-05T11:20:48.812604Z","steps":["trace[969223102] 'process raft request' (duration: 119.486163ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:20:55.083513Z","caller":"traceutil/trace.go:171","msg":"trace[2134269107] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"488.310533ms","start":"2026-05-05T11:20:54.595175Z","end":"2026-05-05T11:20:55.083486Z","steps":["trace[2134269107] 'process raft request' (duration: 488.21073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:20:55.084179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:20:54.595151Z","time spent":"488.456767ms","remote":"127.0.0.1:37618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T11:20:55.08555Z","caller":"traceutil/trace.go:171","msg":"trace[2099289995] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"392.47847ms","start":"2026-05-05T11:20:54.693045Z","end":"2026-05-05T11:20:55.085524Z","steps":["trace[2099289995] 'read index received' (duration: 390.436082ms)","trace[2099289995] 'applied index is now lower than readState.Index' (duration: 2.040998ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:20:55.085707Z","caller":"traceutil/trace.go:171","msg":"trace[546319737] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"443.990236ms","start":"2026-05-05T11:20:54.641668Z","end":"2026-05-05T11:20:55.085658Z","steps":["trace[546319737] 'process raft request' (duration: 443.74246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:20:55.085791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.734116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j7sjw\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-05T11:20:55.085837Z","caller":"traceutil/trace.go:171","msg":"trace[596373392] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j7sjw; range_end:; response_count:1; response_revision:577; }","duration":"392.831789ms","start":"2026-05-05T11:20:54.692989Z","end":"2026-05-05T11:20:55.085821Z","steps":["trace[596373392] 'agreement among raft nodes before linearized reading' (duration: 392.665034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:20:55.085857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.080544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T11:20:55.085915Z","caller":"traceutil/trace.go:171","msg":"trace[1275531349] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:577; }","duration":"253.181736ms","start":"2026-05-05T11:20:54.832716Z","end":"2026-05-05T11:20:55.085898Z","steps":["trace[1275531349] 'agreement among raft nodes before linearized reading' (duration: 253.035912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:20:55.085995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.496263ms","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-05T11:20:55.085874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:20:54.692975Z","time spent":"392.890301ms","remote":"127.0.0.1:37564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-j7sjw\" "} {"level":"info","ts":"2026-05-05T11:20:55.086024Z","caller":"traceutil/trace.go:171","msg":"trace[1778628317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"248.567345ms","start":"2026-05-05T11:20:54.837448Z","end":"2026-05-05T11:20:55.086016Z","steps":["trace[1778628317] 'agreement among raft nodes before linearized reading' (duration: 248.457262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:20:55.086272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:20:54.641638Z","time spent":"444.187222ms","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:21:11.05942Z","caller":"traceutil/trace.go:171","msg":"trace[862886619] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"259.111098ms","start":"2026-05-05T11:21:10.800283Z","end":"2026-05-05T11:21:11.059394Z","steps":["trace[862886619] 'process raft request' (duration: 258.922783ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:21:11.147756Z","caller":"traceutil/trace.go:171","msg":"trace[1431683136] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"131.473099ms","start":"2026-05-05T11:21:11.016248Z","end":"2026-05-05T11:21:11.147721Z","steps":["trace[1431683136] 'process raft request' (duration: 112.034137ms)","trace[1431683136] 'compare' (duration: 19.256266ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:21:38.713541Z","caller":"traceutil/trace.go:171","msg":"trace[502344114] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"171.060351ms","start":"2026-05-05T11:21:38.542445Z","end":"2026-05-05T11:21:38.713506Z","steps":["trace[502344114] 'process raft request' (duration: 170.759853ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:21:41.220567Z","caller":"traceutil/trace.go:171","msg":"trace[367025088] linearizableReadLoop","detail":"{readStateIndex:1179; appliedIndex:1178; }","duration":"125.264233ms","start":"2026-05-05T11:21:41.095279Z","end":"2026-05-05T11:21:41.220543Z","steps":["trace[367025088] 'read index received' (duration: 109.981879ms)","trace[367025088] 'applied index is now lower than readState.Index' (duration: 15.281334ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:21:41.220712Z","caller":"traceutil/trace.go:171","msg":"trace[1722758029] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"132.480557ms","start":"2026-05-05T11:21:41.088119Z","end":"2026-05-05T11:21:41.220599Z","steps":["trace[1722758029] 'process raft request' (duration: 117.237084ms)","trace[1722758029] 'compare' (duration: 15.075738ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:21:41.220851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.546251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck\" ","response":"range_response_count:1 size:1054"} {"level":"info","ts":"2026-05-05T11:21:41.220972Z","caller":"traceutil/trace.go:171","msg":"trace[233397291] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck; range_end:; response_count:1; response_revision:1143; }","duration":"125.664815ms","start":"2026-05-05T11:21:41.095245Z","end":"2026-05-05T11:21:41.22091Z","steps":["trace[233397291] 'agreement among raft nodes before linearized reading' (duration: 125.387357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:22:26.348165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.435051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:22:26.348429Z","caller":"traceutil/trace.go:171","msg":"trace[1034528629] linearizableReadLoop","detail":"{readStateIndex:1880; appliedIndex:1877; }","duration":"174.015047ms","start":"2026-05-05T11:22:26.174392Z","end":"2026-05-05T11:22:26.348407Z","steps":["trace[1034528629] 'read index received' (duration: 118.850845ms)","trace[1034528629] 'applied index is now lower than readState.Index' (duration: 55.162712ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:22:26.348529Z","caller":"traceutil/trace.go:171","msg":"trace[960983340] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"267.795589ms","start":"2026-05-05T11:22:26.08072Z","end":"2026-05-05T11:22:26.348515Z","steps":["trace[960983340] 'process raft request' (duration: 267.577323ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:22:26.348693Z","caller":"traceutil/trace.go:171","msg":"trace[1752697376] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"363.369391ms","start":"2026-05-05T11:22:25.98529Z","end":"2026-05-05T11:22:26.34866Z","steps":["trace[1752697376] 'process raft request' (duration: 110.144538ms)","trace[1752697376] 'compare' (duration: 252.147593ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:22:26.348866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:22:25.98526Z","time spent":"363.510475ms","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:22:26.348949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.537912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c43ba9c0-6486-4350-beb1-a0a148a02245\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-05T11:22:26.349115Z","caller":"traceutil/trace.go:171","msg":"trace[1965118110] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c43ba9c0-6486-4350-beb1-a0a148a02245; range_end:; response_count:1; response_revision:1807; }","duration":"174.729217ms","start":"2026-05-05T11:22:26.174358Z","end":"2026-05-05T11:22:26.349087Z","steps":["trace[1965118110] 'agreement among raft nodes before linearized reading' (duration: 174.436979ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:22:26.348975Z","caller":"traceutil/trace.go:171","msg":"trace[1406067522] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"263.835845ms","start":"2026-05-05T11:22:26.085117Z","end":"2026-05-05T11:22:26.348953Z","steps":["trace[1406067522] 'process raft request' (duration: 263.242069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:22:26.35805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.232773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:22:26.35812Z","caller":"traceutil/trace.go:171","msg":"trace[15086263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1809; }","duration":"141.347306ms","start":"2026-05-05T11:22:26.21676Z","end":"2026-05-05T11:22:26.358107Z","steps":["trace[15086263] 'agreement among raft nodes before linearized reading' (duration: 141.195922ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:22:26.358197Z","caller":"traceutil/trace.go:171","msg":"trace[547249426] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"126.33478ms","start":"2026-05-05T11:22:26.231847Z","end":"2026-05-05T11:22:26.358182Z","steps":["trace[547249426] 'process raft request' (duration: 126.065501ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:22:26.358423Z","caller":"traceutil/trace.go:171","msg":"trace[396742856] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"141.09543ms","start":"2026-05-05T11:22:26.217303Z","end":"2026-05-05T11:22:26.358398Z","steps":["trace[396742856] 'process raft request' (duration: 140.520383ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:22:38.535373Z","caller":"traceutil/trace.go:171","msg":"trace[1864989911] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"142.852589ms","start":"2026-05-05T11:22:38.392494Z","end":"2026-05-05T11:22:38.535346Z","steps":["trace[1864989911] 'process raft request' (duration: 142.686155ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:22:38.872459Z","caller":"traceutil/trace.go:171","msg":"trace[1594859381] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"462.612289ms","start":"2026-05-05T11:22:38.409822Z","end":"2026-05-05T11:22:38.872434Z","steps":["trace[1594859381] 'process raft request' (duration: 462.392822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:22:38.872603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:22:38.409805Z","time spent":"462.727371ms","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:22:38.872606Z","caller":"traceutil/trace.go:171","msg":"trace[1168798286] linearizableReadLoop","detail":"{readStateIndex:1988; appliedIndex:1985; }","duration":"338.422211ms","start":"2026-05-05T11:22:38.534166Z","end":"2026-05-05T11:22:38.872589Z","steps":["trace[1168798286] 'read index received' (duration: 997.708µs)","trace[1168798286] 'applied index is now lower than readState.Index' (duration: 337.423423ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:22:38.872834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.663837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-05T11:22:38.872901Z","caller":"traceutil/trace.go:171","msg":"trace[646425689] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1911; }","duration":"338.794371ms","start":"2026-05-05T11:22:38.534087Z","end":"2026-05-05T11:22:38.872882Z","steps":["trace[646425689] 'agreement among raft nodes before linearized reading' (duration: 338.556385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:22:38.872951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:22:38.534067Z","time spent":"338.871324ms","remote":"127.0.0.1:57486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-05T11:22:38.873098Z","caller":"traceutil/trace.go:171","msg":"trace[1909225645] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"456.464183ms","start":"2026-05-05T11:22:38.416596Z","end":"2026-05-05T11:22:38.87306Z","steps":["trace[1909225645] 'process raft request' (duration: 455.786914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:22:38.873268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:22:38.416582Z","time spent":"456.596338ms","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:22:38.890801Z","caller":"traceutil/trace.go:171","msg":"trace[2130771964] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"134.209834ms","start":"2026-05-05T11:22:38.756571Z","end":"2026-05-05T11:22:38.890781Z","steps":["trace[2130771964] 'process raft request' (duration: 134.105901ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:23:15.389704Z","caller":"traceutil/trace.go:171","msg":"trace[1763231995] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"106.629603ms","start":"2026-05-05T11:23:15.283051Z","end":"2026-05-05T11:23:15.389681Z","steps":["trace[1763231995] 'process raft request' (duration: 106.52344ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:23:15.395452Z","caller":"traceutil/trace.go:171","msg":"trace[2111520494] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"110.72826ms","start":"2026-05-05T11:23:15.284701Z","end":"2026-05-05T11:23:15.39543Z","steps":["trace[2111520494] 'process raft request' (duration: 110.476003ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:23:21.21129Z","caller":"traceutil/trace.go:171","msg":"trace[703168492] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"187.122493ms","start":"2026-05-05T11:23:21.024133Z","end":"2026-05-05T11:23:21.211255Z","steps":["trace[703168492] 'process raft request' (duration: 186.882926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:23:21.474444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.56267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:23:21.474791Z","caller":"traceutil/trace.go:171","msg":"trace[523681751] linearizableReadLoop","detail":"{readStateIndex:2238; appliedIndex:2235; }","duration":"296.03151ms","start":"2026-05-05T11:23:21.178742Z","end":"2026-05-05T11:23:21.474773Z","steps":["trace[523681751] 'read index received' (duration: 32.34196ms)","trace[523681751] 'applied index is now lower than readState.Index' (duration: 263.68795ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:23:21.474811Z","caller":"traceutil/trace.go:171","msg":"trace[86334451] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"422.119316ms","start":"2026-05-05T11:23:21.052648Z","end":"2026-05-05T11:23:21.474767Z","steps":["trace[86334451] 'process raft request' (duration: 186.071512ms)","trace[86334451] 'compare' (duration: 235.436217ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:23:21.47483Z","caller":"traceutil/trace.go:171","msg":"trace[1546828833] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"421.905721ms","start":"2026-05-05T11:23:21.05289Z","end":"2026-05-05T11:23:21.474795Z","steps":["trace[1546828833] 'process raft request' (duration: 421.698735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:23:21.474987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:23:21.052622Z","time spent":"422.268541ms","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:23:21.475004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:23:21.052868Z","time spent":"422.043524ms","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:23:21.475027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.542726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:23:21.475062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.503834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T11:23:21.475125Z","caller":"traceutil/trace.go:171","msg":"trace[315099327] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:2153; }","duration":"174.605777ms","start":"2026-05-05T11:23:21.300501Z","end":"2026-05-05T11:23:21.475107Z","steps":["trace[315099327] 'agreement among raft nodes before linearized reading' (duration: 174.503904ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:23:21.475117Z","caller":"traceutil/trace.go:171","msg":"trace[1644135161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2153; }","duration":"256.66797ms","start":"2026-05-05T11:23:21.218433Z","end":"2026-05-05T11:23:21.475101Z","steps":["trace[1644135161] 'agreement among raft nodes before linearized reading' (duration: 256.492575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:23:21.475262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.357239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-05T11:23:21.4754Z","caller":"traceutil/trace.go:171","msg":"trace[892027991] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2153; }","duration":"296.679708ms","start":"2026-05-05T11:23:21.178689Z","end":"2026-05-05T11:23:21.475368Z","steps":["trace[892027991] 'agreement among raft nodes before linearized reading' (duration: 296.136973ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:23:21.652616Z","caller":"traceutil/trace.go:171","msg":"trace[286158188] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"172.96348ms","start":"2026-05-05T11:23:21.479626Z","end":"2026-05-05T11:23:21.652589Z","steps":["trace[286158188] 'process raft request' (duration: 159.350413ms)","trace[286158188] 'compare' (duration: 13.490313ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:23:56.604369Z","caller":"traceutil/trace.go:171","msg":"trace[523241580] transaction","detail":"{read_only:false; response_revision:2520; number_of_response:1; }","duration":"158.895259ms","start":"2026-05-05T11:23:56.445442Z","end":"2026-05-05T11:23:56.604337Z","steps":["trace[523241580] 'process raft request' (duration: 158.698013ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:27:06.193639Z","caller":"traceutil/trace.go:171","msg":"trace[712143775] transaction","detail":"{read_only:false; response_revision:3744; number_of_response:1; }","duration":"116.970056ms","start":"2026-05-05T11:27:06.076634Z","end":"2026-05-05T11:27:06.193604Z","steps":["trace[712143775] 'process raft request' (duration: 116.76776ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:28:26.084364Z","caller":"traceutil/trace.go:171","msg":"trace[239303865] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"121.629644ms","start":"2026-05-05T11:28:25.962704Z","end":"2026-05-05T11:28:26.084334Z","steps":["trace[239303865] 'process raft request' (duration: 121.444678ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:28:26.111734Z","caller":"traceutil/trace.go:171","msg":"trace[457202337] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"146.831875ms","start":"2026-05-05T11:28:25.964849Z","end":"2026-05-05T11:28:26.111681Z","steps":["trace[457202337] 'process raft request' (duration: 125.636217ms)","trace[457202337] 'compare' (duration: 21.067064ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:28:29.199395Z","caller":"traceutil/trace.go:171","msg":"trace[490058427] linearizableReadLoop","detail":"{readStateIndex:5087; appliedIndex:5086; }","duration":"198.72064ms","start":"2026-05-05T11:28:29.000658Z","end":"2026-05-05T11:28:29.199379Z","steps":["trace[490058427] 'read index received' (duration: 198.648278ms)","trace[490058427] 'applied index is now lower than readState.Index' (duration: 71.272µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:28:29.199476Z","caller":"traceutil/trace.go:171","msg":"trace[137165035] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"199.835372ms","start":"2026-05-05T11:28:28.999617Z","end":"2026-05-05T11:28:29.199452Z","steps":["trace[137165035] 'process raft request' (duration: 199.649577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:28:29.199647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.950348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:28:29.199735Z","caller":"traceutil/trace.go:171","msg":"trace[1741256720] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4897; }","duration":"199.083842ms","start":"2026-05-05T11:28:29.000632Z","end":"2026-05-05T11:28:29.199716Z","steps":["trace[1741256720] 'agreement among raft nodes before linearized reading' (duration: 198.861635ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:28:29.22476Z","caller":"traceutil/trace.go:171","msg":"trace[897040894] transaction","detail":"{read_only:false; response_revision:4899; number_of_response:1; }","duration":"220.883396ms","start":"2026-05-05T11:28:29.003832Z","end":"2026-05-05T11:28:29.224715Z","steps":["trace[897040894] 'process raft request' (duration: 220.66592ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:28:29.224891Z","caller":"traceutil/trace.go:171","msg":"trace[1922535489] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"224.17563ms","start":"2026-05-05T11:28:29.000648Z","end":"2026-05-05T11:28:29.224824Z","steps":["trace[1922535489] 'process raft request' (duration: 223.744538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:28:29.22493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.863499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T11:28:29.22501Z","caller":"traceutil/trace.go:171","msg":"trace[15411688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4900; }","duration":"128.986232ms","start":"2026-05-05T11:28:29.096005Z","end":"2026-05-05T11:28:29.224991Z","steps":["trace[15411688] 'agreement among raft nodes before linearized reading' (duration: 128.765185ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:28:40.487172Z","caller":"traceutil/trace.go:171","msg":"trace[1825438686] linearizableReadLoop","detail":"{readStateIndex:5325; appliedIndex:5324; }","duration":"263.210553ms","start":"2026-05-05T11:28:40.223901Z","end":"2026-05-05T11:28:40.487112Z","steps":["trace[1825438686] 'read index received' (duration: 89.513µs)","trace[1825438686] 'applied index is now lower than readState.Index' (duration: 263.11979ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:28:40.48731Z","caller":"traceutil/trace.go:171","msg":"trace[96406455] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"263.386687ms","start":"2026-05-05T11:28:40.223875Z","end":"2026-05-05T11:28:40.487262Z","steps":["trace[96406455] 'process raft request' (duration: 263.077288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:28:40.487553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.590953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130095"} {"level":"info","ts":"2026-05-05T11:28:40.487604Z","caller":"traceutil/trace.go:171","msg":"trace[549693217] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5128; }","duration":"263.718967ms","start":"2026-05-05T11:28:40.223871Z","end":"2026-05-05T11:28:40.48759Z","steps":["trace[549693217] 'agreement among raft nodes before linearized reading' (duration: 263.409638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:28:40.492826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.845141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:28:40.492887Z","caller":"traceutil/trace.go:171","msg":"trace[607519303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5130; }","duration":"268.925783ms","start":"2026-05-05T11:28:40.223948Z","end":"2026-05-05T11:28:40.492874Z","steps":["trace[607519303] 'agreement among raft nodes before linearized reading' (duration: 268.81499ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:28:40.492948Z","caller":"traceutil/trace.go:171","msg":"trace[81153687] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"268.953234ms","start":"2026-05-05T11:28:40.223954Z","end":"2026-05-05T11:28:40.492908Z","steps":["trace[81153687] 'process raft request' (duration: 268.665716ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:28:40.493023Z","caller":"traceutil/trace.go:171","msg":"trace[1912219704] transaction","detail":"{read_only:false; response_revision:5130; number_of_response:1; }","duration":"268.989945ms","start":"2026-05-05T11:28:40.223998Z","end":"2026-05-05T11:28:40.492988Z","steps":["trace[1912219704] 'process raft request' (duration: 268.718907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:28:40.493076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.690548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tj689\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-05-05T11:28:40.49314Z","caller":"traceutil/trace.go:171","msg":"trace[1386949453] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tj689; range_end:; response_count:1; response_revision:5130; }","duration":"166.771111ms","start":"2026-05-05T11:28:40.326355Z","end":"2026-05-05T11:28:40.493126Z","steps":["trace[1386949453] 'agreement among raft nodes before linearized reading' (duration: 166.453252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:28:40.493159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.660691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" ","response":"range_response_count:12 size:4407"} {"level":"info","ts":"2026-05-05T11:28:40.493185Z","caller":"traceutil/trace.go:171","msg":"trace[411474123] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:12; response_revision:5130; }","duration":"249.703722ms","start":"2026-05-05T11:28:40.243474Z","end":"2026-05-05T11:28:40.493177Z","steps":["trace[411474123] 'agreement among raft nodes before linearized reading' (duration: 249.553168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:28:40.493205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.629933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17318"} {"level":"info","ts":"2026-05-05T11:28:40.493274Z","caller":"traceutil/trace.go:171","msg":"trace[780373385] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5130; }","duration":"247.708505ms","start":"2026-05-05T11:28:40.24555Z","end":"2026-05-05T11:28:40.493259Z","steps":["trace[780373385] 'agreement among raft nodes before linearized reading' (duration: 247.50941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:28:40.493548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.044326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" ","response":"range_response_count:43 size:291830"} {"level":"info","ts":"2026-05-05T11:28:40.49365Z","caller":"traceutil/trace.go:171","msg":"trace[1496855981] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:43; response_revision:5130; }","duration":"248.17991ms","start":"2026-05-05T11:28:40.245451Z","end":"2026-05-05T11:28:40.493631Z","steps":["trace[1496855981] 'agreement among raft nodes before linearized reading' (duration: 247.573563ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:28:54.88288Z","caller":"traceutil/trace.go:171","msg":"trace[1556289683] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"106.106304ms","start":"2026-05-05T11:28:54.77673Z","end":"2026-05-05T11:28:54.882836Z","steps":["trace[1556289683] 'process raft request' (duration: 105.61363ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:30:18.864099Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2990} {"level":"info","ts":"2026-05-05T11:30:18.937711Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2990,"took":"71.978558ms","hash":4270672184,"current-db-size-bytes":59326464,"current-db-size":"59 MB","current-db-size-in-use-bytes":34127872,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-05T11:30:18.937779Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4270672184,"revision":2990,"compact-revision":-1} {"level":"info","ts":"2026-05-05T11:34:56.257075Z","caller":"traceutil/trace.go:171","msg":"trace[547900962] transaction","detail":"{read_only:false; response_revision:8142; number_of_response:1; }","duration":"380.239492ms","start":"2026-05-05T11:34:55.876806Z","end":"2026-05-05T11:34:56.257046Z","steps":["trace[547900962] 'process raft request' (duration: 380.063648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:34:56.257276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:34:55.876782Z","time spent":"380.391466ms","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:34:56.25783Z","caller":"traceutil/trace.go:171","msg":"trace[2104647801] transaction","detail":"{read_only:false; response_revision:8143; number_of_response:1; }","duration":"235.964397ms","start":"2026-05-05T11:34:56.021831Z","end":"2026-05-05T11:34:56.257796Z","steps":["trace[2104647801] 'process raft request' (duration: 235.764242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:07.464279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.154321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T11:35:07.464354Z","caller":"traceutil/trace.go:171","msg":"trace[456454037] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8202; }","duration":"110.277404ms","start":"2026-05-05T11:35:07.354061Z","end":"2026-05-05T11:35:07.464338Z","steps":["trace[456454037] 'range keys from in-memory index tree' (duration: 110.006956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:07.464565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.285438ms","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-05T11:35:07.464636Z","caller":"traceutil/trace.go:171","msg":"trace[218325033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8202; }","duration":"114.424812ms","start":"2026-05-05T11:35:07.350194Z","end":"2026-05-05T11:35:07.464619Z","steps":["trace[218325033] 'range keys from in-memory index tree' (duration: 114.177825ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:35:18.87157Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6097} {"level":"info","ts":"2026-05-05T11:35:18.972988Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6097,"took":"99.355724ms","hash":1877522150,"current-db-size-bytes":59326464,"current-db-size":"59 MB","current-db-size-in-use-bytes":22962176,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-05T11:35:18.973065Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1877522150,"revision":6097,"compact-revision":2990} {"level":"warn","ts":"2026-05-05T11:35:35.62156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.82321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:35:35.621657Z","caller":"traceutil/trace.go:171","msg":"trace[2100214446] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8407; }","duration":"165.965244ms","start":"2026-05-05T11:35:35.455672Z","end":"2026-05-05T11:35:35.621638Z","steps":["trace[2100214446] 'range keys from in-memory index tree' (duration: 165.751147ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:35:37.747249Z","caller":"traceutil/trace.go:171","msg":"trace[715180383] transaction","detail":"{read_only:false; response_revision:8416; number_of_response:1; }","duration":"410.734732ms","start":"2026-05-05T11:35:37.336495Z","end":"2026-05-05T11:35:37.747229Z","steps":["trace[715180383] 'process raft request' (duration: 410.533006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:37.747584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:37.336477Z","time spent":"410.959578ms","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:35:38.198709Z","caller":"traceutil/trace.go:171","msg":"trace[1360183834] linearizableReadLoop","detail":"{readStateIndex:8720; appliedIndex:8718; }","duration":"571.053123ms","start":"2026-05-05T11:35:37.627627Z","end":"2026-05-05T11:35:38.19868Z","steps":["trace[1360183834] 'read index received' (duration: 119.339962ms)","trace[1360183834] 'applied index is now lower than readState.Index' (duration: 451.711631ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:35:38.198728Z","caller":"traceutil/trace.go:171","msg":"trace[532555297] transaction","detail":"{read_only:false; response_revision:8417; number_of_response:1; }","duration":"827.631091ms","start":"2026-05-05T11:35:37.37106Z","end":"2026-05-05T11:35:38.198691Z","steps":["trace[532555297] 'process raft request' (duration: 795.184375ms)","trace[532555297] 'compare' (duration: 32.230299ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:35:38.198876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:37.371039Z","time spent":"827.755354ms","remote":"127.0.0.1:37636","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-05T11:35:38.198997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.890085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:35:38.199195Z","caller":"traceutil/trace.go:171","msg":"trace[1288588928] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:8417; }","duration":"513.173164ms","start":"2026-05-05T11:35:37.685998Z","end":"2026-05-05T11:35:38.199172Z","steps":["trace[1288588928] 'agreement among raft nodes before linearized reading' (duration: 512.863796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:38.199246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.406814ms","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-05T11:35:38.199321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:37.685979Z","time spent":"513.321138ms","remote":"127.0.0.1:40438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"warn","ts":"2026-05-05T11:35:38.199369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.25601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-05T11:35:38.199527Z","caller":"traceutil/trace.go:171","msg":"trace[2053345355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8417; }","duration":"254.450826ms","start":"2026-05-05T11:35:37.945059Z","end":"2026-05-05T11:35:38.19951Z","steps":["trace[2053345355] 'agreement among raft nodes before linearized reading' (duration: 254.133547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:38.199332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.705982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T11:35:38.199651Z","caller":"traceutil/trace.go:171","msg":"trace[86595557] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:8417; }","duration":"572.041542ms","start":"2026-05-05T11:35:37.627573Z","end":"2026-05-05T11:35:38.199614Z","steps":["trace[86595557] 'agreement among raft nodes before linearized reading' (duration: 571.25595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:38.199722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:37.627553Z","time spent":"572.146885ms","remote":"127.0.0.1:37490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":863,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-05T11:35:38.199246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.168786ms","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-05T11:35:38.19989Z","caller":"traceutil/trace.go:171","msg":"trace[192889201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8417; }","duration":"276.856645ms","start":"2026-05-05T11:35:37.923022Z","end":"2026-05-05T11:35:38.199879Z","steps":["trace[192889201] 'agreement among raft nodes before linearized reading' (duration: 276.073893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:38.199284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.465071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T11:35:38.199933Z","caller":"traceutil/trace.go:171","msg":"trace[1306856915] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8417; }","duration":"494.13541ms","start":"2026-05-05T11:35:37.70578Z","end":"2026-05-05T11:35:38.199915Z","steps":["trace[1306856915] 'agreement among raft nodes before linearized reading' (duration: 493.42456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:38.199969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:37.705764Z","time spent":"494.193221ms","remote":"127.0.0.1:37636","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":"info","ts":"2026-05-05T11:35:38.199343Z","caller":"traceutil/trace.go:171","msg":"trace[97296366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8417; }","duration":"430.524657ms","start":"2026-05-05T11:35:37.768788Z","end":"2026-05-05T11:35:38.199312Z","steps":["trace[97296366] 'agreement among raft nodes before linearized reading' (duration: 430.326692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:38.20003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:37.76877Z","time spent":"431.249817ms","remote":"127.0.0.1:37636","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-05T11:35:38.199248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.067499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T11:35:38.20015Z","caller":"traceutil/trace.go:171","msg":"trace[918398850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8417; }","duration":"387.019146ms","start":"2026-05-05T11:35:37.813123Z","end":"2026-05-05T11:35:38.200142Z","steps":["trace[918398850] 'agreement among raft nodes before linearized reading' (duration: 386.025148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:38.200181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:37.813104Z","time spent":"387.068907ms","remote":"127.0.0.1:37636","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":"info","ts":"2026-05-05T11:35:42.079637Z","caller":"traceutil/trace.go:171","msg":"trace[1115859518] transaction","detail":"{read_only:false; response_revision:8436; number_of_response:1; }","duration":"171.227273ms","start":"2026-05-05T11:35:41.90838Z","end":"2026-05-05T11:35:42.079607Z","steps":["trace[1115859518] 'process raft request' (duration: 120.428174ms)","trace[1115859518] 'compare' (duration: 50.418358ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:35:42.552219Z","caller":"traceutil/trace.go:171","msg":"trace[1864983603] transaction","detail":"{read_only:false; response_revision:8437; number_of_response:1; }","duration":"329.099215ms","start":"2026-05-05T11:35:42.223086Z","end":"2026-05-05T11:35:42.552185Z","steps":["trace[1864983603] 'process raft request' (duration: 328.903749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:42.552418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:42.223059Z","time spent":"329.250209ms","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:35:42.552603Z","caller":"traceutil/trace.go:171","msg":"trace[2088019012] linearizableReadLoop","detail":"{readStateIndex:8741; appliedIndex:8741; }","duration":"310.012951ms","start":"2026-05-05T11:35:42.242548Z","end":"2026-05-05T11:35:42.552561Z","steps":["trace[2088019012] 'read index received' (duration: 310.002771ms)","trace[2088019012] 'applied index is now lower than readState.Index' (duration: 8.08µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:35:42.552774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.226247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T11:35:42.552831Z","caller":"traceutil/trace.go:171","msg":"trace[57042085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8437; }","duration":"310.321739ms","start":"2026-05-05T11:35:42.242495Z","end":"2026-05-05T11:35:42.552817Z","steps":["trace[57042085] 'agreement among raft nodes before linearized reading' (duration: 310.153945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:42.552865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:42.242475Z","time spent":"310.383511ms","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T11:35:42.602114Z","caller":"traceutil/trace.go:171","msg":"trace[677553292] transaction","detail":"{read_only:false; response_revision:8438; number_of_response:1; }","duration":"266.214382ms","start":"2026-05-05T11:35:42.335874Z","end":"2026-05-05T11:35:42.602088Z","steps":["trace[677553292] 'process raft request' (duration: 266.022866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:42.602452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.129796ms","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-05T11:35:42.602513Z","caller":"traceutil/trace.go:171","msg":"trace[896997731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8440; }","duration":"342.217109ms","start":"2026-05-05T11:35:42.26028Z","end":"2026-05-05T11:35:42.602497Z","steps":["trace[896997731] 'agreement among raft nodes before linearized reading' (duration: 342.052834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:42.602552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:42.260264Z","time spent":"342.279882ms","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T11:35:42.602523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.777534ms","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":"warn","ts":"2026-05-05T11:35:42.602584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.896617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T11:35:42.60261Z","caller":"traceutil/trace.go:171","msg":"trace[1988122117] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8440; }","duration":"309.897577ms","start":"2026-05-05T11:35:42.292694Z","end":"2026-05-05T11:35:42.602591Z","steps":["trace[1988122117] 'agreement among raft nodes before linearized reading' (duration: 309.669349ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:35:42.602631Z","caller":"traceutil/trace.go:171","msg":"trace[2129163594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8440; }","duration":"310.022321ms","start":"2026-05-05T11:35:42.292595Z","end":"2026-05-05T11:35:42.602618Z","steps":["trace[2129163594] 'agreement among raft nodes before linearized reading' (duration: 309.912347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:42.602658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:42.292567Z","time spent":"310.082182ms","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T11:35:42.602663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:42.292678Z","time spent":"309.97188ms","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T11:35:42.602758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.098582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T11:35:42.602772Z","caller":"traceutil/trace.go:171","msg":"trace[952890557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8440; }","duration":"310.157295ms","start":"2026-05-05T11:35:42.29261Z","end":"2026-05-05T11:35:42.602768Z","steps":["trace[952890557] 'agreement among raft nodes before linearized reading' (duration: 310.118864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:42.602783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:42.292554Z","time spent":"310.226486ms","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T11:35:42.810157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.690689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:35:42.810367Z","caller":"traceutil/trace.go:171","msg":"trace[908074170] transaction","detail":"{read_only:false; response_revision:8442; number_of_response:1; }","duration":"254.393374ms","start":"2026-05-05T11:35:42.555956Z","end":"2026-05-05T11:35:42.810349Z","steps":["trace[908074170] 'process raft request' (duration: 254.299701ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:35:42.81057Z","caller":"traceutil/trace.go:171","msg":"trace[95066106] transaction","detail":"{read_only:false; response_revision:8441; number_of_response:1; }","duration":"255.017102ms","start":"2026-05-05T11:35:42.555502Z","end":"2026-05-05T11:35:42.810519Z","steps":["trace[95066106] 'process raft request' (duration: 131.87251ms)","trace[95066106] 'compare' (duration: 122.369209ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:35:42.824158Z","caller":"traceutil/trace.go:171","msg":"trace[1472236961] transaction","detail":"{read_only:false; response_revision:8446; number_of_response:1; }","duration":"216.04434ms","start":"2026-05-05T11:35:42.608089Z","end":"2026-05-05T11:35:42.824133Z","steps":["trace[1472236961] 'process raft request' (duration: 215.986748ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:35:42.824654Z","caller":"traceutil/trace.go:171","msg":"trace[991472297] transaction","detail":"{read_only:false; response_revision:8443; number_of_response:1; }","duration":"217.247085ms","start":"2026-05-05T11:35:42.607394Z","end":"2026-05-05T11:35:42.824641Z","steps":["trace[991472297] 'process raft request' (duration: 216.292987ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:35:42.824828Z","caller":"traceutil/trace.go:171","msg":"trace[949688528] transaction","detail":"{read_only:false; response_revision:8444; number_of_response:1; }","duration":"217.383299ms","start":"2026-05-05T11:35:42.607435Z","end":"2026-05-05T11:35:42.824819Z","steps":["trace[949688528] 'process raft request' (duration: 216.560666ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:35:42.82495Z","caller":"traceutil/trace.go:171","msg":"trace[343638770] transaction","detail":"{read_only:false; response_revision:8445; number_of_response:1; }","duration":"217.507213ms","start":"2026-05-05T11:35:42.607435Z","end":"2026-05-05T11:35:42.824942Z","steps":["trace[343638770] 'process raft request' (duration: 216.602347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:43.573585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.598774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tj689\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-05-05T11:35:43.573648Z","caller":"traceutil/trace.go:171","msg":"trace[1997102178] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tj689; range_end:; response_count:1; response_revision:8446; }","duration":"642.709307ms","start":"2026-05-05T11:35:42.930921Z","end":"2026-05-05T11:35:43.57363Z","steps":["trace[1997102178] 'range keys from in-memory index tree' (duration: 642.456179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:43.573678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:42.930902Z","time spent":"642.769099ms","remote":"127.0.0.1:37564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-tj689\" "} {"level":"warn","ts":"2026-05-05T11:35:43.573747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.240428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:35:43.573807Z","caller":"traceutil/trace.go:171","msg":"trace[1150496111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8446; }","duration":"356.342641ms","start":"2026-05-05T11:35:43.217451Z","end":"2026-05-05T11:35:43.573793Z","steps":["trace[1150496111] 'range keys from in-memory index tree' (duration: 356.162996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:43.573848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:43.217431Z","time spent":"356.410272ms","remote":"127.0.0.1:37380","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-05T11:35:43.784656Z","caller":"traceutil/trace.go:171","msg":"trace[1197535400] linearizableReadLoop","detail":"{readStateIndex:8751; appliedIndex:8750; }","duration":"244.974225ms","start":"2026-05-05T11:35:43.539662Z","end":"2026-05-05T11:35:43.784636Z","steps":["trace[1197535400] 'read index received' (duration: 244.755289ms)","trace[1197535400] 'applied index is now lower than readState.Index' (duration: 217.816µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:35:43.784764Z","caller":"traceutil/trace.go:171","msg":"trace[1070186579] transaction","detail":"{read_only:false; response_revision:8447; number_of_response:1; }","duration":"323.380381ms","start":"2026-05-05T11:35:43.461376Z","end":"2026-05-05T11:35:43.784757Z","steps":["trace[1070186579] 'process raft request' (duration: 323.109713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:43.784851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:43.461352Z","time spent":"323.432503ms","remote":"127.0.0.1:58262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3657,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:35:43.784891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.122501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:35:43.784943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.276705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T11:35:43.784948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.448771ms","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-05T11:35:43.784976Z","caller":"traceutil/trace.go:171","msg":"trace[1278905222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8447; }","duration":"210.262336ms","start":"2026-05-05T11:35:43.574691Z","end":"2026-05-05T11:35:43.784953Z","steps":["trace[1278905222] 'agreement among raft nodes before linearized reading' (duration: 210.111842ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:35:43.784994Z","caller":"traceutil/trace.go:171","msg":"trace[939354905] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:8447; }","duration":"245.378878ms","start":"2026-05-05T11:35:43.539604Z","end":"2026-05-05T11:35:43.784983Z","steps":["trace[939354905] 'agreement among raft nodes before linearized reading' (duration: 245.286785ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:35:43.785008Z","caller":"traceutil/trace.go:171","msg":"trace[1646150119] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8447; }","duration":"109.544034ms","start":"2026-05-05T11:35:43.675449Z","end":"2026-05-05T11:35:43.784993Z","steps":["trace[1646150119] 'agreement among raft nodes before linearized reading' (duration: 109.435931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:43.785318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.525752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228845"} {"level":"info","ts":"2026-05-05T11:35:43.785427Z","caller":"traceutil/trace.go:171","msg":"trace[1487780960] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8447; }","duration":"205.665285ms","start":"2026-05-05T11:35:43.579733Z","end":"2026-05-05T11:35:43.785398Z","steps":["trace[1487780960] 'agreement among raft nodes before linearized reading' (duration: 205.148601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:44.056604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.831126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:35:44.056701Z","caller":"traceutil/trace.go:171","msg":"trace[499598103] transaction","detail":"{read_only:false; response_revision:8448; number_of_response:1; }","duration":"261.437166ms","start":"2026-05-05T11:35:43.795248Z","end":"2026-05-05T11:35:44.056685Z","steps":["trace[499598103] 'process raft request' (duration: 113.442405ms)","trace[499598103] 'compare' (duration: 147.715583ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:35:44.837296Z","caller":"traceutil/trace.go:171","msg":"trace[432553441] transaction","detail":"{read_only:false; response_revision:8450; number_of_response:1; }","duration":"278.669036ms","start":"2026-05-05T11:35:44.558604Z","end":"2026-05-05T11:35:44.837273Z","steps":["trace[432553441] 'process raft request' (duration: 278.48827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:45.067534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.930555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:35:45.067739Z","caller":"traceutil/trace.go:171","msg":"trace[1138102243] transaction","detail":"{read_only:false; response_revision:8451; number_of_response:1; }","duration":"460.302306ms","start":"2026-05-05T11:35:44.607413Z","end":"2026-05-05T11:35:45.067715Z","steps":["trace[1138102243] 'process raft request' (duration: 303.074922ms)","trace[1138102243] 'compare' (duration: 156.736879ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:35:45.067826Z","caller":"traceutil/trace.go:171","msg":"trace[3262975] transaction","detail":"{read_only:false; response_revision:8452; number_of_response:1; }","duration":"459.873925ms","start":"2026-05-05T11:35:44.607934Z","end":"2026-05-05T11:35:45.067808Z","steps":["trace[3262975] 'process raft request' (duration: 459.717409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:45.067848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:44.607385Z","time spent":"460.401829ms","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:35:45.067899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:44.607922Z","time spent":"459.942276ms","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:35:45.067959Z","caller":"traceutil/trace.go:171","msg":"trace[1764920567] transaction","detail":"{read_only:false; response_revision:8453; number_of_response:1; }","duration":"252.531502ms","start":"2026-05-05T11:35:44.815409Z","end":"2026-05-05T11:35:45.06794Z","steps":["trace[1764920567] 'process raft request' (duration: 252.358217ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:35:45.067963Z","caller":"traceutil/trace.go:171","msg":"trace[1250707052] linearizableReadLoop","detail":"{readStateIndex:8756; appliedIndex:8753; }","duration":"252.926233ms","start":"2026-05-05T11:35:44.815006Z","end":"2026-05-05T11:35:45.067932Z","steps":["trace[1250707052] 'read index received' (duration: 22.177983ms)","trace[1250707052] 'applied index is now lower than readState.Index' (duration: 230.74615ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:35:45.068176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.024187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-05T11:35:45.068173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.156439ms","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-05-05T11:35:45.0682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.854961ms","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-05T11:35:45.068245Z","caller":"traceutil/trace.go:171","msg":"trace[2091958006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8453; }","duration":"253.256933ms","start":"2026-05-05T11:35:44.814968Z","end":"2026-05-05T11:35:45.068225Z","steps":["trace[2091958006] 'agreement among raft nodes before linearized reading' (duration: 253.063687ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:35:45.068257Z","caller":"traceutil/trace.go:171","msg":"trace[1977062224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8453; }","duration":"238.952285ms","start":"2026-05-05T11:35:44.82929Z","end":"2026-05-05T11:35:45.068242Z","steps":["trace[1977062224] 'agreement among raft nodes before linearized reading' (duration: 238.78877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:45.06826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.477531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-05T11:35:45.068298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.588794ms","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-05T11:35:45.068317Z","caller":"traceutil/trace.go:171","msg":"trace[648533533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8453; }","duration":"238.607594ms","start":"2026-05-05T11:35:44.829705Z","end":"2026-05-05T11:35:45.068312Z","steps":["trace[648533533] 'agreement among raft nodes before linearized reading' (duration: 238.513711ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:35:45.068201Z","caller":"traceutil/trace.go:171","msg":"trace[976570397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8453; }","duration":"238.08942ms","start":"2026-05-05T11:35:44.830106Z","end":"2026-05-05T11:35:45.068195Z","steps":["trace[976570397] 'agreement among raft nodes before linearized reading' (duration: 238.001587ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:35:45.068324Z","caller":"traceutil/trace.go:171","msg":"trace[804186168] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8453; }","duration":"238.578633ms","start":"2026-05-05T11:35:44.829728Z","end":"2026-05-05T11:35:45.068307Z","steps":["trace[804186168] 'agreement among raft nodes before linearized reading' (duration: 238.357247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:45.71906Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102860646564106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T11:35:46.266073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.105482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:35:46.266343Z","caller":"traceutil/trace.go:171","msg":"trace[1950654236] linearizableReadLoop","detail":"{readStateIndex:8762; appliedIndex:8758; }","duration":"1.048336973s","start":"2026-05-05T11:35:45.21799Z","end":"2026-05-05T11:35:46.266327Z","steps":["trace[1950654236] 'read index received' (duration: 750.788579ms)","trace[1950654236] 'applied index is now lower than readState.Index' (duration: 297.547244ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:35:46.266473Z","caller":"traceutil/trace.go:171","msg":"trace[603594018] transaction","detail":"{read_only:false; response_revision:8455; number_of_response:1; }","duration":"1.19411468s","start":"2026-05-05T11:35:45.072312Z","end":"2026-05-05T11:35:46.266426Z","steps":["trace[603594018] 'process raft request' (duration: 896.584886ms)","trace[603594018] 'compare' (duration: 296.647739ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:35:46.266557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:45.072292Z","time spent":"1.194217633s","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:35:46.266923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04890196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:35:46.266999Z","caller":"traceutil/trace.go:171","msg":"trace[571413427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8458; }","duration":"1.049019533s","start":"2026-05-05T11:35:45.217962Z","end":"2026-05-05T11:35:46.266982Z","steps":["trace[571413427] 'agreement among raft nodes before linearized reading' (duration: 1.048878149s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:46.267034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:45.217949Z","time spent":"1.049077966s","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T11:35:46.267307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.747774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tj689\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-05-05T11:35:46.267303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.994162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T11:35:46.26736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.806216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T11:35:46.267427Z","caller":"traceutil/trace.go:171","msg":"trace[770273499] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8458; }","duration":"167.870927ms","start":"2026-05-05T11:35:46.099535Z","end":"2026-05-05T11:35:46.267406Z","steps":["trace[770273499] 'agreement among raft nodes before linearized reading' (duration: 167.671512ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:35:46.267411Z","caller":"traceutil/trace.go:171","msg":"trace[1073536135] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:8458; }","duration":"484.133796ms","start":"2026-05-05T11:35:45.783254Z","end":"2026-05-05T11:35:46.267388Z","steps":["trace[1073536135] 'agreement among raft nodes before linearized reading' (duration: 483.94117ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:35:46.267472Z","caller":"traceutil/trace.go:171","msg":"trace[236920119] transaction","detail":"{read_only:false; response_revision:8457; number_of_response:1; }","duration":"1.194309597s","start":"2026-05-05T11:35:45.073143Z","end":"2026-05-05T11:35:46.267453Z","steps":["trace[236920119] 'process raft request' (duration: 1.193092161s)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:35:46.26748Z","caller":"traceutil/trace.go:171","msg":"trace[850262604] transaction","detail":"{read_only:false; response_revision:8458; number_of_response:1; }","duration":"1.194302387s","start":"2026-05-05T11:35:45.07316Z","end":"2026-05-05T11:35:46.267463Z","steps":["trace[850262604] 'process raft request' (duration: 1.193129363s)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:35:46.267377Z","caller":"traceutil/trace.go:171","msg":"trace[138936406] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tj689; range_end:; response_count:1; response_revision:8458; }","duration":"470.866277ms","start":"2026-05-05T11:35:45.796497Z","end":"2026-05-05T11:35:46.267363Z","steps":["trace[138936406] 'agreement among raft nodes before linearized reading' (duration: 470.679502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:46.267496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:45.78324Z","time spent":"484.242138ms","remote":"127.0.0.1:37892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":3,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-05T11:35:46.267533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:45.796479Z","time spent":"471.043722ms","remote":"127.0.0.1:37564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-tj689\" "} {"level":"info","ts":"2026-05-05T11:35:46.2675Z","caller":"traceutil/trace.go:171","msg":"trace[343500896] transaction","detail":"{read_only:false; response_revision:8456; number_of_response:1; }","duration":"1.194950095s","start":"2026-05-05T11:35:45.072507Z","end":"2026-05-05T11:35:46.267457Z","steps":["trace[343500896] 'process raft request' (duration: 1.193660747s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:46.267664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:45.072492Z","time spent":"1.195110689s","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:35:46.267563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:45.073147Z","time spent":"1.194363268s","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:35:46.267565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:45.073126Z","time spent":"1.194381789s","remote":"127.0.0.1:37636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:35:46.267791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.117508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-05-05T11:35:46.268382Z","caller":"traceutil/trace.go:171","msg":"trace[511507114] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8458; }","duration":"509.713055ms","start":"2026-05-05T11:35:45.758625Z","end":"2026-05-05T11:35:46.268338Z","steps":["trace[511507114] 'agreement among raft nodes before linearized reading' (duration: 508.295565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:35:46.268465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:35:45.758605Z","time spent":"509.805658ms","remote":"127.0.0.1:37564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-05T11:35:46.560981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.167787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-05-05T11:35:46.561082Z","caller":"traceutil/trace.go:171","msg":"trace[1174538742] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8459; }","duration":"194.28122ms","start":"2026-05-05T11:35:46.366779Z","end":"2026-05-05T11:35:46.56106Z","steps":["trace[1174538742] 'range keys from in-memory index tree' (duration: 193.976241ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:35:51.933761Z","caller":"traceutil/trace.go:171","msg":"trace[2035582497] transaction","detail":"{read_only:false; response_revision:8501; number_of_response:1; }","duration":"118.089307ms","start":"2026-05-05T11:35:51.815632Z","end":"2026-05-05T11:35:51.933722Z","steps":["trace[2035582497] 'process raft request' (duration: 105.92102ms)","trace[2035582497] 'compare' (duration: 11.730215ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:36:32.996921Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002406.wal"} {"level":"warn","ts":"2026-05-05T11:37:17.998405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.249385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:37:17.998489Z","caller":"traceutil/trace.go:171","msg":"trace[46520206] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:9332; }","duration":"250.345237ms","start":"2026-05-05T11:37:17.748125Z","end":"2026-05-05T11:37:17.99847Z","steps":["trace[46520206] 'count revisions from in-memory index tree' (duration: 250.015538ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:38:03.079642Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T11:38:03.086874Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T11:38:03.086955Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-05T11:40:18.879663Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8270} {"level":"info","ts":"2026-05-05T11:40:18.958107Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8270,"took":"76.251386ms","hash":732817492,"current-db-size-bytes":59326464,"current-db-size":"59 MB","current-db-size-in-use-bytes":23908352,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-05T11:40:18.958187Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":732817492,"revision":8270,"compact-revision":6097} {"level":"info","ts":"2026-05-05T11:41:29.162537Z","caller":"traceutil/trace.go:171","msg":"trace[1545515110] transaction","detail":"{read_only:false; response_revision:10956; number_of_response:1; }","duration":"106.569102ms","start":"2026-05-05T11:41:29.055933Z","end":"2026-05-05T11:41:29.162502Z","steps":["trace[1545515110] 'process raft request' (duration: 50.42733ms)","trace[1545515110] 'compare' (duration: 55.586996ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:42:52.704375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.392457ms","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-05-05T11:42:52.704502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.457229ms","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-05T11:42:52.704505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.38745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T11:42:52.704579Z","caller":"traceutil/trace.go:171","msg":"trace[185268395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11737; }","duration":"237.580132ms","start":"2026-05-05T11:42:52.466978Z","end":"2026-05-05T11:42:52.704558Z","steps":["trace[185268395] 'range keys from in-memory index tree' (duration: 237.317005ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:42:52.704594Z","caller":"traceutil/trace.go:171","msg":"trace[890186461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11737; }","duration":"235.512522ms","start":"2026-05-05T11:42:52.469061Z","end":"2026-05-05T11:42:52.704574Z","steps":["trace[890186461] 'range keys from in-memory index tree' (duration: 235.216154ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:42:52.704594Z","caller":"traceutil/trace.go:171","msg":"trace[1174017273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11737; }","duration":"237.622194ms","start":"2026-05-05T11:42:52.466919Z","end":"2026-05-05T11:42:52.704542Z","steps":["trace[1174017273] 'range keys from in-memory index tree' (duration: 237.187341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:42:52.704441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.61974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-05T11:42:52.704743Z","caller":"traceutil/trace.go:171","msg":"trace[696092284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11737; }","duration":"239.98357ms","start":"2026-05-05T11:42:52.464749Z","end":"2026-05-05T11:42:52.704733Z","steps":["trace[696092284] 'range keys from in-memory index tree' (duration: 239.423284ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:42:52.76175Z","caller":"traceutil/trace.go:171","msg":"trace[153786926] transaction","detail":"{read_only:false; response_revision:11739; number_of_response:1; }","duration":"130.514106ms","start":"2026-05-05T11:42:52.631207Z","end":"2026-05-05T11:42:52.761722Z","steps":["trace[153786926] 'process raft request' (duration: 130.29057ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:42:52.761893Z","caller":"traceutil/trace.go:171","msg":"trace[673421479] transaction","detail":"{read_only:false; response_revision:11740; number_of_response:1; }","duration":"127.234782ms","start":"2026-05-05T11:42:52.634626Z","end":"2026-05-05T11:42:52.761861Z","steps":["trace[673421479] 'process raft request' (duration: 127.023426ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:42:52.880793Z","caller":"traceutil/trace.go:171","msg":"trace[1511070295] transaction","detail":"{read_only:false; response_revision:11745; number_of_response:1; }","duration":"114.868129ms","start":"2026-05-05T11:42:52.765888Z","end":"2026-05-05T11:42:52.880756Z","steps":["trace[1511070295] 'process raft request' (duration: 114.672183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:42:53.822202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.022849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T11:42:53.822256Z","caller":"traceutil/trace.go:171","msg":"trace[1774958138] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:11749; }","duration":"194.096841ms","start":"2026-05-05T11:42:53.628147Z","end":"2026-05-05T11:42:53.822243Z","steps":["trace[1774958138] 'count revisions from in-memory index tree' (duration: 193.896704ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:45:18.886533Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10480} {"level":"info","ts":"2026-05-05T11:45:18.965089Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10480,"took":"76.223216ms","hash":429905165,"current-db-size-bytes":59326464,"current-db-size":"59 MB","current-db-size-in-use-bytes":26046464,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-05T11:45:18.965165Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":429905165,"revision":10480,"compact-revision":8270} {"level":"info","ts":"2026-05-05T11:50:18.893644Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12733} {"level":"info","ts":"2026-05-05T11:50:18.971765Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12733,"took":"75.737873ms","hash":3081848919,"current-db-size-bytes":59326464,"current-db-size":"59 MB","current-db-size-in-use-bytes":26939392,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-05T11:50:18.971856Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3081848919,"revision":12733,"compact-revision":10480}