{"level":"warn","ts":"2026-04-16T22:47:42.379289Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T22:47:42.379391Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-16T22:47:42.37947Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T22:47:42.37949Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-04-16T22:47:42.379519Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-16T22:47:42.380304Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-04-16T22:47:42.380397Z","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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-16T22:47:42.384675Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.044651ms"} {"level":"info","ts":"2026-04-16T22:47:42.390795Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-04-16T22:47:42.390873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T22:47:42.3909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-04-16T22:47:42.390919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T22:47:42.39093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-04-16T22:47:42.390976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-04-16T22:47:42.398085Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T22:47:42.400211Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T22:47:42.401421Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-16T22:47:42.403628Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T22:47:42.404064Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T22:47:42.404096Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T22:47:42.404208Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T22:47:42.404219Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T22:47:42.405167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-04-16T22:47:42.405377Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-04-16T22:47:42.408199Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-16T22:47:42.408326Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-04-16T22:47:42.408349Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-04-16T22:47:42.408536Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T22:47:42.408651Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T22:47:42.891282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T22:47:42.8914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T22:47:42.891433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-04-16T22:47:42.891456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-04-16T22:47:42.891468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-04-16T22:47:42.891485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-04-16T22:47:42.8915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-04-16T22:47:42.89236Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:47:42.893284Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T22:47:42.893282Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T22:47:42.893413Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T22:47:42.893674Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:47:42.893789Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:47:42.893848Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:47:42.893788Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T22:47:42.89393Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T22:47:42.896454Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T22:47:42.902601Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"warn","ts":"2026-04-16T22:47:44.672622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.013011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-16T22:47:44.672779Z","caller":"traceutil/trace.go:171","msg":"trace[1954531202] linearizableReadLoop","detail":"{readStateIndex:74; appliedIndex:73; }","duration":"261.283144ms","start":"2026-04-16T22:47:44.411479Z","end":"2026-04-16T22:47:44.672762Z","steps":["trace[1954531202] 'read index received' (duration: 145.468302ms)","trace[1954531202] 'applied index is now lower than readState.Index' (duration: 115.813202ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:47:44.672895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.420448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a6f7e3fcba7035\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-16T22:47:44.672931Z","caller":"traceutil/trace.go:171","msg":"trace[1642918408] range","detail":"{range_begin:/registry/events/default/instance.18a6f7e3fcba7035; range_end:; response_count:1; response_revision:69; }","duration":"261.48377ms","start":"2026-04-16T22:47:44.411438Z","end":"2026-04-16T22:47:44.672922Z","steps":["trace[1642918408] 'agreement among raft nodes before linearized reading' (duration: 261.417338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:47:44.672959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.72628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-16T22:47:44.673017Z","caller":"traceutil/trace.go:171","msg":"trace[844456579] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:69; }","duration":"182.808561ms","start":"2026-04-16T22:47:44.490192Z","end":"2026-04-16T22:47:44.673001Z","steps":["trace[844456579] 'agreement among raft nodes before linearized reading' (duration: 182.726129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:47:44.673191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.990368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-16T22:47:44.673228Z","caller":"traceutil/trace.go:171","msg":"trace[654265814] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:69; }","duration":"108.053041ms","start":"2026-04-16T22:47:44.565165Z","end":"2026-04-16T22:47:44.673219Z","steps":["trace[654265814] 'agreement among raft nodes before linearized reading' (duration: 107.999019ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:47:44.673421Z","caller":"traceutil/trace.go:171","msg":"trace[1686471862] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"261.814749ms","start":"2026-04-16T22:47:44.411015Z","end":"2026-04-16T22:47:44.67283Z","steps":["trace[1686471862] 'process raft request' (duration: 145.990096ms)","trace[1686471862] 'compare' (duration: 114.903888ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:47:53.099785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.809418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:47:53.099903Z","caller":"traceutil/trace.go:171","msg":"trace[1498398998] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:352; }","duration":"311.961409ms","start":"2026-04-16T22:47:52.787922Z","end":"2026-04-16T22:47:53.099883Z","steps":["trace[1498398998] 'read index received' (duration: 101.844941ms)","trace[1498398998] 'applied index is now lower than readState.Index' (duration: 210.115238ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:47:53.099948Z","caller":"traceutil/trace.go:171","msg":"trace[1276525146] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"352.189567ms","start":"2026-04-16T22:47:52.747718Z","end":"2026-04-16T22:47:53.099908Z","steps":["trace[1276525146] 'process raft request' (duration: 142.185793ms)","trace[1276525146] 'compare' (duration: 209.680095ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:47:53.100069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.93524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-04-16T22:47:53.100098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.144074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-16T22:47:53.100155Z","caller":"traceutil/trace.go:171","msg":"trace[1230502330] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:340; }","duration":"263.079524ms","start":"2026-04-16T22:47:52.837062Z","end":"2026-04-16T22:47:53.100142Z","steps":["trace[1230502330] 'agreement among raft nodes before linearized reading' (duration: 262.911029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:47:53.100127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:47:52.747332Z","time spent":"352.715251ms","remote":"127.0.0.1:53896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:47:53.100206Z","caller":"traceutil/trace.go:171","msg":"trace[1663835687] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:340; }","duration":"312.314039ms","start":"2026-04-16T22:47:52.787867Z","end":"2026-04-16T22:47:53.100181Z","steps":["trace[1663835687] 'agreement among raft nodes before linearized reading' (duration: 312.075152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:47:53.100253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:47:52.787843Z","time spent":"312.40102ms","remote":"127.0.0.1:53896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-04-16T22:47:53.100284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.443961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-16T22:47:53.100369Z","caller":"traceutil/trace.go:171","msg":"trace[1874787762] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:340; }","duration":"164.576954ms","start":"2026-04-16T22:47:52.935768Z","end":"2026-04-16T22:47:53.100344Z","steps":["trace[1874787762] 'agreement among raft nodes before linearized reading' (duration: 164.376289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:47:53.100373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.028544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-04-16T22:47:53.100372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.245073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-16T22:47:53.100521Z","caller":"traceutil/trace.go:171","msg":"trace[1641937778] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:340; }","duration":"114.172407ms","start":"2026-04-16T22:47:52.986292Z","end":"2026-04-16T22:47:53.100465Z","steps":["trace[1641937778] 'agreement among raft nodes before linearized reading' (duration: 113.980592ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:47:53.100502Z","caller":"traceutil/trace.go:171","msg":"trace[586570267] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:340; }","duration":"213.439888ms","start":"2026-04-16T22:47:52.887042Z","end":"2026-04-16T22:47:53.100482Z","steps":["trace[586570267] 'agreement among raft nodes before linearized reading' (duration: 213.165731ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:47:53.389047Z","caller":"traceutil/trace.go:171","msg":"trace[227409244] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:354; }","duration":"251.546569ms","start":"2026-04-16T22:47:53.137475Z","end":"2026-04-16T22:47:53.389021Z","steps":["trace[227409244] 'read index received' (duration: 251.318673ms)","trace[227409244] 'applied index is now lower than readState.Index' (duration: 224.926µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:47:53.3893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.82433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-16T22:47:53.389344Z","caller":"traceutil/trace.go:171","msg":"trace[1726514104] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:342; }","duration":"101.891693ms","start":"2026-04-16T22:47:53.287441Z","end":"2026-04-16T22:47:53.389333Z","steps":["trace[1726514104] 'agreement among raft nodes before linearized reading' (duration: 101.818661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:47:53.389362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.082615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4201"} {"level":"info","ts":"2026-04-16T22:47:53.389343Z","caller":"traceutil/trace.go:171","msg":"trace[1545634180] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"278.468975ms","start":"2026-04-16T22:47:53.110687Z","end":"2026-04-16T22:47:53.389156Z","steps":["trace[1545634180] 'process raft request' (duration: 278.182847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:47:53.389525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.474729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-04-16T22:47:53.389571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.261066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-16T22:47:53.389215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.716014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-16T22:47:53.3897Z","caller":"traceutil/trace.go:171","msg":"trace[690408081] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:342; }","duration":"153.417149ms","start":"2026-04-16T22:47:53.236246Z","end":"2026-04-16T22:47:53.389664Z","steps":["trace[690408081] 'agreement among raft nodes before linearized reading' (duration: 153.104941ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:47:53.389612Z","caller":"traceutil/trace.go:171","msg":"trace[207717166] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:342; }","duration":"202.605813ms","start":"2026-04-16T22:47:53.186985Z","end":"2026-04-16T22:47:53.389591Z","steps":["trace[207717166] 'agreement among raft nodes before linearized reading' (duration: 202.421068ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:47:53.389885Z","caller":"traceutil/trace.go:171","msg":"trace[1201474587] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:342; }","duration":"252.29692ms","start":"2026-04-16T22:47:53.137419Z","end":"2026-04-16T22:47:53.389716Z","steps":["trace[1201474587] 'agreement among raft nodes before linearized reading' (duration: 251.728375ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:47:53.389471Z","caller":"traceutil/trace.go:171","msg":"trace[1655103600] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:342; }","duration":"248.26524ms","start":"2026-04-16T22:47:53.141186Z","end":"2026-04-16T22:47:53.389451Z","steps":["trace[1655103600] 'agreement among raft nodes before linearized reading' (duration: 248.001023ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:48:13.537454Z","caller":"traceutil/trace.go:171","msg":"trace[1429620550] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"359.894716ms","start":"2026-04-16T22:48:13.177507Z","end":"2026-04-16T22:48:13.537401Z","steps":["trace[1429620550] 'process raft request' (duration: 291.446388ms)","trace[1429620550] 'compare' (duration: 68.228332ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:48:13.537733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:48:13.177461Z","time spent":"360.123632ms","remote":"127.0.0.1:53744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:48:13.538188Z","caller":"traceutil/trace.go:171","msg":"trace[482518341] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"351.715312ms","start":"2026-04-16T22:48:13.18644Z","end":"2026-04-16T22:48:13.538155Z","steps":["trace[482518341] 'process raft request' (duration: 351.515407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:48:13.538403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:48:13.18641Z","time spent":"351.879407ms","remote":"127.0.0.1:53744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:48:13.538571Z","caller":"traceutil/trace.go:171","msg":"trace[957968150] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"299.260721ms","start":"2026-04-16T22:48:13.239277Z","end":"2026-04-16T22:48:13.538537Z","steps":["trace[957968150] 'process raft request' (duration: 298.835379ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:48:13.538599Z","caller":"traceutil/trace.go:171","msg":"trace[921240281] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"288.180388ms","start":"2026-04-16T22:48:13.250399Z","end":"2026-04-16T22:48:13.53858Z","steps":["trace[921240281] 'process raft request' (duration: 287.994483ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:48:20.12136Z","caller":"traceutil/trace.go:171","msg":"trace[999698778] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"352.617459ms","start":"2026-04-16T22:48:19.768716Z","end":"2026-04-16T22:48:20.121333Z","steps":["trace[999698778] 'process raft request' (duration: 352.124696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:48:20.121537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:48:19.768673Z","time spent":"352.773324ms","remote":"127.0.0.1:53802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:48:20.215523Z","caller":"traceutil/trace.go:171","msg":"trace[1012155863] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:634; }","duration":"347.627492ms","start":"2026-04-16T22:48:19.867865Z","end":"2026-04-16T22:48:20.215492Z","steps":["trace[1012155863] 'read index received' (duration: 252.657219ms)","trace[1012155863] 'applied index is now lower than readState.Index' (duration: 94.969013ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:48:20.21567Z","caller":"traceutil/trace.go:171","msg":"trace[739248043] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"440.324604ms","start":"2026-04-16T22:48:19.775298Z","end":"2026-04-16T22:48:20.215623Z","steps":["trace[739248043] 'process raft request' (duration: 416.159724ms)","trace[739248043] 'compare' (duration: 23.891022ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:48:20.215869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:48:19.775242Z","time spent":"440.52242ms","remote":"127.0.0.1:53876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:48:20.216029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.080795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-16T22:48:20.216116Z","caller":"traceutil/trace.go:171","msg":"trace[1038300179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"348.28621ms","start":"2026-04-16T22:48:19.867809Z","end":"2026-04-16T22:48:20.216095Z","steps":["trace[1038300179] 'agreement among raft nodes before linearized reading' (duration: 347.812998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:48:20.216168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:48:19.867783Z","time spent":"348.375443ms","remote":"127.0.0.1:53948","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-04-16T22:48:20.679687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.871639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:48:20.679871Z","caller":"traceutil/trace.go:171","msg":"trace[447231920] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"189.201817ms","start":"2026-04-16T22:48:20.490651Z","end":"2026-04-16T22:48:20.679853Z","steps":["trace[447231920] 'read index received' (duration: 36.751µs)","trace[447231920] 'applied index is now lower than readState.Index' (duration: 189.163776ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:48:20.679979Z","caller":"traceutil/trace.go:171","msg":"trace[2042963941] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"458.979662ms","start":"2026-04-16T22:48:20.220989Z","end":"2026-04-16T22:48:20.679968Z","steps":["trace[2042963941] 'process raft request' (duration: 458.799568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:48:20.680056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:48:20.220962Z","time spent":"459.048785ms","remote":"127.0.0.1:53948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:48:20.680111Z","caller":"traceutil/trace.go:171","msg":"trace[1164710759] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"488.773877ms","start":"2026-04-16T22:48:20.191294Z","end":"2026-04-16T22:48:20.680068Z","steps":["trace[1164710759] 'process raft request' (duration: 281.447466ms)","trace[1164710759] 'compare' (duration: 206.561859ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:48:20.680257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.617808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-16T22:48:20.680286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:48:20.191255Z","time spent":"488.945282ms","remote":"127.0.0.1:53918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:48:20.680309Z","caller":"traceutil/trace.go:171","msg":"trace[917433798] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"189.711781ms","start":"2026-04-16T22:48:20.490584Z","end":"2026-04-16T22:48:20.680295Z","steps":["trace[917433798] 'agreement among raft nodes before linearized reading' (duration: 189.634759ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:48:35.123122Z","caller":"traceutil/trace.go:171","msg":"trace[2011391927] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"191.170725ms","start":"2026-04-16T22:48:34.931907Z","end":"2026-04-16T22:48:35.123078Z","steps":["trace[2011391927] 'process raft request' (duration: 190.845026ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:03.034873Z","caller":"traceutil/trace.go:171","msg":"trace[709060995] linearizableReadLoop","detail":"{readStateIndex:1201; appliedIndex:1200; }","duration":"151.901549ms","start":"2026-04-16T22:49:02.882942Z","end":"2026-04-16T22:49:03.034843Z","steps":["trace[709060995] 'read index received' (duration: 151.688834ms)","trace[709060995] 'applied index is now lower than readState.Index' (duration: 211.095µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:49:03.034973Z","caller":"traceutil/trace.go:171","msg":"trace[1898723615] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"152.515326ms","start":"2026-04-16T22:49:02.882427Z","end":"2026-04-16T22:49:03.034942Z","steps":["trace[1898723615] 'process raft request' (duration: 152.153686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:49:03.035138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.179926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T22:49:03.035181Z","caller":"traceutil/trace.go:171","msg":"trace[331726804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1168; }","duration":"152.262859ms","start":"2026-04-16T22:49:02.882907Z","end":"2026-04-16T22:49:03.03517Z","steps":["trace[331726804] 'agreement among raft nodes before linearized reading' (duration: 152.095615ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:03.050975Z","caller":"traceutil/trace.go:171","msg":"trace[427614258] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"166.754927ms","start":"2026-04-16T22:49:02.884197Z","end":"2026-04-16T22:49:03.050952Z","steps":["trace[427614258] 'process raft request' (duration: 166.640064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:49:03.051192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.853901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T22:49:03.051257Z","caller":"traceutil/trace.go:171","msg":"trace[1938938279] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1169; }","duration":"135.986323ms","start":"2026-04-16T22:49:02.915258Z","end":"2026-04-16T22:49:03.051244Z","steps":["trace[1938938279] 'agreement among raft nodes before linearized reading' (duration: 135.758937ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:25.294202Z","caller":"traceutil/trace.go:171","msg":"trace[1594003606] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"136.308093ms","start":"2026-04-16T22:49:25.15786Z","end":"2026-04-16T22:49:25.294168Z","steps":["trace[1594003606] 'process raft request' (duration: 122.723721ms)","trace[1594003606] 'compare' (duration: 13.262033ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:49:25.294324Z","caller":"traceutil/trace.go:171","msg":"trace[519660686] linearizableReadLoop","detail":"{readStateIndex:1467; appliedIndex:1465; }","duration":"133.779894ms","start":"2026-04-16T22:49:25.160516Z","end":"2026-04-16T22:49:25.294296Z","steps":["trace[519660686] 'read index received' (duration: 120.14032ms)","trace[519660686] 'applied index is now lower than readState.Index' (duration: 13.637724ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:49:25.294414Z","caller":"traceutil/trace.go:171","msg":"trace[2005743877] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"134.205715ms","start":"2026-04-16T22:49:25.160163Z","end":"2026-04-16T22:49:25.294369Z","steps":["trace[2005743877] 'process raft request' (duration: 133.935247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:49:25.294531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.995349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-16T22:49:25.294559Z","caller":"traceutil/trace.go:171","msg":"trace[420894569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1427; }","duration":"134.05852ms","start":"2026-04-16T22:49:25.160491Z","end":"2026-04-16T22:49:25.29455Z","steps":["trace[420894569] 'agreement among raft nodes before linearized reading' (duration: 133.902706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:49:49.850196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.838502ms","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-04-16T22:49:49.850299Z","caller":"traceutil/trace.go:171","msg":"trace[737524944] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1848; }","duration":"197.004947ms","start":"2026-04-16T22:49:49.653272Z","end":"2026-04-16T22:49:49.850277Z","steps":["trace[737524944] 'range keys from in-memory index tree' (duration: 196.608156ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:49.865128Z","caller":"traceutil/trace.go:171","msg":"trace[1739595995] linearizableReadLoop","detail":"{readStateIndex:1917; appliedIndex:1916; }","duration":"128.947239ms","start":"2026-04-16T22:49:49.736157Z","end":"2026-04-16T22:49:49.865104Z","steps":["trace[1739595995] 'read index received' (duration: 128.844956ms)","trace[1739595995] 'applied index is now lower than readState.Index' (duration: 101.563µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:49:49.865192Z","caller":"traceutil/trace.go:171","msg":"trace[1143071188] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"187.21478ms","start":"2026-04-16T22:49:49.677947Z","end":"2026-04-16T22:49:49.865162Z","steps":["trace[1143071188] 'process raft request' (duration: 187.045665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:49:49.865287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.122615ms","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-04-16T22:49:49.86532Z","caller":"traceutil/trace.go:171","msg":"trace[591106784] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1849; }","duration":"129.185826ms","start":"2026-04-16T22:49:49.736123Z","end":"2026-04-16T22:49:49.865308Z","steps":["trace[591106784] 'agreement among raft nodes before linearized reading' (duration: 129.059572ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:50:02.229782Z","caller":"traceutil/trace.go:171","msg":"trace[574988510] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"271.762548ms","start":"2026-04-16T22:50:01.957984Z","end":"2026-04-16T22:50:02.229747Z","steps":["trace[574988510] 'process raft request' (duration: 271.463909ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:50:39.097435Z","caller":"traceutil/trace.go:171","msg":"trace[424737937] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"213.716981ms","start":"2026-04-16T22:50:38.883679Z","end":"2026-04-16T22:50:39.097396Z","steps":["trace[424737937] 'process raft request' (duration: 116.363209ms)","trace[424737937] 'compare' (duration: 97.180058ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:50:39.131297Z","caller":"traceutil/trace.go:171","msg":"trace[1071892654] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"207.332948ms","start":"2026-04-16T22:50:38.923938Z","end":"2026-04-16T22:50:39.131271Z","steps":["trace[1071892654] 'process raft request' (duration: 207.174334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:28.389437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.075316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:51:28.38952Z","caller":"traceutil/trace.go:171","msg":"trace[1313275971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2604; }","duration":"153.215269ms","start":"2026-04-16T22:51:28.236286Z","end":"2026-04-16T22:51:28.389501Z","steps":["trace[1313275971] 'agreement among raft nodes before linearized reading' (duration: 27.092855ms)","trace[1313275971] 'range keys from in-memory index tree' (duration: 125.983589ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:52:02.120471Z","caller":"traceutil/trace.go:171","msg":"trace[1715472484] transaction","detail":"{read_only:false; response_revision:2806; number_of_response:1; }","duration":"101.93156ms","start":"2026-04-16T22:52:02.018507Z","end":"2026-04-16T22:52:02.120439Z","steps":["trace[1715472484] 'process raft request' (duration: 101.638112ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:02.297693Z","caller":"traceutil/trace.go:171","msg":"trace[1860062681] transaction","detail":"{read_only:false; response_revision:2808; number_of_response:1; }","duration":"126.154541ms","start":"2026-04-16T22:52:02.171505Z","end":"2026-04-16T22:52:02.297659Z","steps":["trace[1860062681] 'process raft request' (duration: 124.163686ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:02.301204Z","caller":"traceutil/trace.go:171","msg":"trace[1234041626] transaction","detail":"{read_only:false; response_revision:2809; number_of_response:1; }","duration":"129.095691ms","start":"2026-04-16T22:52:02.172096Z","end":"2026-04-16T22:52:02.301192Z","steps":["trace[1234041626] 'process raft request' (duration: 129.016109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:02.695659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.097149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:52:02.695748Z","caller":"traceutil/trace.go:171","msg":"trace[613786037] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2810; }","duration":"196.245643ms","start":"2026-04-16T22:52:02.499484Z","end":"2026-04-16T22:52:02.69573Z","steps":["trace[613786037] 'count revisions from in-memory index tree' (duration: 196.018377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:55:43.669275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.305212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:55:43.66937Z","caller":"traceutil/trace.go:171","msg":"trace[1200059278] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"246.152827ms","start":"2026-04-16T22:55:43.423201Z","end":"2026-04-16T22:55:43.669354Z","steps":["trace[1200059278] 'process raft request' (duration: 130.67021ms)","trace[1200059278] 'compare' (duration: 115.20917ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:55:43.671093Z","caller":"traceutil/trace.go:171","msg":"trace[1796341742] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"130.733812ms","start":"2026-04-16T22:55:43.540339Z","end":"2026-04-16T22:55:43.671073Z","steps":["trace[1796341742] 'process raft request' (duration: 130.63814ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:45.284413Z","caller":"traceutil/trace.go:171","msg":"trace[144413639] linearizableReadLoop","detail":"{readStateIndex:5112; appliedIndex:5111; }","duration":"105.193676ms","start":"2026-04-16T22:55:45.17919Z","end":"2026-04-16T22:55:45.284384Z","steps":["trace[144413639] 'read index received' (duration: 105.00204ms)","trace[144413639] 'applied index is now lower than readState.Index' (duration: 190.656µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:55:45.284483Z","caller":"traceutil/trace.go:171","msg":"trace[220712684] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"118.003045ms","start":"2026-04-16T22:55:45.166443Z","end":"2026-04-16T22:55:45.284446Z","steps":["trace[220712684] 'process raft request' (duration: 117.758179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:55:45.284597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.376561ms","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-04-16T22:55:45.284634Z","caller":"traceutil/trace.go:171","msg":"trace[1074916544] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4929; }","duration":"105.480124ms","start":"2026-04-16T22:55:45.179144Z","end":"2026-04-16T22:55:45.284624Z","steps":["trace[1074916544] 'agreement among raft nodes before linearized reading' (duration: 105.389442ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:55.175783Z","caller":"traceutil/trace.go:171","msg":"trace[213847879] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"125.584121ms","start":"2026-04-16T22:55:55.05017Z","end":"2026-04-16T22:55:55.175754Z","steps":["trace[213847879] 'process raft request' (duration: 58.193185ms)","trace[213847879] 'compare' (duration: 67.154089ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:55:55.453Z","caller":"traceutil/trace.go:171","msg":"trace[879291399] transaction","detail":"{read_only:false; response_revision:5135; number_of_response:1; }","duration":"126.965599ms","start":"2026-04-16T22:55:55.326001Z","end":"2026-04-16T22:55:55.452967Z","steps":["trace[879291399] 'process raft request' (duration: 110.939063ms)","trace[879291399] 'compare' (duration: 15.139962ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:55:55.453199Z","caller":"traceutil/trace.go:171","msg":"trace[1889449674] transaction","detail":"{read_only:false; response_revision:5136; number_of_response:1; }","duration":"127.134764ms","start":"2026-04-16T22:55:55.326054Z","end":"2026-04-16T22:55:55.453189Z","steps":["trace[1889449674] 'process raft request' (duration: 126.23007ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:55.453388Z","caller":"traceutil/trace.go:171","msg":"trace[1624863110] transaction","detail":"{read_only:false; response_revision:5137; number_of_response:1; }","duration":"127.274067ms","start":"2026-04-16T22:55:55.326102Z","end":"2026-04-16T22:55:55.453376Z","steps":["trace[1624863110] 'process raft request' (duration: 126.228099ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:55.453487Z","caller":"traceutil/trace.go:171","msg":"trace[1572347340] transaction","detail":"{read_only:false; response_revision:5138; number_of_response:1; }","duration":"127.349329ms","start":"2026-04-16T22:55:55.326111Z","end":"2026-04-16T22:55:55.45346Z","steps":["trace[1572347340] 'process raft request' (duration: 126.27429ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:55.583561Z","caller":"traceutil/trace.go:171","msg":"trace[874536655] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"103.891271ms","start":"2026-04-16T22:55:55.479641Z","end":"2026-04-16T22:55:55.583532Z","steps":["trace[874536655] 'process raft request' (duration: 73.420741ms)","trace[874536655] 'compare' (duration: 30.300855ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:56:03.552832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.374249ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:56:03.553019Z","caller":"traceutil/trace.go:171","msg":"trace[855892033] transaction","detail":"{read_only:false; response_revision:5304; number_of_response:1; }","duration":"194.97315ms","start":"2026-04-16T22:56:03.358023Z","end":"2026-04-16T22:56:03.552996Z","steps":["trace[855892033] 'process raft request' (duration: 24.323193ms)","trace[855892033] 'compare' (duration: 170.243706ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:56:03.553337Z","caller":"traceutil/trace.go:171","msg":"trace[325845706] transaction","detail":"{read_only:false; response_revision:5305; number_of_response:1; }","duration":"195.249677ms","start":"2026-04-16T22:56:03.358056Z","end":"2026-04-16T22:56:03.553305Z","steps":["trace[325845706] 'process raft request' (duration: 194.895418ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:56:03.553454Z","caller":"traceutil/trace.go:171","msg":"trace[1084569691] transaction","detail":"{read_only:false; response_revision:5306; number_of_response:1; }","duration":"195.335079ms","start":"2026-04-16T22:56:03.358084Z","end":"2026-04-16T22:56:03.553419Z","steps":["trace[1084569691] 'process raft request' (duration: 195.133854ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:56:03.55368Z","caller":"traceutil/trace.go:171","msg":"trace[1292522576] transaction","detail":"{read_only:false; response_revision:5307; number_of_response:1; }","duration":"195.485444ms","start":"2026-04-16T22:56:03.358181Z","end":"2026-04-16T22:56:03.553667Z","steps":["trace[1292522576] 'process raft request' (duration: 195.179236ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:56:03.553724Z","caller":"traceutil/trace.go:171","msg":"trace[89230848] transaction","detail":"{read_only:false; response_revision:5308; number_of_response:1; }","duration":"195.455673ms","start":"2026-04-16T22:56:03.358245Z","end":"2026-04-16T22:56:03.553701Z","steps":["trace[89230848] 'process raft request' (duration: 195.298169ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:56:03.553859Z","caller":"traceutil/trace.go:171","msg":"trace[168420508] transaction","detail":"{read_only:false; response_revision:5309; number_of_response:1; }","duration":"195.440262ms","start":"2026-04-16T22:56:03.358408Z","end":"2026-04-16T22:56:03.553848Z","steps":["trace[168420508] 'process raft request' (duration: 195.237047ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:56:03.55394Z","caller":"traceutil/trace.go:171","msg":"trace[2002884935] transaction","detail":"{read_only:false; response_revision:5310; number_of_response:1; }","duration":"194.457455ms","start":"2026-04-16T22:56:03.359467Z","end":"2026-04-16T22:56:03.553924Z","steps":["trace[2002884935] 'process raft request' (duration: 194.319971ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:56:03.554071Z","caller":"traceutil/trace.go:171","msg":"trace[1354213977] linearizableReadLoop","detail":"{readStateIndex:5499; appliedIndex:5496; }","duration":"195.880435ms","start":"2026-04-16T22:56:03.358176Z","end":"2026-04-16T22:56:03.554056Z","steps":["trace[1354213977] 'read index received' (duration: 24.146848ms)","trace[1354213977] 'applied index is now lower than readState.Index' (duration: 171.732457ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:56:03.554175Z","caller":"traceutil/trace.go:171","msg":"trace[1477006210] transaction","detail":"{read_only:false; response_revision:5311; number_of_response:1; }","duration":"191.912056ms","start":"2026-04-16T22:56:03.362235Z","end":"2026-04-16T22:56:03.554147Z","steps":["trace[1477006210] 'process raft request' (duration: 191.642299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:56:03.554297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.190073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-8ea1e90b-4aba-47e3-8011-6098ed761b0a\" ","response":"range_response_count:1 size:1275"} {"level":"info","ts":"2026-04-16T22:56:03.554343Z","caller":"traceutil/trace.go:171","msg":"trace[1602909184] range","detail":"{range_begin:/registry/persistentvolumes/pvc-8ea1e90b-4aba-47e3-8011-6098ed761b0a; range_end:; response_count:1; response_revision:5311; }","duration":"196.262045ms","start":"2026-04-16T22:56:03.358067Z","end":"2026-04-16T22:56:03.554329Z","steps":["trace[1602909184] 'agreement among raft nodes before linearized reading' (duration: 196.06304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:56:03.881216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.050941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:56:03.881414Z","caller":"traceutil/trace.go:171","msg":"trace[1914804963] linearizableReadLoop","detail":"{readStateIndex:5506; appliedIndex:5504; }","duration":"254.698966ms","start":"2026-04-16T22:56:03.626697Z","end":"2026-04-16T22:56:03.881396Z","steps":["trace[1914804963] 'read index received' (duration: 41.360026ms)","trace[1914804963] 'applied index is now lower than readState.Index' (duration: 213.33757ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:56:03.881493Z","caller":"traceutil/trace.go:171","msg":"trace[1314267809] transaction","detail":"{read_only:false; response_revision:5313; number_of_response:1; }","duration":"320.696493ms","start":"2026-04-16T22:56:03.560783Z","end":"2026-04-16T22:56:03.88148Z","steps":["trace[1314267809] 'process raft request' (duration: 320.544899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:56:03.881552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:56:03.560758Z","time spent":"320.761475ms","remote":"127.0.0.1:53948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:56:03.881526Z","caller":"traceutil/trace.go:171","msg":"trace[2112544418] transaction","detail":"{read_only:false; response_revision:5312; number_of_response:1; }","duration":"322.150923ms","start":"2026-04-16T22:56:03.559327Z","end":"2026-04-16T22:56:03.881478Z","steps":["trace[2112544418] 'process raft request' (duration: 108.742992ms)","trace[2112544418] 'compare' (duration: 212.925938ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:56:03.881682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.968314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-16T22:56:03.881762Z","caller":"traceutil/trace.go:171","msg":"trace[484268856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5313; }","duration":"255.110597ms","start":"2026-04-16T22:56:03.626631Z","end":"2026-04-16T22:56:03.881742Z","steps":["trace[484268856] 'agreement among raft nodes before linearized reading' (duration: 254.85295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:56:03.881758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:56:03.559295Z","time spent":"322.326698ms","remote":"127.0.0.1:53948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:57:08.336519Z","caller":"traceutil/trace.go:171","msg":"trace[1982629876] transaction","detail":"{read_only:false; response_revision:5913; number_of_response:1; }","duration":"118.140521ms","start":"2026-04-16T22:57:08.218345Z","end":"2026-04-16T22:57:08.336486Z","steps":["trace[1982629876] 'process raft request' (duration: 103.200183ms)","trace[1982629876] 'compare' (duration: 14.824485ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:57:08.903906Z","caller":"traceutil/trace.go:171","msg":"trace[1162181304] transaction","detail":"{read_only:false; response_revision:5916; number_of_response:1; }","duration":"108.991381ms","start":"2026-04-16T22:57:08.794883Z","end":"2026-04-16T22:57:08.903875Z","steps":["trace[1162181304] 'process raft request' (duration: 108.788946ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:57:42.991595Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3032} {"level":"info","ts":"2026-04-16T22:57:43.085101Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3032,"took":"89.697614ms","hash":901124772,"current-db-size-bytes":58380288,"current-db-size":"58 MB","current-db-size-in-use-bytes":32145408,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-16T22:57:43.085182Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":901124772,"revision":3032,"compact-revision":-1} {"level":"info","ts":"2026-04-16T22:59:54.25284Z","caller":"traceutil/trace.go:171","msg":"trace[1593790974] transaction","detail":"{read_only:false; response_revision:7018; number_of_response:1; }","duration":"100.17267ms","start":"2026-04-16T22:59:54.151946Z","end":"2026-04-16T22:59:54.252119Z","steps":["trace[1593790974] 'process raft request' (duration: 99.768758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:02:21.29911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.345388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4k7w7\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-16T23:02:21.299213Z","caller":"traceutil/trace.go:171","msg":"trace[1546305879] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4k7w7; range_end:; response_count:1; response_revision:8196; }","duration":"255.507462ms","start":"2026-04-16T23:02:21.043685Z","end":"2026-04-16T23:02:21.299192Z","steps":["trace[1546305879] 'range keys from in-memory index tree' (duration: 255.122002ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:02:33.110129Z","caller":"traceutil/trace.go:171","msg":"trace[686108606] transaction","detail":"{read_only:false; response_revision:8261; number_of_response:1; }","duration":"107.495015ms","start":"2026-04-16T23:02:33.002609Z","end":"2026-04-16T23:02:33.110104Z","steps":["trace[686108606] 'process raft request' (duration: 107.30973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:02:34.348369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.683235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T23:02:34.348464Z","caller":"traceutil/trace.go:171","msg":"trace[1773413398] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:8269; }","duration":"213.834899ms","start":"2026-04-16T23:02:34.134608Z","end":"2026-04-16T23:02:34.348443Z","steps":["trace[1773413398] 'count revisions from in-memory index tree' (duration: 213.575813ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:02:43.001522Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6169} {"level":"info","ts":"2026-04-16T23:02:43.098686Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6169,"took":"95.130958ms","hash":304230056,"current-db-size-bytes":58380288,"current-db-size":"58 MB","current-db-size-in-use-bytes":22978560,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-16T23:02:43.098738Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":304230056,"revision":6169,"compact-revision":3032} {"level":"info","ts":"2026-04-16T23:03:00.257265Z","caller":"traceutil/trace.go:171","msg":"trace[1427467292] transaction","detail":"{read_only:false; response_revision:8463; number_of_response:1; }","duration":"106.653723ms","start":"2026-04-16T23:03:00.150575Z","end":"2026-04-16T23:03:00.257228Z","steps":["trace[1427467292] 'compare' (duration: 97.152003ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:03:00.257493Z","caller":"traceutil/trace.go:171","msg":"trace[1199661766] transaction","detail":"{read_only:false; response_revision:8464; number_of_response:1; }","duration":"105.341856ms","start":"2026-04-16T23:03:00.152092Z","end":"2026-04-16T23:03:00.257434Z","steps":["trace[1199661766] 'process raft request' (duration: 105.059959ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:03:03.782407Z","caller":"traceutil/trace.go:171","msg":"trace[1892949893] linearizableReadLoop","detail":"{readStateIndex:8774; appliedIndex:8774; }","duration":"185.440084ms","start":"2026-04-16T23:03:03.596933Z","end":"2026-04-16T23:03:03.782373Z","steps":["trace[1892949893] 'read index received' (duration: 185.421854ms)","trace[1892949893] 'applied index is now lower than readState.Index' (duration: 16.15µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T23:03:03.782421Z","caller":"traceutil/trace.go:171","msg":"trace[1054155099] transaction","detail":"{read_only:false; response_revision:8479; number_of_response:1; }","duration":"202.610453ms","start":"2026-04-16T23:03:03.579756Z","end":"2026-04-16T23:03:03.782367Z","steps":["trace[1054155099] 'process raft request' (duration: 202.423338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:03.782801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.858186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4k7w7\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-16T23:03:03.782846Z","caller":"traceutil/trace.go:171","msg":"trace[1983718534] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4k7w7; range_end:; response_count:1; response_revision:8479; }","duration":"185.97793ms","start":"2026-04-16T23:03:03.596855Z","end":"2026-04-16T23:03:03.782833Z","steps":["trace[1983718534] 'agreement among raft nodes before linearized reading' (duration: 185.784945ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:03:03.81826Z","caller":"traceutil/trace.go:171","msg":"trace[286543538] transaction","detail":"{read_only:false; response_revision:8480; number_of_response:1; }","duration":"152.818853ms","start":"2026-04-16T23:03:03.665404Z","end":"2026-04-16T23:03:03.818223Z","steps":["trace[286543538] 'process raft request' (duration: 151.97714ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:03:16.472555Z","caller":"traceutil/trace.go:171","msg":"trace[218675610] transaction","detail":"{read_only:false; response_revision:8592; number_of_response:1; }","duration":"110.782225ms","start":"2026-04-16T23:03:16.361733Z","end":"2026-04-16T23:03:16.472515Z","steps":["trace[218675610] 'process raft request' (duration: 110.58712ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:03:16.617797Z","caller":"traceutil/trace.go:171","msg":"trace[1654341791] transaction","detail":"{read_only:false; response_revision:8593; number_of_response:1; }","duration":"253.78724ms","start":"2026-04-16T23:03:16.363962Z","end":"2026-04-16T23:03:16.617749Z","steps":["trace[1654341791] 'process raft request' (duration: 240.905489ms)","trace[1654341791] 'compare' (duration: 12.674646ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T23:03:17.385256Z","caller":"traceutil/trace.go:171","msg":"trace[1158307067] linearizableReadLoop","detail":"{readStateIndex:8899; appliedIndex:8898; }","duration":"177.778335ms","start":"2026-04-16T23:03:17.207434Z","end":"2026-04-16T23:03:17.385213Z","steps":["trace[1158307067] 'read index received' (duration: 152.753381ms)","trace[1158307067] 'applied index is now lower than readState.Index' (duration: 25.023704ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T23:03:17.385281Z","caller":"traceutil/trace.go:171","msg":"trace[1469626954] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"218.280412ms","start":"2026-04-16T23:03:17.166953Z","end":"2026-04-16T23:03:17.385233Z","steps":["trace[1469626954] 'process raft request' (duration: 193.136666ms)","trace[1469626954] 'compare' (duration: 24.936291ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T23:03:17.385626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.137665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T23:03:17.385758Z","caller":"traceutil/trace.go:171","msg":"trace[1941956984] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:8597; }","duration":"178.35506ms","start":"2026-04-16T23:03:17.20736Z","end":"2026-04-16T23:03:17.385715Z","steps":["trace[1941956984] 'agreement among raft nodes before linearized reading' (duration: 177.96898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:17.40324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.186831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T23:03:17.403347Z","caller":"traceutil/trace.go:171","msg":"trace[1796107323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8598; }","duration":"195.363856ms","start":"2026-04-16T23:03:17.207958Z","end":"2026-04-16T23:03:17.403322Z","steps":["trace[1796107323] 'agreement among raft nodes before linearized reading' (duration: 195.218882ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:03:49.687173Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002410.wal"} {"level":"warn","ts":"2026-04-16T23:04:36.729621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.242373ms","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-04-16T23:04:36.72975Z","caller":"traceutil/trace.go:171","msg":"trace[170564379] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9358; }","duration":"130.419748ms","start":"2026-04-16T23:04:36.599307Z","end":"2026-04-16T23:04:36.729727Z","steps":["trace[170564379] 'range keys from in-memory index tree' (duration: 130.013467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:04:36.729751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.637284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T23:04:36.7297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.998189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T23:04:36.729832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.51071ms","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-04-16T23:04:36.729952Z","caller":"traceutil/trace.go:171","msg":"trace[1180686180] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9358; }","duration":"134.702117ms","start":"2026-04-16T23:04:36.59523Z","end":"2026-04-16T23:04:36.729932Z","steps":["trace[1180686180] 'range keys from in-memory index tree' (duration: 134.252162ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:04:36.729997Z","caller":"traceutil/trace.go:171","msg":"trace[1747721068] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:9358; }","duration":"196.278787ms","start":"2026-04-16T23:04:36.533637Z","end":"2026-04-16T23:04:36.729915Z","steps":["trace[1747721068] 'count revisions from in-memory index tree' (duration: 195.900786ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:04:36.729861Z","caller":"traceutil/trace.go:171","msg":"trace[201107181] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:9358; }","duration":"215.80219ms","start":"2026-04-16T23:04:36.514031Z","end":"2026-04-16T23:04:36.729834Z","steps":["trace[201107181] 'count revisions from in-memory index tree' (duration: 215.513591ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:04:36.870712Z","caller":"traceutil/trace.go:171","msg":"trace[487277616] transaction","detail":"{read_only:false; response_revision:9360; number_of_response:1; }","duration":"136.143595ms","start":"2026-04-16T23:04:36.734541Z","end":"2026-04-16T23:04:36.870685Z","steps":["trace[487277616] 'process raft request' (duration: 129.806373ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:05:18.465358Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-16T23:05:18.471758Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-16T23:05:18.471845Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-16T23:07:43.008679Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8320} {"level":"info","ts":"2026-04-16T23:07:43.094072Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8320,"took":"82.82538ms","hash":1096938266,"current-db-size-bytes":58380288,"current-db-size":"58 MB","current-db-size-in-use-bytes":23871488,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-16T23:07:43.094139Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1096938266,"revision":8320,"compact-revision":6169} {"level":"info","ts":"2026-04-16T23:12:43.015975Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10536} {"level":"info","ts":"2026-04-16T23:12:43.099848Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10536,"took":"80.631918ms","hash":1443580039,"current-db-size-bytes":58380288,"current-db-size":"58 MB","current-db-size-in-use-bytes":21250048,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-16T23:12:43.099921Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1443580039,"revision":10536,"compact-revision":8320}