{"level":"warn","ts":"2026-03-25T13:16:51.137979Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T13:16:51.138181Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.47:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.47:2380","--initial-cluster=instance=https://199.19.213.47:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.47:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.47: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-03-25T13:16:51.138314Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T13:16:51.138333Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.47:2380"]} {"level":"info","ts":"2026-03-25T13:16:51.138369Z","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-03-25T13:16:51.139422Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"]} {"level":"info","ts":"2026-03-25T13:16:51.13956Z","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.19.213.47:2380"],"listen-peer-urls":["https://199.19.213.47:2380"],"advertise-client-urls":["https://199.19.213.47:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.47: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-03-25T13:16:51.144285Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.26066ms"} {"level":"info","ts":"2026-03-25T13:16:51.152154Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"944e7e1987ec6eaf","cluster-id":"cb5c24893c5cbfa7"} {"level":"info","ts":"2026-03-25T13:16:51.152324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=()"} {"level":"info","ts":"2026-03-25T13:16:51.152366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became follower at term 0"} {"level":"info","ts":"2026-03-25T13:16:51.152394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 944e7e1987ec6eaf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-25T13:16:51.152409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became follower at term 1"} {"level":"info","ts":"2026-03-25T13:16:51.152487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=(10686617613916466863)"} {"level":"warn","ts":"2026-03-25T13:16:51.159892Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-25T13:16:51.161732Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-25T13:16:51.162905Z","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-03-25T13:16:51.165977Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"944e7e1987ec6eaf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-25T13:16:51.166638Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"944e7e1987ec6eaf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-25T13:16:51.166731Z","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-03-25T13:16:51.166829Z","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-03-25T13:16:51.166855Z","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-03-25T13:16:51.169953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=(10686617613916466863)"} {"level":"info","ts":"2026-03-25T13:16:51.170997Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cb5c24893c5cbfa7","local-member-id":"944e7e1987ec6eaf","added-peer-id":"944e7e1987ec6eaf","added-peer-peer-urls":["https://199.19.213.47:2380"]} {"level":"info","ts":"2026-03-25T13:16:51.17308Z","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-03-25T13:16:51.173416Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.47:2380"} {"level":"info","ts":"2026-03-25T13:16:51.173445Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.47:2380"} {"level":"info","ts":"2026-03-25T13:16:51.17452Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"944e7e1987ec6eaf","initial-advertise-peer-urls":["https://199.19.213.47:2380"],"listen-peer-urls":["https://199.19.213.47:2380"],"advertise-client-urls":["https://199.19.213.47:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-25T13:16:51.174556Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-25T13:16:52.052831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf is starting a new election at term 1"} {"level":"info","ts":"2026-03-25T13:16:52.052921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became pre-candidate at term 1"} {"level":"info","ts":"2026-03-25T13:16:52.053016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf received MsgPreVoteResp from 944e7e1987ec6eaf at term 1"} {"level":"info","ts":"2026-03-25T13:16:52.053053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became candidate at term 2"} {"level":"info","ts":"2026-03-25T13:16:52.053075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf received MsgVoteResp from 944e7e1987ec6eaf at term 2"} {"level":"info","ts":"2026-03-25T13:16:52.053098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became leader at term 2"} {"level":"info","ts":"2026-03-25T13:16:52.053124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 944e7e1987ec6eaf elected leader 944e7e1987ec6eaf at term 2"} {"level":"info","ts":"2026-03-25T13:16:52.054192Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T13:16:52.055351Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"944e7e1987ec6eaf","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.47:2379]}","request-path":"/0/members/944e7e1987ec6eaf/attributes","cluster-id":"cb5c24893c5cbfa7","publish-timeout":"7s"} {"level":"info","ts":"2026-03-25T13:16:52.055465Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T13:16:52.055506Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T13:16:52.055671Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-25T13:16:52.055922Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-25T13:16:52.055889Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cb5c24893c5cbfa7","local-member-id":"944e7e1987ec6eaf","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T13:16:52.05623Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T13:16:52.056322Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T13:16:52.059131Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.47:2379"} {"level":"info","ts":"2026-03-25T13:16:52.064514Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-25T13:17:12.018134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.94801ms","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-03-25T13:17:12.018286Z","caller":"traceutil/trace.go:171","msg":"trace[1947591354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:455; }","duration":"147.211275ms","start":"2026-03-25T13:17:11.871048Z","end":"2026-03-25T13:17:12.018259Z","steps":["trace[1947591354] 'range keys from in-memory index tree' (duration: 146.790036ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:17:12.17589Z","caller":"traceutil/trace.go:171","msg":"trace[2125192944] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"152.910655ms","start":"2026-03-25T13:17:12.022953Z","end":"2026-03-25T13:17:12.175864Z","steps":["trace[2125192944] 'process raft request' (duration: 152.715721ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:17:12.503928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.696772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T13:17:12.504066Z","caller":"traceutil/trace.go:171","msg":"trace[856386263] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"324.375513ms","start":"2026-03-25T13:17:12.179666Z","end":"2026-03-25T13:17:12.504041Z","steps":["trace[856386263] 'process raft request' (duration: 124.69918ms)","trace[856386263] 'compare' (duration: 198.523038ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T13:17:12.504163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T13:17:12.179644Z","time spent":"324.472206ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T13:17:33.24692Z","caller":"traceutil/trace.go:171","msg":"trace[844570951] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"223.246082ms","start":"2026-03-25T13:17:33.023641Z","end":"2026-03-25T13:17:33.246887Z","steps":["trace[844570951] 'process raft request' (duration: 222.736692ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:17:33.409411Z","caller":"traceutil/trace.go:171","msg":"trace[1751210943] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"145.196565ms","start":"2026-03-25T13:17:33.264176Z","end":"2026-03-25T13:17:33.409373Z","steps":["trace[1751210943] 'process raft request' (duration: 65.809109ms)","trace[1751210943] 'compare' (duration: 79.199992ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T13:17:33.817764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.233179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T13:17:33.81786Z","caller":"traceutil/trace.go:171","msg":"trace[187916609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"231.532628ms","start":"2026-03-25T13:17:33.586306Z","end":"2026-03-25T13:17:33.817839Z","steps":["trace[187916609] 'range keys from in-memory index tree' (duration: 230.128427ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:17:33.838758Z","caller":"traceutil/trace.go:171","msg":"trace[1206812640] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"144.695374ms","start":"2026-03-25T13:17:33.693983Z","end":"2026-03-25T13:17:33.838679Z","steps":["trace[1206812640] 'process raft request' (duration: 142.214981ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:17:50.69882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.181473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T13:17:50.698919Z","caller":"traceutil/trace.go:171","msg":"trace[1089146285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"116.346637ms","start":"2026-03-25T13:17:50.582554Z","end":"2026-03-25T13:17:50.698901Z","steps":["trace[1089146285] 'range keys from in-memory index tree' (duration: 116.081211ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:18:18.975704Z","caller":"traceutil/trace.go:171","msg":"trace[1299729049] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"201.127725ms","start":"2026-03-25T13:18:18.774537Z","end":"2026-03-25T13:18:18.975665Z","steps":["trace[1299729049] 'process raft request' (duration: 200.710176ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:19:08.756371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.424416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T13:19:08.756458Z","caller":"traceutil/trace.go:171","msg":"trace[1581049385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1885; }","duration":"173.579309ms","start":"2026-03-25T13:19:08.582862Z","end":"2026-03-25T13:19:08.756441Z","steps":["trace[1581049385] 'range keys from in-memory index tree' (duration: 173.301863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:19:08.756756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.109317ms","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-03-25T13:19:08.756888Z","caller":"traceutil/trace.go:171","msg":"trace[763294480] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1885; }","duration":"297.28379ms","start":"2026-03-25T13:19:08.45958Z","end":"2026-03-25T13:19:08.756864Z","steps":["trace[763294480] 'range keys from in-memory index tree' (duration: 296.945263ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:19:08.883347Z","caller":"traceutil/trace.go:171","msg":"trace[341224874] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"121.085736ms","start":"2026-03-25T13:19:08.762232Z","end":"2026-03-25T13:19:08.883318Z","steps":["trace[341224874] 'process raft request' (duration: 120.8261ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:19:09.263097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.886702ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T13:19:09.263409Z","caller":"traceutil/trace.go:171","msg":"trace[156214223] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"441.86014ms","start":"2026-03-25T13:19:08.82153Z","end":"2026-03-25T13:19:09.26339Z","steps":["trace[156214223] 'process raft request' (duration: 441.780708ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:19:09.263444Z","caller":"traceutil/trace.go:171","msg":"trace[1450746825] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"500.006423ms","start":"2026-03-25T13:19:08.763395Z","end":"2026-03-25T13:19:09.263402Z","steps":["trace[1450746825] 'process raft request' (duration: 310.702943ms)","trace[1450746825] 'compare' (duration: 188.705108ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T13:19:09.26361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T13:19:08.821493Z","time spent":"442.041494ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T13:19:09.263677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T13:19:08.763368Z","time spent":"500.199207ms","remote":"127.0.0.1:48318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T13:19:09.269257Z","caller":"traceutil/trace.go:171","msg":"trace[1072839723] linearizableReadLoop","detail":"{readStateIndex:1959; appliedIndex:1958; }","duration":"114.453556ms","start":"2026-03-25T13:19:09.154761Z","end":"2026-03-25T13:19:09.269215Z","steps":["trace[1072839723] 'read index received' (duration: 114.13284ms)","trace[1072839723] 'applied index is now lower than readState.Index' (duration: 319.366µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T13:19:09.269451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.65278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eccae0cb-0e7f-4ed9-936d-49aa57947863\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-03-25T13:19:09.269447Z","caller":"traceutil/trace.go:171","msg":"trace[131784459] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"263.146023ms","start":"2026-03-25T13:19:09.00619Z","end":"2026-03-25T13:19:09.269336Z","steps":["trace[131784459] 'process raft request' (duration: 262.840796ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:19:09.269498Z","caller":"traceutil/trace.go:171","msg":"trace[1390187785] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-eccae0cb-0e7f-4ed9-936d-49aa57947863; range_end:; response_count:1; response_revision:1889; }","duration":"114.809484ms","start":"2026-03-25T13:19:09.154677Z","end":"2026-03-25T13:19:09.269486Z","steps":["trace[1390187785] 'agreement among raft nodes before linearized reading' (duration: 114.682101ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:20:10.916322Z","caller":"traceutil/trace.go:171","msg":"trace[758074327] transaction","detail":"{read_only:false; response_revision:2270; number_of_response:1; }","duration":"135.842637ms","start":"2026-03-25T13:20:10.780403Z","end":"2026-03-25T13:20:10.916246Z","steps":["trace[758074327] 'process raft request' (duration: 135.594612ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:21:22.0135Z","caller":"traceutil/trace.go:171","msg":"trace[150876119] transaction","detail":"{read_only:false; response_revision:2816; number_of_response:1; }","duration":"152.440814ms","start":"2026-03-25T13:21:21.861026Z","end":"2026-03-25T13:21:22.013467Z","steps":["trace[150876119] 'process raft request' (duration: 152.229669ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:21:22.026063Z","caller":"traceutil/trace.go:171","msg":"trace[845170003] transaction","detail":"{read_only:false; response_revision:2817; number_of_response:1; }","duration":"112.591163ms","start":"2026-03-25T13:21:21.913428Z","end":"2026-03-25T13:21:22.02602Z","steps":["trace[845170003] 'process raft request' (duration: 112.384749ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:21:55.879389Z","caller":"traceutil/trace.go:171","msg":"trace[1063953263] transaction","detail":"{read_only:false; response_revision:3024; number_of_response:1; }","duration":"119.558922ms","start":"2026-03-25T13:21:55.759784Z","end":"2026-03-25T13:21:55.879343Z","steps":["trace[1063953263] 'process raft request' (duration: 119.226704ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:26:09.359425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.170522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T13:26:09.359577Z","caller":"traceutil/trace.go:171","msg":"trace[1149868828] linearizableReadLoop","detail":"{readStateIndex:5292; appliedIndex:5291; }","duration":"125.009634ms","start":"2026-03-25T13:26:09.234541Z","end":"2026-03-25T13:26:09.359551Z","steps":["trace[1149868828] 'read index received' (duration: 2.534895ms)","trace[1149868828] 'applied index is now lower than readState.Index' (duration: 122.473039ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:26:09.359645Z","caller":"traceutil/trace.go:171","msg":"trace[1319701919] transaction","detail":"{read_only:false; response_revision:5100; number_of_response:1; }","duration":"151.555423ms","start":"2026-03-25T13:26:09.208042Z","end":"2026-03-25T13:26:09.359597Z","steps":["trace[1319701919] 'process raft request' (duration: 29.079594ms)","trace[1319701919] 'compare' (duration: 121.950347ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T13:26:09.359909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.365671ms","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-03-25T13:26:09.359946Z","caller":"traceutil/trace.go:171","msg":"trace[1565701959] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5100; }","duration":"125.443933ms","start":"2026-03-25T13:26:09.234492Z","end":"2026-03-25T13:26:09.359936Z","steps":["trace[1565701959] 'agreement among raft nodes before linearized reading' (duration: 125.240378ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:26:09.360094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.697612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e1c31804-e812-4d9b-8f6f-d2275ff8969e\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-03-25T13:26:09.360171Z","caller":"traceutil/trace.go:171","msg":"trace[1940881808] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e1c31804-e812-4d9b-8f6f-d2275ff8969e; range_end:; response_count:1; response_revision:5100; }","duration":"100.915655ms","start":"2026-03-25T13:26:09.259236Z","end":"2026-03-25T13:26:09.360152Z","steps":["trace[1940881808] 'agreement among raft nodes before linearized reading' (duration: 100.540047ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:26:14.525713Z","caller":"traceutil/trace.go:171","msg":"trace[261100640] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"222.550386ms","start":"2026-03-25T13:26:14.30314Z","end":"2026-03-25T13:26:14.52569Z","steps":["trace[261100640] 'process raft request' (duration: 222.26677ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:26:14.687943Z","caller":"traceutil/trace.go:171","msg":"trace[1470488686] transaction","detail":"{read_only:false; response_revision:5299; number_of_response:1; }","duration":"366.779833ms","start":"2026-03-25T13:26:14.321132Z","end":"2026-03-25T13:26:14.687912Z","steps":["trace[1470488686] 'process raft request' (duration: 305.866625ms)","trace[1470488686] 'compare' (duration: 60.611592ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T13:26:14.688083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T13:26:14.32111Z","time spent":"366.909325ms","remote":"127.0.0.1:44432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2897,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T13:26:16.212977Z","caller":"traceutil/trace.go:171","msg":"trace[494939427] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"105.155887ms","start":"2026-03-25T13:26:16.107796Z","end":"2026-03-25T13:26:16.212952Z","steps":["trace[494939427] 'process raft request' (duration: 105.009764ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:26:24.238593Z","caller":"traceutil/trace.go:171","msg":"trace[56434270] transaction","detail":"{read_only:false; response_revision:5481; number_of_response:1; }","duration":"103.678355ms","start":"2026-03-25T13:26:24.134882Z","end":"2026-03-25T13:26:24.23856Z","steps":["trace[56434270] 'process raft request' (duration: 82.645114ms)","trace[56434270] 'compare' (duration: 20.929789ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:26:24.449133Z","caller":"traceutil/trace.go:171","msg":"trace[293302137] transaction","detail":"{read_only:false; response_revision:5486; number_of_response:1; }","duration":"135.343024ms","start":"2026-03-25T13:26:24.313761Z","end":"2026-03-25T13:26:24.449104Z","steps":["trace[293302137] 'process raft request' (duration: 48.809398ms)","trace[293302137] 'compare' (duration: 86.399293ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:26:35.407687Z","caller":"traceutil/trace.go:171","msg":"trace[1346039033] linearizableReadLoop","detail":"{readStateIndex:5911; appliedIndex:5910; }","duration":"135.12751ms","start":"2026-03-25T13:26:35.272531Z","end":"2026-03-25T13:26:35.407659Z","steps":["trace[1346039033] 'read index received' (duration: 135.025278ms)","trace[1346039033] 'applied index is now lower than readState.Index' (duration: 101.352µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:26:35.4078Z","caller":"traceutil/trace.go:171","msg":"trace[1371224098] transaction","detail":"{read_only:false; response_revision:5702; number_of_response:1; }","duration":"182.147339ms","start":"2026-03-25T13:26:35.225625Z","end":"2026-03-25T13:26:35.407773Z","steps":["trace[1371224098] 'process raft request' (duration: 181.874764ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:26:35.407945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.392295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.47\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-25T13:26:35.408016Z","caller":"traceutil/trace.go:171","msg":"trace[292886486] range","detail":"{range_begin:/registry/masterleases/199.19.213.47; range_end:; response_count:1; response_revision:5702; }","duration":"135.531199ms","start":"2026-03-25T13:26:35.272467Z","end":"2026-03-25T13:26:35.407998Z","steps":["trace[292886486] 'agreement among raft nodes before linearized reading' (duration: 135.308204ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:26:35.545437Z","caller":"traceutil/trace.go:171","msg":"trace[1428612238] transaction","detail":"{read_only:false; response_revision:5705; number_of_response:1; }","duration":"227.460862ms","start":"2026-03-25T13:26:35.317956Z","end":"2026-03-25T13:26:35.545417Z","steps":["trace[1428612238] 'process raft request' (duration: 227.425971ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:26:35.545471Z","caller":"traceutil/trace.go:171","msg":"trace[847220128] transaction","detail":"{read_only:false; response_revision:5703; number_of_response:1; }","duration":"266.145932ms","start":"2026-03-25T13:26:35.279299Z","end":"2026-03-25T13:26:35.545445Z","steps":["trace[847220128] 'process raft request' (duration: 265.906617ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:26:35.545494Z","caller":"traceutil/trace.go:171","msg":"trace[595185382] transaction","detail":"{read_only:false; response_revision:5704; number_of_response:1; }","duration":"230.656411ms","start":"2026-03-25T13:26:35.314811Z","end":"2026-03-25T13:26:35.545467Z","steps":["trace[595185382] 'process raft request' (duration: 230.537178ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:26:35.590693Z","caller":"traceutil/trace.go:171","msg":"trace[1139069398] transaction","detail":"{read_only:false; response_revision:5706; number_of_response:1; }","duration":"156.128381ms","start":"2026-03-25T13:26:35.434538Z","end":"2026-03-25T13:26:35.590666Z","steps":["trace[1139069398] 'process raft request' (duration: 155.964367ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:26:35.590836Z","caller":"traceutil/trace.go:171","msg":"trace[2076056159] transaction","detail":"{read_only:false; response_revision:5707; number_of_response:1; }","duration":"125.28157ms","start":"2026-03-25T13:26:35.465517Z","end":"2026-03-25T13:26:35.590799Z","steps":["trace[2076056159] 'process raft request' (duration: 125.068925ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:26:52.093246Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3001} {"level":"info","ts":"2026-03-25T13:26:52.175329Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3001,"took":"80.728394ms","hash":630004820,"current-db-size-bytes":56057856,"current-db-size":"56 MB","current-db-size-in-use-bytes":29712384,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-25T13:26:52.175413Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":630004820,"revision":3001,"compact-revision":-1} {"level":"info","ts":"2026-03-25T13:31:52.099959Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5922} {"level":"info","ts":"2026-03-25T13:31:52.184515Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5922,"took":"82.527896ms","hash":1280979055,"current-db-size-bytes":56057856,"current-db-size":"56 MB","current-db-size-in-use-bytes":26206208,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-25T13:31:52.184611Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1280979055,"revision":5922,"compact-revision":3001} {"level":"info","ts":"2026-03-25T13:32:57.626877Z","caller":"traceutil/trace.go:171","msg":"trace[528465742] transaction","detail":"{read_only:false; response_revision:8634; number_of_response:1; }","duration":"255.050237ms","start":"2026-03-25T13:32:57.371798Z","end":"2026-03-25T13:32:57.626848Z","steps":["trace[528465742] 'process raft request' (duration: 254.848203ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:33:38.692172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.322784ms","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-03-25T13:33:38.692237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.17865ms","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-03-25T13:33:38.692318Z","caller":"traceutil/trace.go:171","msg":"trace[146279409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8902; }","duration":"435.516148ms","start":"2026-03-25T13:33:38.256781Z","end":"2026-03-25T13:33:38.692298Z","steps":["trace[146279409] 'range keys from in-memory index tree' (duration: 435.075188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:33:38.692409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T13:33:38.256762Z","time spent":"435.632431ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-25T13:33:38.692415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.710755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-25T13:33:38.69245Z","caller":"traceutil/trace.go:171","msg":"trace[798977631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8902; }","duration":"494.774836ms","start":"2026-03-25T13:33:38.197667Z","end":"2026-03-25T13:33:38.692442Z","steps":["trace[798977631] 'range keys from in-memory index tree' (duration: 494.610933ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:33:38.692326Z","caller":"traceutil/trace.go:171","msg":"trace[1595201972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8902; }","duration":"492.284312ms","start":"2026-03-25T13:33:38.200022Z","end":"2026-03-25T13:33:38.692307Z","steps":["trace[1595201972] 'range keys from in-memory index tree' (duration: 491.924024ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:33:38.692709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T13:33:38.199982Z","time spent":"492.703502ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-25T13:33:38.692919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T13:33:38.197646Z","time spent":"495.256127ms","remote":"127.0.0.1:48406","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":"info","ts":"2026-03-25T13:33:38.692965Z","caller":"traceutil/trace.go:171","msg":"trace[1614364494] linearizableReadLoop","detail":"{readStateIndex:9218; appliedIndex:9217; }","duration":"153.583437ms","start":"2026-03-25T13:33:38.539365Z","end":"2026-03-25T13:33:38.692948Z","steps":["trace[1614364494] 'read index received' (duration: 135.646952ms)","trace[1614364494] 'applied index is now lower than readState.Index' (duration: 17.934805ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T13:33:38.693038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.672818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T13:33:38.692999Z","caller":"traceutil/trace.go:171","msg":"trace[2126279835] transaction","detail":"{read_only:false; response_revision:8903; number_of_response:1; }","duration":"321.451778ms","start":"2026-03-25T13:33:38.371486Z","end":"2026-03-25T13:33:38.692938Z","steps":["trace[2126279835] 'process raft request' (duration: 303.543215ms)","trace[2126279835] 'compare' (duration: 16.944732ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T13:33:38.693285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.824713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-03-25T13:33:38.693323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T13:33:38.37147Z","time spent":"321.748204ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T13:33:38.69336Z","caller":"traceutil/trace.go:171","msg":"trace[823533857] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8903; }","duration":"118.937395ms","start":"2026-03-25T13:33:38.574411Z","end":"2026-03-25T13:33:38.693348Z","steps":["trace[823533857] 'agreement among raft nodes before linearized reading' (duration: 118.654619ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:33:38.693087Z","caller":"traceutil/trace.go:171","msg":"trace[2040007418] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:8903; }","duration":"153.714999ms","start":"2026-03-25T13:33:38.539349Z","end":"2026-03-25T13:33:38.693064Z","steps":["trace[2040007418] 'agreement among raft nodes before linearized reading' (duration: 153.655918ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:33:38.693148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.391651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T13:33:38.693606Z","caller":"traceutil/trace.go:171","msg":"trace[1529202346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8903; }","duration":"109.891451ms","start":"2026-03-25T13:33:38.583702Z","end":"2026-03-25T13:33:38.693593Z","steps":["trace[1529202346] 'agreement among raft nodes before linearized reading' (duration: 109.434972ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:33:42.980685Z","caller":"traceutil/trace.go:171","msg":"trace[2039859659] linearizableReadLoop","detail":"{readStateIndex:9240; appliedIndex:9239; }","duration":"259.43015ms","start":"2026-03-25T13:33:42.721233Z","end":"2026-03-25T13:33:42.980663Z","steps":["trace[2039859659] 'read index received' (duration: 259.196005ms)","trace[2039859659] 'applied index is now lower than readState.Index' (duration: 233.265µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:33:42.980773Z","caller":"traceutil/trace.go:171","msg":"trace[694603486] transaction","detail":"{read_only:false; response_revision:8924; number_of_response:1; }","duration":"259.971661ms","start":"2026-03-25T13:33:42.720759Z","end":"2026-03-25T13:33:42.98073Z","steps":["trace[694603486] 'process raft request' (duration: 259.760827ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:33:42.98094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.680545ms","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-03-25T13:33:42.981052Z","caller":"traceutil/trace.go:171","msg":"trace[1917108251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8924; }","duration":"259.814608ms","start":"2026-03-25T13:33:42.721208Z","end":"2026-03-25T13:33:42.981023Z","steps":["trace[1917108251] 'agreement among raft nodes before linearized reading' (duration: 259.549563ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:33:42.981143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.618994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-25T13:33:42.98133Z","caller":"traceutil/trace.go:171","msg":"trace[1153600576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8924; }","duration":"259.816239ms","start":"2026-03-25T13:33:42.721434Z","end":"2026-03-25T13:33:42.98125Z","steps":["trace[1153600576] 'agreement among raft nodes before linearized reading' (duration: 259.379729ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:33:43.155758Z","caller":"traceutil/trace.go:171","msg":"trace[1678955066] transaction","detail":"{read_only:false; response_revision:8927; number_of_response:1; }","duration":"170.254993ms","start":"2026-03-25T13:33:42.985476Z","end":"2026-03-25T13:33:43.155731Z","steps":["trace[1678955066] 'process raft request' (duration: 170.156521ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:33:43.155818Z","caller":"traceutil/trace.go:171","msg":"trace[209270688] transaction","detail":"{read_only:false; response_revision:8926; number_of_response:1; }","duration":"170.57102ms","start":"2026-03-25T13:33:42.985207Z","end":"2026-03-25T13:33:43.155778Z","steps":["trace[209270688] 'process raft request' (duration: 122.861199ms)","trace[209270688] 'compare' (duration: 47.301203ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:33:51.827252Z","caller":"traceutil/trace.go:171","msg":"trace[719596661] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9014; }","duration":"153.116455ms","start":"2026-03-25T13:33:51.674096Z","end":"2026-03-25T13:33:51.827212Z","steps":["trace[719596661] 'process raft request' (duration: 131.711078ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:33:52.073607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.540884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T13:33:52.073804Z","caller":"traceutil/trace.go:171","msg":"trace[1307601956] linearizableReadLoop","detail":"{readStateIndex:9336; appliedIndex:9334; }","duration":"291.984857ms","start":"2026-03-25T13:33:51.7818Z","end":"2026-03-25T13:33:52.073785Z","steps":["trace[1307601956] 'read index received' (duration: 24.073786ms)","trace[1307601956] 'applied index is now lower than readState.Index' (duration: 267.910121ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:33:52.073877Z","caller":"traceutil/trace.go:171","msg":"trace[1637224528] transaction","detail":"{read_only:false; response_revision:9016; number_of_response:1; }","duration":"290.956616ms","start":"2026-03-25T13:33:51.782909Z","end":"2026-03-25T13:33:52.073866Z","steps":["trace[1637224528] 'process raft request' (duration: 290.803983ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:33:52.073938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.14899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-c2ct5\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-03-25T13:33:52.073983Z","caller":"traceutil/trace.go:171","msg":"trace[2061928475] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-c2ct5; range_end:; response_count:1; response_revision:9016; }","duration":"292.221312ms","start":"2026-03-25T13:33:51.781749Z","end":"2026-03-25T13:33:52.07397Z","steps":["trace[2061928475] 'agreement among raft nodes before linearized reading' (duration: 292.13028ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:33:52.074049Z","caller":"traceutil/trace.go:171","msg":"trace[126331973] transaction","detail":"{read_only:false; response_revision:9015; number_of_response:1; }","duration":"394.801287ms","start":"2026-03-25T13:33:51.678984Z","end":"2026-03-25T13:33:52.073785Z","steps":["trace[126331973] 'process raft request' (duration: 269.992277ms)","trace[126331973] 'compare' (duration: 124.420322ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T13:33:52.074179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T13:33:51.678971Z","time spent":"395.136844ms","remote":"127.0.0.1:48318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4632,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T13:33:52.074263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.387637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T13:33:52.074414Z","caller":"traceutil/trace.go:171","msg":"trace[271644559] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:9016; }","duration":"114.667692ms","start":"2026-03-25T13:33:51.959698Z","end":"2026-03-25T13:33:52.074366Z","steps":["trace[271644559] 'agreement among raft nodes before linearized reading' (duration: 114.325725ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:33:52.783614Z","caller":"traceutil/trace.go:171","msg":"trace[228268503] linearizableReadLoop","detail":"{readStateIndex:9340; appliedIndex:9339; }","duration":"126.087307ms","start":"2026-03-25T13:33:52.657498Z","end":"2026-03-25T13:33:52.783585Z","steps":["trace[228268503] 'read index received' (duration: 125.892923ms)","trace[228268503] 'applied index is now lower than readState.Index' (duration: 192.754µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:33:52.783729Z","caller":"traceutil/trace.go:171","msg":"trace[19915067] transaction","detail":"{read_only:false; response_revision:9019; number_of_response:1; }","duration":"173.810269ms","start":"2026-03-25T13:33:52.609884Z","end":"2026-03-25T13:33:52.783695Z","steps":["trace[19915067] 'process raft request' (duration: 173.535423ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:33:52.783811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.302872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-03-25T13:33:52.783856Z","caller":"traceutil/trace.go:171","msg":"trace[1254397725] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:9019; }","duration":"126.392024ms","start":"2026-03-25T13:33:52.657451Z","end":"2026-03-25T13:33:52.783843Z","steps":["trace[1254397725] 'agreement among raft nodes before linearized reading' (duration: 126.23538ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:34:11.197847Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000252e.wal"} {"level":"info","ts":"2026-03-25T13:34:54.397957Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"944e7e1987ec6eaf","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-25T13:34:54.404686Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-25T13:34:54.404829Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-25T13:34:59.818343Z","caller":"traceutil/trace.go:171","msg":"trace[799405389] transaction","detail":"{read_only:false; response_revision:9718; number_of_response:1; }","duration":"117.097856ms","start":"2026-03-25T13:34:59.701217Z","end":"2026-03-25T13:34:59.818315Z","steps":["trace[799405389] 'process raft request' (duration: 45.033863ms)","trace[799405389] 'compare' (duration: 71.600663ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:36:52.106682Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8068} {"level":"info","ts":"2026-03-25T13:36:52.167085Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8068,"took":"57.367448ms","hash":3296727730,"current-db-size-bytes":56057856,"current-db-size":"56 MB","current-db-size-in-use-bytes":28426240,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-25T13:36:52.167147Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3296727730,"revision":8068,"compact-revision":5922} {"level":"info","ts":"2026-03-25T13:41:52.113717Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10474} {"level":"info","ts":"2026-03-25T13:41:52.196754Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10474,"took":"80.957121ms","hash":2976219925,"current-db-size-bytes":56057856,"current-db-size":"56 MB","current-db-size-in-use-bytes":22687744,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-25T13:41:52.196837Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2976219925,"revision":10474,"compact-revision":8068}