{"level":"warn","ts":"2026-08-19T09:10:13.752197Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-19T09:10:13.752329Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.237: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.237:2380","--initial-cluster=instance=https://199.19.213.237:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.237:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.237: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-08-19T09:10:13.752408Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-19T09:10:13.752425Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.237:2380"]} {"level":"info","ts":"2026-08-19T09:10:13.75245Z","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-08-19T09:10:13.753315Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.237:2379"]} {"level":"info","ts":"2026-08-19T09:10:13.753408Z","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.237:2380"],"listen-peer-urls":["https://199.19.213.237:2380"],"advertise-client-urls":["https://199.19.213.237:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.237:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.237: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-08-19T09:10:13.756306Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.632613ms"} {"level":"info","ts":"2026-08-19T09:10:13.761007Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c51d7a9ea5537d6e","cluster-id":"bea7a5ea8f053dc3"} {"level":"info","ts":"2026-08-19T09:10:13.761097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e switched to configuration voters=()"} {"level":"info","ts":"2026-08-19T09:10:13.761124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became follower at term 0"} {"level":"info","ts":"2026-08-19T09:10:13.761138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c51d7a9ea5537d6e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-19T09:10:13.761151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became follower at term 1"} {"level":"info","ts":"2026-08-19T09:10:13.761193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e switched to configuration voters=(14203643621593546094)"} {"level":"warn","ts":"2026-08-19T09:10:13.768199Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-19T09:10:13.770181Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-19T09:10:13.771732Z","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-08-19T09:10:13.776624Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c51d7a9ea5537d6e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-19T09:10:13.776785Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c51d7a9ea5537d6e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-19T09:10:13.776993Z","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-08-19T09:10:13.777086Z","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-08-19T09:10:13.777103Z","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-08-19T09:10:13.779642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e switched to configuration voters=(14203643621593546094)"} {"level":"info","ts":"2026-08-19T09:10:13.779925Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bea7a5ea8f053dc3","local-member-id":"c51d7a9ea5537d6e","added-peer-id":"c51d7a9ea5537d6e","added-peer-peer-urls":["https://199.19.213.237:2380"]} {"level":"info","ts":"2026-08-19T09:10:13.782696Z","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-08-19T09:10:13.782857Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.237:2380"} {"level":"info","ts":"2026-08-19T09:10:13.782902Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.237:2380"} {"level":"info","ts":"2026-08-19T09:10:13.783009Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c51d7a9ea5537d6e","initial-advertise-peer-urls":["https://199.19.213.237:2380"],"listen-peer-urls":["https://199.19.213.237:2380"],"advertise-client-urls":["https://199.19.213.237:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.237:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-19T09:10:13.783036Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-19T09:10:14.062209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e is starting a new election at term 1"} {"level":"info","ts":"2026-08-19T09:10:14.062343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became pre-candidate at term 1"} {"level":"info","ts":"2026-08-19T09:10:14.062399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e received MsgPreVoteResp from c51d7a9ea5537d6e at term 1"} {"level":"info","ts":"2026-08-19T09:10:14.062429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became candidate at term 2"} {"level":"info","ts":"2026-08-19T09:10:14.062449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e received MsgVoteResp from c51d7a9ea5537d6e at term 2"} {"level":"info","ts":"2026-08-19T09:10:14.062469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became leader at term 2"} {"level":"info","ts":"2026-08-19T09:10:14.062491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c51d7a9ea5537d6e elected leader c51d7a9ea5537d6e at term 2"} {"level":"info","ts":"2026-08-19T09:10:14.063264Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c51d7a9ea5537d6e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.237:2379]}","request-path":"/0/members/c51d7a9ea5537d6e/attributes","cluster-id":"bea7a5ea8f053dc3","publish-timeout":"7s"} {"level":"info","ts":"2026-08-19T09:10:14.063284Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T09:10:14.063284Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:10:14.063632Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T09:10:14.064166Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-19T09:10:14.064192Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-19T09:10:14.064502Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bea7a5ea8f053dc3","local-member-id":"c51d7a9ea5537d6e","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:10:14.064697Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:10:14.064742Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:10:14.066455Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-19T09:10:14.06738Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.237:2379"} {"level":"warn","ts":"2026-08-19T09:10:26.103752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.293244ms","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-08-19T09:10:26.103955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.970434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-19T09:10:26.103853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.254649ms","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-08-19T09:10:26.104031Z","caller":"traceutil/trace.go:171","msg":"trace[1301435375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:394; }","duration":"259.060246ms","start":"2026-08-19T09:10:25.844933Z","end":"2026-08-19T09:10:26.103993Z","steps":["trace[1301435375] 'range keys from in-memory index tree' (duration: 258.872881ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:26.104063Z","caller":"traceutil/trace.go:171","msg":"trace[1684046840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:394; }","duration":"239.574916ms","start":"2026-08-19T09:10:25.864476Z","end":"2026-08-19T09:10:26.104051Z","steps":["trace[1684046840] 'range keys from in-memory index tree' (duration: 239.028183ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:26.103958Z","caller":"traceutil/trace.go:171","msg":"trace[172500248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:394; }","duration":"294.56407ms","start":"2026-08-19T09:10:25.80937Z","end":"2026-08-19T09:10:26.103934Z","steps":["trace[172500248] 'range keys from in-memory index tree' (duration: 294.173221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:26.537434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.900708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:10:26.537724Z","caller":"traceutil/trace.go:171","msg":"trace[2048696465] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:409; }","duration":"118.875891ms","start":"2026-08-19T09:10:26.418829Z","end":"2026-08-19T09:10:26.537705Z","steps":["trace[2048696465] 'read index received' (duration: 36.686663ms)","trace[2048696465] 'applied index is now lower than readState.Index' (duration: 82.188148ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:10:26.537705Z","caller":"traceutil/trace.go:171","msg":"trace[1373219622] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"426.65868ms","start":"2026-08-19T09:10:26.110994Z","end":"2026-08-19T09:10:26.537653Z","steps":["trace[1373219622] 'process raft request' (duration: 130.891481ms)","trace[1373219622] 'compare' (duration: 294.750665ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:10:26.537862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:26.110959Z","time spent":"426.863835ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:26.537879Z","caller":"traceutil/trace.go:171","msg":"trace[168355357] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"426.604229ms","start":"2026-08-19T09:10:26.111227Z","end":"2026-08-19T09:10:26.537831Z","steps":["trace[168355357] 'process raft request' (duration: 426.344923ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:26.537918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.077085ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:10:26.538014Z","caller":"traceutil/trace.go:171","msg":"trace[1763977036] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:398; }","duration":"119.185908ms","start":"2026-08-19T09:10:26.418817Z","end":"2026-08-19T09:10:26.538002Z","steps":["trace[1763977036] 'agreement among raft nodes before linearized reading' (duration: 119.055745ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:26.537969Z","caller":"traceutil/trace.go:171","msg":"trace[1765855621] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"425.642256ms","start":"2026-08-19T09:10:26.112256Z","end":"2026-08-19T09:10:26.537898Z","steps":["trace[1765855621] 'process raft request' (duration: 425.386179ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:26.538118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:26.111203Z","time spent":"426.795873ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:10:26.538237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:26.112246Z","time spent":"425.86016ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:51.916526Z","caller":"traceutil/trace.go:171","msg":"trace[485930031] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"359.495794ms","start":"2026-08-19T09:10:51.557009Z","end":"2026-08-19T09:10:51.916505Z","steps":["trace[485930031] 'process raft request' (duration: 359.434212ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:51.916599Z","caller":"traceutil/trace.go:171","msg":"trace[2107546530] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"360.796405ms","start":"2026-08-19T09:10:51.555753Z","end":"2026-08-19T09:10:51.916549Z","steps":["trace[2107546530] 'process raft request' (duration: 360.482007ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:51.916682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:51.556997Z","time spent":"359.608977ms","remote":"127.0.0.1:58430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:10:51.916792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:51.555728Z","time spent":"360.968989ms","remote":"127.0.0.1:58430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T09:10:51.91675Z","caller":"traceutil/trace.go:171","msg":"trace[266221483] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"360.025357ms","start":"2026-08-19T09:10:51.556623Z","end":"2026-08-19T09:10:51.916648Z","steps":["trace[266221483] 'process raft request' (duration: 359.771251ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:51.917075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:51.556614Z","time spent":"360.288623ms","remote":"127.0.0.1:58430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:52.031377Z","caller":"traceutil/trace.go:171","msg":"trace[685419492] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"467.400682ms","start":"2026-08-19T09:10:51.563937Z","end":"2026-08-19T09:10:52.031338Z","steps":["trace[685419492] 'process raft request' (duration: 444.146741ms)","trace[685419492] 'compare' (duration: 23.015815ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:10:52.031572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:51.563903Z","time spent":"467.557385ms","remote":"127.0.0.1:58430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:52.031563Z","caller":"traceutil/trace.go:171","msg":"trace[221905642] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"443.583478ms","start":"2026-08-19T09:10:51.5879Z","end":"2026-08-19T09:10:52.031484Z","steps":["trace[221905642] 'process raft request' (duration: 443.363223ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:52.031726Z","caller":"traceutil/trace.go:171","msg":"trace[694311633] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"442.549973ms","start":"2026-08-19T09:10:51.589163Z","end":"2026-08-19T09:10:52.031713Z","steps":["trace[694311633] 'process raft request' (duration: 442.189254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:52.031795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:51.589138Z","time spent":"442.624915ms","remote":"127.0.0.1:58430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:10:52.031796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:51.587783Z","time spent":"443.899186ms","remote":"127.0.0.1:58430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T09:10:52.031936Z","caller":"traceutil/trace.go:171","msg":"trace[1302744865] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"320.058914ms","start":"2026-08-19T09:10:51.711827Z","end":"2026-08-19T09:10:52.031886Z","steps":["trace[1302744865] 'process raft request' (duration: 319.848659ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:52.032018Z","caller":"traceutil/trace.go:171","msg":"trace[1356784875] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"442.570404ms","start":"2026-08-19T09:10:51.589436Z","end":"2026-08-19T09:10:52.032006Z","steps":["trace[1356784875] 'process raft request' (duration: 442.00215ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:52.032018Z","caller":"traceutil/trace.go:171","msg":"trace[1198944338] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:563; }","duration":"175.675359ms","start":"2026-08-19T09:10:51.856317Z","end":"2026-08-19T09:10:52.031993Z","steps":["trace[1198944338] 'read index received' (duration: 59.962764ms)","trace[1198944338] 'applied index is now lower than readState.Index' (duration: 115.708655ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:10:52.032077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:51.58943Z","time spent":"442.615705ms","remote":"127.0.0.1:58430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:10:52.032106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:51.711786Z","time spent":"320.240638ms","remote":"127.0.0.1:58430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T09:10:52.032189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.855623ms","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-08-19T09:10:52.032239Z","caller":"traceutil/trace.go:171","msg":"trace[1522190764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"175.960196ms","start":"2026-08-19T09:10:51.856264Z","end":"2026-08-19T09:10:52.032224Z","steps":["trace[1522190764] 'agreement among raft nodes before linearized reading' (duration: 175.823503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:59.270905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.578642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:10:59.271165Z","caller":"traceutil/trace.go:171","msg":"trace[1793043304] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"472.484624ms","start":"2026-08-19T09:10:58.798643Z","end":"2026-08-19T09:10:59.271127Z","steps":["trace[1793043304] 'process raft request' (duration: 177.523944ms)","trace[1793043304] 'compare' (duration: 294.354496ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:10:59.271315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:58.798632Z","time spent":"472.605877ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:59.271542Z","caller":"traceutil/trace.go:171","msg":"trace[407674573] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"472.870363ms","start":"2026-08-19T09:10:58.798642Z","end":"2026-08-19T09:10:59.271512Z","steps":["trace[407674573] 'process raft request' (duration: 472.414302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:59.271671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:58.798632Z","time spent":"472.983715ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:59.28307Z","caller":"traceutil/trace.go:171","msg":"trace[455308175] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"214.603796ms","start":"2026-08-19T09:10:59.068409Z","end":"2026-08-19T09:10:59.283013Z","steps":["trace[455308175] 'process raft request' (duration: 213.886699ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:12:06.834587Z","caller":"traceutil/trace.go:171","msg":"trace[1954613587] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"134.839525ms","start":"2026-08-19T09:12:06.699709Z","end":"2026-08-19T09:12:06.834549Z","steps":["trace[1954613587] 'process raft request' (duration: 134.6413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:31.40317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.457794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:12:31.403337Z","caller":"traceutil/trace.go:171","msg":"trace[700514740] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1857; }","duration":"237.70176ms","start":"2026-08-19T09:12:31.165608Z","end":"2026-08-19T09:12:31.403309Z","steps":["trace[700514740] 'count revisions from in-memory index tree' (duration: 237.342141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:31.403495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.623238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-19T09:12:31.403514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.121767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-19T09:12:31.403624Z","caller":"traceutil/trace.go:171","msg":"trace[1705863698] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1857; }","duration":"247.268131ms","start":"2026-08-19T09:12:31.156335Z","end":"2026-08-19T09:12:31.403603Z","steps":["trace[1705863698] 'range keys from in-memory index tree' (duration: 246.843531ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:12:31.40361Z","caller":"traceutil/trace.go:171","msg":"trace[1958296588] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1857; }","duration":"195.787092ms","start":"2026-08-19T09:12:31.207796Z","end":"2026-08-19T09:12:31.403583Z","steps":["trace[1958296588] 'range keys from in-memory index tree' (duration: 195.378482ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:12:31.696773Z","caller":"traceutil/trace.go:171","msg":"trace[2032604428] linearizableReadLoop","detail":"{readStateIndex:1929; appliedIndex:1927; }","duration":"275.67964ms","start":"2026-08-19T09:12:31.421052Z","end":"2026-08-19T09:12:31.696732Z","steps":["trace[2032604428] 'read index received' (duration: 14.702306ms)","trace[2032604428] 'applied index is now lower than readState.Index' (duration: 260.975684ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:12:31.696902Z","caller":"traceutil/trace.go:171","msg":"trace[1960496470] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"287.112159ms","start":"2026-08-19T09:12:31.409648Z","end":"2026-08-19T09:12:31.69676Z","steps":["trace[1960496470] 'process raft request' (duration: 188.967777ms)","trace[1960496470] 'compare' (duration: 97.943097ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:12:31.697227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.146063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-08-19T09:12:31.697266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.453348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-60f0e41e-cf3f-4f7d-bd9b-7c89913e50d0\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-19T09:12:31.697383Z","caller":"traceutil/trace.go:171","msg":"trace[1026268126] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-60f0e41e-cf3f-4f7d-bd9b-7c89913e50d0; range_end:; response_count:1; response_revision:1859; }","duration":"217.592421ms","start":"2026-08-19T09:12:31.479766Z","end":"2026-08-19T09:12:31.697359Z","steps":["trace[1026268126] 'agreement among raft nodes before linearized reading' (duration: 217.275423ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:12:31.697309Z","caller":"traceutil/trace.go:171","msg":"trace[1797084723] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1859; }","duration":"276.293216ms","start":"2026-08-19T09:12:31.42099Z","end":"2026-08-19T09:12:31.697283Z","steps":["trace[1797084723] 'agreement among raft nodes before linearized reading' (duration: 276.01874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:32.396996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.934194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:12:32.397223Z","caller":"traceutil/trace.go:171","msg":"trace[1140221262] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"619.130506ms","start":"2026-08-19T09:12:31.778057Z","end":"2026-08-19T09:12:32.397187Z","steps":["trace[1140221262] 'process raft request' (duration: 71.898615ms)","trace[1140221262] 'compare' (duration: 546.793711ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:12:32.397483Z","caller":"traceutil/trace.go:171","msg":"trace[1707333549] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1931; }","duration":"611.862619ms","start":"2026-08-19T09:12:31.785604Z","end":"2026-08-19T09:12:32.397467Z","steps":["trace[1707333549] 'read index received' (duration: 64.371922ms)","trace[1707333549] 'applied index is now lower than readState.Index' (duration: 547.487577ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:12:32.397469Z","caller":"traceutil/trace.go:171","msg":"trace[1010227578] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"617.426645ms","start":"2026-08-19T09:12:31.779986Z","end":"2026-08-19T09:12:32.397413Z","steps":["trace[1010227578] 'process raft request' (duration: 617.21127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:32.397529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:31.778027Z","time spent":"619.327621ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:12:32.397653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:31.779961Z","time spent":"617.595579ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:12:32.39776Z","caller":"traceutil/trace.go:171","msg":"trace[710469124] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"267.738338ms","start":"2026-08-19T09:12:32.129982Z","end":"2026-08-19T09:12:32.39772Z","steps":["trace[710469124] 'process raft request' (duration: 267.323908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:32.397806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.217598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-60f0e41e-cf3f-4f7d-bd9b-7c89913e50d0\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-19T09:12:32.397865Z","caller":"traceutil/trace.go:171","msg":"trace[879062283] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-60f0e41e-cf3f-4f7d-bd9b-7c89913e50d0; range_end:; response_count:1; response_revision:1863; }","duration":"612.30696ms","start":"2026-08-19T09:12:31.785545Z","end":"2026-08-19T09:12:32.397852Z","steps":["trace[879062283] 'agreement among raft nodes before linearized reading' (duration: 611.970032ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:32.39789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:31.785531Z","time spent":"612.352841ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-60f0e41e-cf3f-4f7d-bd9b-7c89913e50d0\" "} {"level":"info","ts":"2026-08-19T09:12:32.398374Z","caller":"traceutil/trace.go:171","msg":"trace[2118627508] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"185.791809ms","start":"2026-08-19T09:12:32.212563Z","end":"2026-08-19T09:12:32.398354Z","steps":["trace[2118627508] 'process raft request' (duration: 185.516263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:32.398391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.598125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:12:32.398412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.272977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:12:32.398616Z","caller":"traceutil/trace.go:171","msg":"trace[535567479] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1865; }","duration":"478.852481ms","start":"2026-08-19T09:12:31.919732Z","end":"2026-08-19T09:12:32.398584Z","steps":["trace[535567479] 'agreement among raft nodes before linearized reading' (duration: 478.584554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:32.398793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:31.919707Z","time spent":"479.017725ms","remote":"127.0.0.1:55616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:12:32.398608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.020073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:12:32.399084Z","caller":"traceutil/trace.go:171","msg":"trace[1082722037] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1865; }","duration":"338.597278ms","start":"2026-08-19T09:12:32.06047Z","end":"2026-08-19T09:12:32.399067Z","steps":["trace[1082722037] 'agreement among raft nodes before linearized reading' (duration: 338.034824ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:32.399148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:32.06043Z","time spent":"338.695881ms","remote":"127.0.0.1:58708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-08-19T09:12:32.398648Z","caller":"traceutil/trace.go:171","msg":"trace[1828037220] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1865; }","duration":"108.504373ms","start":"2026-08-19T09:12:32.290116Z","end":"2026-08-19T09:12:32.39862Z","steps":["trace[1828037220] 'agreement among raft nodes before linearized reading' (duration: 108.236076ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:13:16.733886Z","caller":"traceutil/trace.go:171","msg":"trace[600092375] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"103.301647ms","start":"2026-08-19T09:13:16.630545Z","end":"2026-08-19T09:13:16.733846Z","steps":["trace[600092375] 'process raft request' (duration: 103.033841ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:13:21.827381Z","caller":"traceutil/trace.go:171","msg":"trace[2014592741] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"116.10197ms","start":"2026-08-19T09:13:21.711241Z","end":"2026-08-19T09:13:21.827343Z","steps":["trace[2014592741] 'process raft request' (duration: 115.831954ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:13:22.102063Z","caller":"traceutil/trace.go:171","msg":"trace[1199602482] linearizableReadLoop","detail":"{readStateIndex:2271; appliedIndex:2270; }","duration":"123.984977ms","start":"2026-08-19T09:13:21.978036Z","end":"2026-08-19T09:13:22.102021Z","steps":["trace[1199602482] 'read index received' (duration: 123.509175ms)","trace[1199602482] 'applied index is now lower than readState.Index' (duration: 473.851µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:13:22.102347Z","caller":"traceutil/trace.go:171","msg":"trace[680474763] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"142.78446ms","start":"2026-08-19T09:13:21.959358Z","end":"2026-08-19T09:13:22.102142Z","steps":["trace[680474763] 'process raft request' (duration: 142.34724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:13:22.102511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.441577ms","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-08-19T09:13:22.102648Z","caller":"traceutil/trace.go:171","msg":"trace[468160323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2189; }","duration":"124.653022ms","start":"2026-08-19T09:13:21.977967Z","end":"2026-08-19T09:13:22.10262Z","steps":["trace[468160323] 'agreement among raft nodes before linearized reading' (duration: 124.258492ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:14:45.065959Z","caller":"traceutil/trace.go:171","msg":"trace[1746497897] transaction","detail":"{read_only:false; response_revision:2832; number_of_response:1; }","duration":"114.964109ms","start":"2026-08-19T09:14:44.950958Z","end":"2026-08-19T09:14:45.065923Z","steps":["trace[1746497897] 'process raft request' (duration: 109.699041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:14:45.402761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.415964ms","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-08-19T09:14:45.402901Z","caller":"traceutil/trace.go:171","msg":"trace[1514846309] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2832; }","duration":"145.604358ms","start":"2026-08-19T09:14:45.257249Z","end":"2026-08-19T09:14:45.402853Z","steps":["trace[1514846309] 'range keys from in-memory index tree' (duration: 145.124426ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:14:45.402947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.677315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-19T09:14:45.40305Z","caller":"traceutil/trace.go:171","msg":"trace[1189091131] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2832; }","duration":"129.831968ms","start":"2026-08-19T09:14:45.273195Z","end":"2026-08-19T09:14:45.403027Z","steps":["trace[1189091131] 'range keys from in-memory index tree' (duration: 129.452189ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:14:45.403152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.531282ms","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-08-19T09:14:45.403283Z","caller":"traceutil/trace.go:171","msg":"trace[1377844512] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2832; }","duration":"139.720886ms","start":"2026-08-19T09:14:45.263535Z","end":"2026-08-19T09:14:45.403256Z","steps":["trace[1377844512] 'range keys from in-memory index tree' (duration: 139.302977ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:14:45.607166Z","caller":"traceutil/trace.go:171","msg":"trace[1645209555] transaction","detail":"{read_only:false; response_revision:2833; number_of_response:1; }","duration":"198.979848ms","start":"2026-08-19T09:14:45.408159Z","end":"2026-08-19T09:14:45.607139Z","steps":["trace[1645209555] 'process raft request' (duration: 198.795463ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:14:45.659348Z","caller":"traceutil/trace.go:171","msg":"trace[610341923] transaction","detail":"{read_only:false; response_revision:2835; number_of_response:1; }","duration":"250.708348ms","start":"2026-08-19T09:14:45.408611Z","end":"2026-08-19T09:14:45.659319Z","steps":["trace[610341923] 'process raft request' (duration: 250.627356ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:14:45.659409Z","caller":"traceutil/trace.go:171","msg":"trace[774717986] transaction","detail":"{read_only:false; response_revision:2834; number_of_response:1; }","duration":"251.21131ms","start":"2026-08-19T09:14:45.408158Z","end":"2026-08-19T09:14:45.659369Z","steps":["trace[774717986] 'process raft request' (duration: 250.904453ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:14:45.659724Z","caller":"traceutil/trace.go:171","msg":"trace[857316211] transaction","detail":"{read_only:false; response_revision:2836; number_of_response:1; }","duration":"206.086469ms","start":"2026-08-19T09:14:45.453624Z","end":"2026-08-19T09:14:45.659711Z","steps":["trace[857316211] 'process raft request' (duration: 205.650549ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:14:45.659867Z","caller":"traceutil/trace.go:171","msg":"trace[1990233531] transaction","detail":"{read_only:false; response_revision:2837; number_of_response:1; }","duration":"199.364848ms","start":"2026-08-19T09:14:45.460491Z","end":"2026-08-19T09:14:45.659856Z","steps":["trace[1990233531] 'process raft request' (duration: 199.149363ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:14:45.659832Z","caller":"traceutil/trace.go:171","msg":"trace[1004133947] linearizableReadLoop","detail":"{readStateIndex:2942; appliedIndex:2939; }","duration":"248.789101ms","start":"2026-08-19T09:14:45.411012Z","end":"2026-08-19T09:14:45.659801Z","steps":["trace[1004133947] 'read index received' (duration: 195.998626ms)","trace[1004133947] 'applied index is now lower than readState.Index' (duration: 52.789375ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:14:45.660126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.094709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1315"} {"level":"info","ts":"2026-08-19T09:14:45.660186Z","caller":"traceutil/trace.go:171","msg":"trace[848572845] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2837; }","duration":"249.219162ms","start":"2026-08-19T09:14:45.410952Z","end":"2026-08-19T09:14:45.660171Z","steps":["trace[848572845] 'agreement among raft nodes before linearized reading' (duration: 248.978886ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:18:49.012383Z","caller":"traceutil/trace.go:171","msg":"trace[2116206465] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"102.107373ms","start":"2026-08-19T09:18:48.910252Z","end":"2026-08-19T09:18:49.012359Z","steps":["trace[2116206465] 'process raft request' (duration: 101.767315ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:18:49.124416Z","caller":"traceutil/trace.go:171","msg":"trace[1211069606] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"107.100865ms","start":"2026-08-19T09:18:49.017277Z","end":"2026-08-19T09:18:49.124378Z","steps":["trace[1211069606] 'process raft request' (duration: 106.959182ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:18:49.124481Z","caller":"traceutil/trace.go:171","msg":"trace[1568526617] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"106.581182ms","start":"2026-08-19T09:18:49.017886Z","end":"2026-08-19T09:18:49.124467Z","steps":["trace[1568526617] 'process raft request' (duration: 106.407418ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:18:49.124476Z","caller":"traceutil/trace.go:171","msg":"trace[1330571213] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"107.120735ms","start":"2026-08-19T09:18:49.017306Z","end":"2026-08-19T09:18:49.124427Z","steps":["trace[1330571213] 'process raft request' (duration: 106.961191ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:18:49.124416Z","caller":"traceutil/trace.go:171","msg":"trace[1473851334] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"107.35041ms","start":"2026-08-19T09:18:49.016996Z","end":"2026-08-19T09:18:49.124346Z","steps":["trace[1473851334] 'process raft request' (duration: 107.078494ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:18:49.125013Z","caller":"traceutil/trace.go:171","msg":"trace[1045803141] linearizableReadLoop","detail":"{readStateIndex:5109; appliedIndex:5104; }","duration":"105.773182ms","start":"2026-08-19T09:18:49.019216Z","end":"2026-08-19T09:18:49.124989Z","steps":["trace[1045803141] 'read index received' (duration: 61.996198ms)","trace[1045803141] 'applied index is now lower than readState.Index' (duration: 43.776054ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:18:49.125151Z","caller":"traceutil/trace.go:171","msg":"trace[1340033188] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"106.493141ms","start":"2026-08-19T09:18:49.018646Z","end":"2026-08-19T09:18:49.125139Z","steps":["trace[1340033188] 'process raft request' (duration: 106.094261ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:18:49.125287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.055449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-08-19T09:18:49.125328Z","caller":"traceutil/trace.go:171","msg":"trace[605780133] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4924; }","duration":"106.132241ms","start":"2026-08-19T09:18:49.019184Z","end":"2026-08-19T09:18:49.125317Z","steps":["trace[605780133] 'agreement among raft nodes before linearized reading' (duration: 105.907345ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:18:49.243895Z","caller":"traceutil/trace.go:171","msg":"trace[1390387292] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"102.546805ms","start":"2026-08-19T09:18:49.141302Z","end":"2026-08-19T09:18:49.243849Z","steps":["trace[1390387292] 'process raft request' (duration: 101.52663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:18:49.244018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.240447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-08-19T09:18:49.244104Z","caller":"traceutil/trace.go:171","msg":"trace[1597413098] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4928; }","duration":"102.36434ms","start":"2026-08-19T09:18:49.14172Z","end":"2026-08-19T09:18:49.244084Z","steps":["trace[1597413098] 'agreement among raft nodes before linearized reading' (duration: 102.130205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:18:53.241181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.971814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:18:53.241382Z","caller":"traceutil/trace.go:171","msg":"trace[2067425870] linearizableReadLoop","detail":"{readStateIndex:5268; appliedIndex:5266; }","duration":"215.232466ms","start":"2026-08-19T09:18:53.02613Z","end":"2026-08-19T09:18:53.241362Z","steps":["trace[2067425870] 'read index received' (duration: 66.048197ms)","trace[2067425870] 'applied index is now lower than readState.Index' (duration: 149.183249ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:18:53.241443Z","caller":"traceutil/trace.go:171","msg":"trace[1114868916] transaction","detail":"{read_only:false; response_revision:5076; number_of_response:1; }","duration":"258.855936ms","start":"2026-08-19T09:18:52.982575Z","end":"2026-08-19T09:18:53.241431Z","steps":["trace[1114868916] 'process raft request' (duration: 109.567395ms)","trace[1114868916] 'compare' (duration: 148.847421ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:18:53.241443Z","caller":"traceutil/trace.go:171","msg":"trace[1642738088] transaction","detail":"{read_only:false; response_revision:5077; number_of_response:1; }","duration":"258.396345ms","start":"2026-08-19T09:18:52.983005Z","end":"2026-08-19T09:18:53.241401Z","steps":["trace[1642738088] 'process raft request' (duration: 258.290512ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:18:53.241667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.543342ms","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-08-19T09:18:53.24173Z","caller":"traceutil/trace.go:171","msg":"trace[882936628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5077; }","duration":"215.737448ms","start":"2026-08-19T09:18:53.025978Z","end":"2026-08-19T09:18:53.241715Z","steps":["trace[882936628] 'agreement among raft nodes before linearized reading' (duration: 215.499252ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:18:54.997468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.646983ms","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-08-19T09:18:54.99755Z","caller":"traceutil/trace.go:171","msg":"trace[942646515] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5102; }","duration":"101.793246ms","start":"2026-08-19T09:18:54.895741Z","end":"2026-08-19T09:18:54.997535Z","steps":["trace[942646515] 'range keys from in-memory index tree' (duration: 101.51142ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:19:05.949739Z","caller":"traceutil/trace.go:171","msg":"trace[1526456900] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"130.425922ms","start":"2026-08-19T09:19:05.819264Z","end":"2026-08-19T09:19:05.94969Z","steps":["trace[1526456900] 'process raft request' (duration: 47.367922ms)","trace[1526456900] 'compare' (duration: 82.340253ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:19:05.949969Z","caller":"traceutil/trace.go:171","msg":"trace[1712695293] transaction","detail":"{read_only:false; response_revision:5319; number_of_response:1; }","duration":"129.699514ms","start":"2026-08-19T09:19:05.820246Z","end":"2026-08-19T09:19:05.949946Z","steps":["trace[1712695293] 'process raft request' (duration: 129.199092ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:19:06.846911Z","caller":"traceutil/trace.go:171","msg":"trace[688315413] linearizableReadLoop","detail":"{readStateIndex:5529; appliedIndex:5528; }","duration":"185.331949ms","start":"2026-08-19T09:19:06.661553Z","end":"2026-08-19T09:19:06.846885Z","steps":["trace[688315413] 'read index received' (duration: 185.197786ms)","trace[688315413] 'applied index is now lower than readState.Index' (duration: 129.133µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:19:06.846969Z","caller":"traceutil/trace.go:171","msg":"trace[829799954] transaction","detail":"{read_only:false; response_revision:5330; number_of_response:1; }","duration":"187.428339ms","start":"2026-08-19T09:19:06.659483Z","end":"2026-08-19T09:19:06.846911Z","steps":["trace[829799954] 'process raft request' (duration: 187.198753ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:19:06.847401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.626156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6tsk5\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-19T09:19:06.84751Z","caller":"traceutil/trace.go:171","msg":"trace[749316603] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6tsk5; range_end:; response_count:1; response_revision:5330; }","duration":"185.996465ms","start":"2026-08-19T09:19:06.661494Z","end":"2026-08-19T09:19:06.847491Z","steps":["trace[749316603] 'agreement among raft nodes before linearized reading' (duration: 185.500443ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:19:06.848543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.557805ms","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-08-19T09:19:06.848551Z","caller":"traceutil/trace.go:171","msg":"trace[1423156451] transaction","detail":"{read_only:false; response_revision:5331; number_of_response:1; }","duration":"164.785238ms","start":"2026-08-19T09:19:06.683736Z","end":"2026-08-19T09:19:06.848522Z","steps":["trace[1423156451] 'process raft request' (duration: 164.588024ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:19:06.848615Z","caller":"traceutil/trace.go:171","msg":"trace[151382931] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:5331; }","duration":"174.700639ms","start":"2026-08-19T09:19:06.673898Z","end":"2026-08-19T09:19:06.848598Z","steps":["trace[151382931] 'agreement among raft nodes before linearized reading' (duration: 174.568966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:19:06.848742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.392656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:19:06.848862Z","caller":"traceutil/trace.go:171","msg":"trace[891431588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5331; }","duration":"101.57112ms","start":"2026-08-19T09:19:06.747254Z","end":"2026-08-19T09:19:06.848825Z","steps":["trace[891431588] 'agreement among raft nodes before linearized reading' (duration: 101.334104ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:19:14.216905Z","caller":"traceutil/trace.go:171","msg":"trace[2132723834] transaction","detail":"{read_only:false; response_revision:5529; number_of_response:1; }","duration":"133.671831ms","start":"2026-08-19T09:19:14.083198Z","end":"2026-08-19T09:19:14.21687Z","steps":["trace[2132723834] 'process raft request' (duration: 114.405544ms)","trace[2132723834] 'compare' (duration: 19.056802ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:19:14.217053Z","caller":"traceutil/trace.go:171","msg":"trace[1421851965] linearizableReadLoop","detail":"{readStateIndex:5733; appliedIndex:5731; }","duration":"132.282828ms","start":"2026-08-19T09:19:14.084726Z","end":"2026-08-19T09:19:14.217009Z","steps":["trace[1421851965] 'read index received' (duration: 7.549644ms)","trace[1421851965] 'applied index is now lower than readState.Index' (duration: 124.730024ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:19:14.217137Z","caller":"traceutil/trace.go:171","msg":"trace[904253962] transaction","detail":"{read_only:false; response_revision:5530; number_of_response:1; }","duration":"130.512685ms","start":"2026-08-19T09:19:14.086613Z","end":"2026-08-19T09:19:14.217125Z","steps":["trace[904253962] 'process raft request' (duration: 130.191977ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:19:14.217469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.848178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"warn","ts":"2026-08-19T09:19:14.217496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.716409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-19T09:19:14.217641Z","caller":"traceutil/trace.go:171","msg":"trace[895935559] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5530; }","duration":"132.921674ms","start":"2026-08-19T09:19:14.084684Z","end":"2026-08-19T09:19:14.217605Z","steps":["trace[895935559] 'agreement among raft nodes before linearized reading' (duration: 132.481113ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:19:14.217539Z","caller":"traceutil/trace.go:171","msg":"trace[1822978366] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5530; }","duration":"108.996452ms","start":"2026-08-19T09:19:14.10852Z","end":"2026-08-19T09:19:14.217517Z","steps":["trace[1822978366] 'agreement among raft nodes before linearized reading' (duration: 108.704615ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:19:14.428013Z","caller":"traceutil/trace.go:171","msg":"trace[2102559659] transaction","detail":"{read_only:false; response_revision:5533; number_of_response:1; }","duration":"111.422951ms","start":"2026-08-19T09:19:14.316548Z","end":"2026-08-19T09:19:14.427971Z","steps":["trace[2102559659] 'process raft request' (duration: 111.026241ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:20:14.270673Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2993} {"level":"info","ts":"2026-08-19T09:20:14.340456Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2993,"took":"68.183609ms","hash":3485077880,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":33812480,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-19T09:20:14.340594Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3485077880,"revision":2993,"compact-revision":-1} {"level":"info","ts":"2026-08-19T09:25:14.276983Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6070} {"level":"info","ts":"2026-08-19T09:25:14.382778Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6070,"took":"104.017018ms","hash":2280939917,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":21934080,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-19T09:25:14.382902Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2280939917,"revision":6070,"compact-revision":2993} {"level":"info","ts":"2026-08-19T09:25:20.201878Z","caller":"traceutil/trace.go:171","msg":"trace[982021616] transaction","detail":"{read_only:false; response_revision:8328; number_of_response:1; }","duration":"181.166813ms","start":"2026-08-19T09:25:20.020683Z","end":"2026-08-19T09:25:20.20185Z","steps":["trace[982021616] 'process raft request' (duration: 180.967429ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:25:31.843828Z","caller":"traceutil/trace.go:171","msg":"trace[898181655] linearizableReadLoop","detail":"{readStateIndex:8684; appliedIndex:8683; }","duration":"124.757262ms","start":"2026-08-19T09:25:31.718996Z","end":"2026-08-19T09:25:31.843753Z","steps":["trace[898181655] 'read index received' (duration: 124.530707ms)","trace[898181655] 'applied index is now lower than readState.Index' (duration: 225.365µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:25:31.843882Z","caller":"traceutil/trace.go:171","msg":"trace[1601543978] transaction","detail":"{read_only:false; response_revision:8389; number_of_response:1; }","duration":"200.215597ms","start":"2026-08-19T09:25:31.643637Z","end":"2026-08-19T09:25:31.843853Z","steps":["trace[1601543978] 'process raft request' (duration: 199.963111ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:25:31.84407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.553874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-19T09:25:31.844107Z","caller":"traceutil/trace.go:171","msg":"trace[75103850] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8389; }","duration":"100.653716ms","start":"2026-08-19T09:25:31.743445Z","end":"2026-08-19T09:25:31.844098Z","steps":["trace[75103850] 'agreement among raft nodes before linearized reading' (duration: 100.503783ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:25:31.844188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.160142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-19T09:25:31.844295Z","caller":"traceutil/trace.go:171","msg":"trace[2108323354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8389; }","duration":"125.330467ms","start":"2026-08-19T09:25:31.718943Z","end":"2026-08-19T09:25:31.844274Z","steps":["trace[2108323354] 'agreement among raft nodes before linearized reading' (duration: 124.995749ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:26:00.518743Z","caller":"traceutil/trace.go:171","msg":"trace[1847052523] transaction","detail":"{read_only:false; response_revision:8596; number_of_response:1; }","duration":"140.280579ms","start":"2026-08-19T09:26:00.378436Z","end":"2026-08-19T09:26:00.518717Z","steps":["trace[1847052523] 'process raft request' (duration: 140.122735ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:26:00.716239Z","caller":"traceutil/trace.go:171","msg":"trace[1210718138] linearizableReadLoop","detail":"{readStateIndex:8900; appliedIndex:8898; }","duration":"299.479469ms","start":"2026-08-19T09:26:00.416742Z","end":"2026-08-19T09:26:00.716222Z","steps":["trace[1210718138] 'read index received' (duration: 101.931068ms)","trace[1210718138] 'applied index is now lower than readState.Index' (duration: 197.547771ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:26:00.716329Z","caller":"traceutil/trace.go:171","msg":"trace[1385738187] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"330.778519ms","start":"2026-08-19T09:26:00.385543Z","end":"2026-08-19T09:26:00.716321Z","steps":["trace[1385738187] 'process raft request' (duration: 231.347973ms)","trace[1385738187] 'compare' (duration: 99.177ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:26:00.716396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:26:00.385528Z","time spent":"330.81737ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:26:00.716508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.05624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-19T09:26:00.716565Z","caller":"traceutil/trace.go:171","msg":"trace[1999370296] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8597; }","duration":"251.148663ms","start":"2026-08-19T09:26:00.465402Z","end":"2026-08-19T09:26:00.716551Z","steps":["trace[1999370296] 'agreement among raft nodes before linearized reading' (duration: 251.008499ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:26:00.716594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.861468ms","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-08-19T09:26:00.71661Z","caller":"traceutil/trace.go:171","msg":"trace[1938753310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8597; }","duration":"299.90796ms","start":"2026-08-19T09:26:00.416698Z","end":"2026-08-19T09:26:00.716606Z","steps":["trace[1938753310] 'agreement among raft nodes before linearized reading' (duration: 299.857878ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:26:03.649734Z","caller":"traceutil/trace.go:171","msg":"trace[470740163] transaction","detail":"{read_only:false; response_revision:8613; number_of_response:1; }","duration":"104.301446ms","start":"2026-08-19T09:26:03.54541Z","end":"2026-08-19T09:26:03.649711Z","steps":["trace[470740163] 'process raft request' (duration: 103.934296ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:26:13.935695Z","caller":"traceutil/trace.go:171","msg":"trace[1356094151] transaction","detail":"{read_only:false; response_revision:8710; number_of_response:1; }","duration":"125.221613ms","start":"2026-08-19T09:26:13.810453Z","end":"2026-08-19T09:26:13.935675Z","steps":["trace[1356094151] 'process raft request' (duration: 101.845125ms)","trace[1356094151] 'compare' (duration: 23.233014ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:26:13.935678Z","caller":"traceutil/trace.go:171","msg":"trace[52677953] linearizableReadLoop","detail":"{readStateIndex:9020; appliedIndex:9019; }","duration":"124.69908ms","start":"2026-08-19T09:26:13.810953Z","end":"2026-08-19T09:26:13.935652Z","steps":["trace[52677953] 'read index received' (duration: 101.284541ms)","trace[52677953] 'applied index is now lower than readState.Index' (duration: 23.413639ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:26:13.935853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.894295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-rtmq6\" ","response":"range_response_count:1 size:2802"} {"level":"info","ts":"2026-08-19T09:26:13.935922Z","caller":"traceutil/trace.go:171","msg":"trace[1258229186] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-rtmq6; range_end:; response_count:1; response_revision:8710; }","duration":"125.021738ms","start":"2026-08-19T09:26:13.810882Z","end":"2026-08-19T09:26:13.935904Z","steps":["trace[1258229186] 'agreement among raft nodes before linearized reading' (duration: 124.843304ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:26:14.771198Z","caller":"traceutil/trace.go:171","msg":"trace[358008852] transaction","detail":"{read_only:false; response_revision:8716; number_of_response:1; }","duration":"192.901109ms","start":"2026-08-19T09:26:14.578273Z","end":"2026-08-19T09:26:14.771174Z","steps":["trace[358008852] 'process raft request' (duration: 192.833947ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:26:14.771474Z","caller":"traceutil/trace.go:171","msg":"trace[1328773582] transaction","detail":"{read_only:false; response_revision:8715; number_of_response:1; }","duration":"204.798127ms","start":"2026-08-19T09:26:14.566662Z","end":"2026-08-19T09:26:14.77146Z","steps":["trace[1328773582] 'process raft request' (duration: 164.870876ms)","trace[1328773582] 'compare' (duration: 39.436358ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:26:31.060537Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023e7.wal"} {"level":"info","ts":"2026-08-19T09:27:32.714811Z","caller":"traceutil/trace.go:171","msg":"trace[1018485422] transaction","detail":"{read_only:false; response_revision:9470; number_of_response:1; }","duration":"175.449156ms","start":"2026-08-19T09:27:32.539325Z","end":"2026-08-19T09:27:32.714774Z","steps":["trace[1018485422] 'process raft request' (duration: 148.744036ms)","trace[1018485422] 'compare' (duration: 26.571437ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:27:58.428379Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c51d7a9ea5537d6e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-19T09:27:58.434014Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-19T09:27:58.434132Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-19T09:30:14.282203Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8169} {"level":"info","ts":"2026-08-19T09:30:14.345964Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8169,"took":"61.802913ms","hash":2289185292,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":29671424,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-19T09:30:14.346017Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2289185292,"revision":8169,"compact-revision":6070} {"level":"info","ts":"2026-08-19T09:31:15.578542Z","caller":"traceutil/trace.go:171","msg":"trace[2108800573] linearizableReadLoop","detail":"{readStateIndex:11705; appliedIndex:11704; }","duration":"110.66612ms","start":"2026-08-19T09:31:15.467837Z","end":"2026-08-19T09:31:15.578503Z","steps":["trace[2108800573] 'read index received' (duration: 110.429805ms)","trace[2108800573] 'applied index is now lower than readState.Index' (duration: 234.795µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:31:15.578854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.973858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6tsk5\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-19T09:31:15.578667Z","caller":"traceutil/trace.go:171","msg":"trace[692456702] transaction","detail":"{read_only:false; response_revision:11320; number_of_response:1; }","duration":"116.000369ms","start":"2026-08-19T09:31:15.462615Z","end":"2026-08-19T09:31:15.578615Z","steps":["trace[692456702] 'process raft request' (duration: 115.642741ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:31:15.578907Z","caller":"traceutil/trace.go:171","msg":"trace[2133190501] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6tsk5; range_end:; response_count:1; response_revision:11320; }","duration":"111.092301ms","start":"2026-08-19T09:31:15.467801Z","end":"2026-08-19T09:31:15.578893Z","steps":["trace[2133190501] 'agreement among raft nodes before linearized reading' (duration: 110.855405ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:35:14.28869Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10773} {"level":"info","ts":"2026-08-19T09:35:14.369749Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10773,"took":"78.172465ms","hash":961366584,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":27516928,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-19T09:35:14.369885Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":961366584,"revision":10773,"compact-revision":8169} {"level":"info","ts":"2026-08-19T09:37:26.317975Z","caller":"traceutil/trace.go:171","msg":"trace[1136683435] transaction","detail":"{read_only:false; response_revision:13822; number_of_response:1; }","duration":"104.048634ms","start":"2026-08-19T09:37:26.213897Z","end":"2026-08-19T09:37:26.317946Z","steps":["trace[1136683435] 'process raft request' (duration: 103.554953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:37:43.01811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.018705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-19T09:37:43.018186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.50545ms","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-08-19T09:37:43.018225Z","caller":"traceutil/trace.go:171","msg":"trace[2088763006] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13924; }","duration":"279.192729ms","start":"2026-08-19T09:37:42.739012Z","end":"2026-08-19T09:37:43.018205Z","steps":["trace[2088763006] 'range keys from in-memory index tree' (duration: 278.751999ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:37:43.018275Z","caller":"traceutil/trace.go:171","msg":"trace[640428227] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13924; }","duration":"275.642213ms","start":"2026-08-19T09:37:42.7426Z","end":"2026-08-19T09:37:43.018243Z","steps":["trace[640428227] 'range keys from in-memory index tree' (duration: 275.311906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:37:43.018289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.817319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6tsk5\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-19T09:37:43.018397Z","caller":"traceutil/trace.go:171","msg":"trace[1880420109] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6tsk5; range_end:; response_count:1; response_revision:13924; }","duration":"259.964051ms","start":"2026-08-19T09:37:42.758406Z","end":"2026-08-19T09:37:43.01837Z","steps":["trace[1880420109] 'range keys from in-memory index tree' (duration: 259.604963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:37:43.158321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.8986ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-19T09:37:43.158612Z","caller":"traceutil/trace.go:171","msg":"trace[1818609020] transaction","detail":"{read_only:false; response_revision:13925; number_of_response:1; }","duration":"136.433193ms","start":"2026-08-19T09:37:43.022164Z","end":"2026-08-19T09:37:43.158597Z","steps":["trace[1818609020] 'process raft request' (duration: 136.281059ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:37:43.159373Z","caller":"traceutil/trace.go:171","msg":"trace[1928721508] linearizableReadLoop","detail":"{readStateIndex:14403; appliedIndex:14402; }","duration":"136.239269ms","start":"2026-08-19T09:37:43.023123Z","end":"2026-08-19T09:37:43.159362Z","steps":["trace[1928721508] 'read index received' (duration: 135.539451ms)","trace[1928721508] 'applied index is now lower than readState.Index' (duration: 699.098µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:37:43.159446Z","caller":"traceutil/trace.go:171","msg":"trace[1528874945] transaction","detail":"{read_only:false; response_revision:13926; number_of_response:1; }","duration":"136.699799ms","start":"2026-08-19T09:37:43.022721Z","end":"2026-08-19T09:37:43.159421Z","steps":["trace[1528874945] 'process raft request' (duration: 136.574916ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:37:43.16042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.290794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:63 size:594956"} {"level":"info","ts":"2026-08-19T09:37:43.160467Z","caller":"traceutil/trace.go:171","msg":"trace[90583078] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:63; response_revision:13926; }","duration":"137.376876ms","start":"2026-08-19T09:37:43.02308Z","end":"2026-08-19T09:37:43.160456Z","steps":["trace[90583078] 'agreement among raft nodes before linearized reading' (duration: 136.328321ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:38:05.110578Z","caller":"traceutil/trace.go:171","msg":"trace[1722054532] linearizableReadLoop","detail":"{readStateIndex:14724; appliedIndex:14723; }","duration":"301.195796ms","start":"2026-08-19T09:38:04.809326Z","end":"2026-08-19T09:38:05.110522Z","steps":["trace[1722054532] 'read index received' (duration: 275.867369ms)","trace[1722054532] 'applied index is now lower than readState.Index' (duration: 25.326187ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:38:05.110596Z","caller":"traceutil/trace.go:171","msg":"trace[2058904478] transaction","detail":"{read_only:false; response_revision:14233; number_of_response:1; }","duration":"343.308801ms","start":"2026-08-19T09:38:04.767239Z","end":"2026-08-19T09:38:05.110548Z","steps":["trace[2058904478] 'process raft request' (duration: 317.978614ms)","trace[2058904478] 'compare' (duration: 24.909867ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:38:05.11074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.38517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-19T09:38:05.110935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:38:04.767212Z","time spent":"343.587679ms","remote":"127.0.0.1:58676","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-08-19T09:38:05.111014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.920162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:38:05.111091Z","caller":"traceutil/trace.go:171","msg":"trace[142979342] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14233; }","duration":"301.499324ms","start":"2026-08-19T09:38:04.809309Z","end":"2026-08-19T09:38:05.110809Z","steps":["trace[142979342] 'agreement among raft nodes before linearized reading' (duration: 301.334859ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:38:05.11111Z","caller":"traceutil/trace.go:171","msg":"trace[2088236000] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:14233; }","duration":"205.061036ms","start":"2026-08-19T09:38:04.906023Z","end":"2026-08-19T09:38:05.111084Z","steps":["trace[2088236000] 'agreement among raft nodes before linearized reading' (duration: 204.888361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:38:05.111118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.439378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:38:05.111169Z","caller":"traceutil/trace.go:171","msg":"trace[1597484996] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:14233; }","duration":"153.53798ms","start":"2026-08-19T09:38:04.957615Z","end":"2026-08-19T09:38:05.111153Z","steps":["trace[1597484996] 'agreement among raft nodes before linearized reading' (duration: 153.446948ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:38:05.111226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:38:04.80925Z","time spent":"301.885523ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-08-19T09:39:21.172102Z","caller":"traceutil/trace.go:171","msg":"trace[1945226659] linearizableReadLoop","detail":"{readStateIndex:15503; appliedIndex:15502; }","duration":"179.235536ms","start":"2026-08-19T09:39:20.992838Z","end":"2026-08-19T09:39:21.172074Z","steps":["trace[1945226659] 'read index received' (duration: 177.649896ms)","trace[1945226659] 'applied index is now lower than readState.Index' (duration: 1.58459ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:39:21.17213Z","caller":"traceutil/trace.go:171","msg":"trace[1300623545] transaction","detail":"{read_only:false; response_revision:14990; number_of_response:1; }","duration":"192.506339ms","start":"2026-08-19T09:39:20.979576Z","end":"2026-08-19T09:39:21.172082Z","steps":["trace[1300623545] 'process raft request' (duration: 190.93545ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:39:21.172268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.564204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:39:21.172689Z","caller":"traceutil/trace.go:171","msg":"trace[597663739] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14990; }","duration":"173.033185ms","start":"2026-08-19T09:39:20.999641Z","end":"2026-08-19T09:39:21.172675Z","steps":["trace[597663739] 'agreement among raft nodes before linearized reading' (duration: 172.578894ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:39:21.172329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.485691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-19T09:39:21.172883Z","caller":"traceutil/trace.go:171","msg":"trace[1850286234] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14990; }","duration":"180.087136ms","start":"2026-08-19T09:39:20.992786Z","end":"2026-08-19T09:39:21.172873Z","steps":["trace[1850286234] 'agreement among raft nodes before linearized reading' (duration: 179.41382ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:39:21.172404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.929216ms","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-08-19T09:39:21.172982Z","caller":"traceutil/trace.go:171","msg":"trace[229948094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14990; }","duration":"158.581501ms","start":"2026-08-19T09:39:21.014392Z","end":"2026-08-19T09:39:21.172974Z","steps":["trace[229948094] 'agreement among raft nodes before linearized reading' (duration: 157.850684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:39:21.172411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.682517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-19T09:39:21.17313Z","caller":"traceutil/trace.go:171","msg":"trace[66849183] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14990; }","duration":"173.407294ms","start":"2026-08-19T09:39:20.999711Z","end":"2026-08-19T09:39:21.173119Z","steps":["trace[66849183] 'agreement among raft nodes before linearized reading' (duration: 172.576914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:39:21.172467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.764348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-08-19T09:39:21.173219Z","caller":"traceutil/trace.go:171","msg":"trace[1274334713] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14990; }","duration":"173.545977ms","start":"2026-08-19T09:39:20.999665Z","end":"2026-08-19T09:39:21.173211Z","steps":["trace[1274334713] 'agreement among raft nodes before linearized reading' (duration: 172.735218ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:40:14.295693Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12863} {"level":"info","ts":"2026-08-19T09:40:14.3754Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12863,"took":"76.642318ms","hash":950613612,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":29843456,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-19T09:40:14.375453Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":950613612,"revision":12863,"compact-revision":10773} {"level":"warn","ts":"2026-08-19T09:42:20.188552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.675865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-08-19T09:42:20.188858Z","caller":"traceutil/trace.go:171","msg":"trace[1952987941] transaction","detail":"{read_only:false; response_revision:16413; number_of_response:1; }","duration":"114.267684ms","start":"2026-08-19T09:42:20.07446Z","end":"2026-08-19T09:42:20.188728Z","steps":["trace[1952987941] 'process raft request' (duration: 114.195103ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:42:20.188957Z","caller":"traceutil/trace.go:171","msg":"trace[1845119479] transaction","detail":"{read_only:false; response_revision:16412; number_of_response:1; }","duration":"219.626171ms","start":"2026-08-19T09:42:19.969315Z","end":"2026-08-19T09:42:20.188941Z","steps":["trace[1845119479] 'process raft request' (duration: 115.498694ms)","trace[1845119479] 'compare' (duration: 103.555343ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:42:20.18898Z","caller":"traceutil/trace.go:171","msg":"trace[55398691] linearizableReadLoop","detail":"{readStateIndex:16972; appliedIndex:16971; }","duration":"212.650031ms","start":"2026-08-19T09:42:19.976314Z","end":"2026-08-19T09:42:20.188964Z","steps":["trace[55398691] 'read index received' (duration: 108.730119ms)","trace[55398691] 'applied index is now lower than readState.Index' (duration: 103.918032ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:42:20.189165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.849406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7456"} {"level":"warn","ts":"2026-08-19T09:42:20.189186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.34076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T09:42:20.189189Z","caller":"traceutil/trace.go:171","msg":"trace[789105458] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:16413; }","duration":"212.911927ms","start":"2026-08-19T09:42:19.976267Z","end":"2026-08-19T09:42:20.189179Z","steps":["trace[789105458] 'agreement among raft nodes before linearized reading' (duration: 212.781614ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:42:20.189207Z","caller":"traceutil/trace.go:171","msg":"trace[1869039640] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:16413; }","duration":"193.365061ms","start":"2026-08-19T09:42:19.995835Z","end":"2026-08-19T09:42:20.1892Z","steps":["trace[1869039640] 'agreement among raft nodes before linearized reading' (duration: 193.32497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:42:20.189325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.456375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-08-19T09:42:20.189355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.560117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-neutron\" ","response":"range_response_count:1 size:905"} {"level":"info","ts":"2026-08-19T09:42:20.189358Z","caller":"traceutil/trace.go:171","msg":"trace[808171876] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16413; }","duration":"189.500116ms","start":"2026-08-19T09:42:19.999847Z","end":"2026-08-19T09:42:20.189347Z","steps":["trace[808171876] 'agreement among raft nodes before linearized reading' (duration: 189.370553ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:42:20.189374Z","caller":"traceutil/trace.go:171","msg":"trace[209518563] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-neutron; range_end:; response_count:1; response_revision:16413; }","duration":"189.633559ms","start":"2026-08-19T09:42:19.999735Z","end":"2026-08-19T09:42:20.189368Z","steps":["trace[209518563] 'agreement among raft nodes before linearized reading' (duration: 189.569277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:42:20.189382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.952909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T09:42:20.18952Z","caller":"traceutil/trace.go:171","msg":"trace[561465148] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:16413; }","duration":"188.123183ms","start":"2026-08-19T09:42:20.001377Z","end":"2026-08-19T09:42:20.1895Z","steps":["trace[561465148] 'agreement among raft nodes before linearized reading' (duration: 187.912348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:42:20.189538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.160093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-gw4vq\" ","response":"range_response_count:1 size:14150"} {"level":"warn","ts":"2026-08-19T09:42:20.189547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.473876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:683"} {"level":"info","ts":"2026-08-19T09:42:20.189561Z","caller":"traceutil/trace.go:171","msg":"trace[199651231] range","detail":"{range_begin:/registry/pods/openstack/neutron-metadata-agent-default-gw4vq; range_end:; response_count:1; response_revision:16413; }","duration":"190.214204ms","start":"2026-08-19T09:42:19.999339Z","end":"2026-08-19T09:42:20.189553Z","steps":["trace[199651231] 'agreement among raft nodes before linearized reading' (duration: 190.154173ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:42:20.189577Z","caller":"traceutil/trace.go:171","msg":"trace[753125828] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16413; }","duration":"189.551348ms","start":"2026-08-19T09:42:20.000017Z","end":"2026-08-19T09:42:20.189568Z","steps":["trace[753125828] 'agreement among raft nodes before linearized reading' (duration: 189.400364ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:42:20.189769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.517741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-08-19T09:42:20.189823Z","caller":"traceutil/trace.go:171","msg":"trace[1500947232] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16413; }","duration":"190.601804ms","start":"2026-08-19T09:42:19.999213Z","end":"2026-08-19T09:42:20.189815Z","steps":["trace[1500947232] 'agreement among raft nodes before linearized reading' (duration: 190.492511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:42:26.571705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.345929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:89 size:897959"} {"level":"info","ts":"2026-08-19T09:42:26.571805Z","caller":"traceutil/trace.go:171","msg":"trace[1688515989] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:89; response_revision:16483; }","duration":"108.492183ms","start":"2026-08-19T09:42:26.463295Z","end":"2026-08-19T09:42:26.571787Z","steps":["trace[1688515989] 'range keys from in-memory index tree' (duration: 107.438807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:42:26.572018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.751154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:89 size:897959"} {"level":"info","ts":"2026-08-19T09:42:26.572111Z","caller":"traceutil/trace.go:171","msg":"trace[1263362101] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:89; response_revision:16483; }","duration":"103.04929ms","start":"2026-08-19T09:42:26.469042Z","end":"2026-08-19T09:42:26.572092Z","steps":["trace[1263362101] 'range keys from in-memory index tree' (duration: 101.568574ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:45:14.303051Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15328} {"level":"info","ts":"2026-08-19T09:45:14.404689Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15328,"took":"98.849468ms","hash":3040461933,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":31780864,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-19T09:45:14.404768Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3040461933,"revision":15328,"compact-revision":12863} {"level":"warn","ts":"2026-08-19T09:45:16.249209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.969473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T09:45:16.249333Z","caller":"traceutil/trace.go:171","msg":"trace[798219136] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:17615; }","duration":"120.144387ms","start":"2026-08-19T09:45:16.129156Z","end":"2026-08-19T09:45:16.2493Z","steps":["trace[798219136] 'range keys from in-memory index tree' (duration: 119.882491ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:45:23.321251Z","caller":"traceutil/trace.go:171","msg":"trace[1147897669] linearizableReadLoop","detail":"{readStateIndex:18257; appliedIndex:18256; }","duration":"191.668769ms","start":"2026-08-19T09:45:23.129556Z","end":"2026-08-19T09:45:23.321225Z","steps":["trace[1147897669] 'read index received' (duration: 191.487024ms)","trace[1147897669] 'applied index is now lower than readState.Index' (duration: 180.495µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:45:23.321352Z","caller":"traceutil/trace.go:171","msg":"trace[1457271506] transaction","detail":"{read_only:false; response_revision:17654; number_of_response:1; }","duration":"194.310823ms","start":"2026-08-19T09:45:23.127011Z","end":"2026-08-19T09:45:23.321321Z","steps":["trace[1457271506] 'process raft request' (duration: 194.039536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:45:23.321482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.905485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-19T09:45:23.32153Z","caller":"traceutil/trace.go:171","msg":"trace[690158230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17654; }","duration":"192.020638ms","start":"2026-08-19T09:45:23.129498Z","end":"2026-08-19T09:45:23.321519Z","steps":["trace[690158230] 'agreement among raft nodes before linearized reading' (duration: 191.852694ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:48:48.807174Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c51d7a9ea5537d6e","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-19T09:48:48.81518Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-19T09:48:48.815288Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-19T09:50:14.312867Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17600} {"level":"info","ts":"2026-08-19T09:50:14.404145Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17600,"took":"87.933147ms","hash":1119259608,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":34242560,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-19T09:50:14.404205Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1119259608,"revision":17600,"compact-revision":15328} {"level":"info","ts":"2026-08-19T09:50:59.962548Z","caller":"traceutil/trace.go:171","msg":"trace[250616191] transaction","detail":"{read_only:false; response_revision:20285; number_of_response:1; }","duration":"116.957903ms","start":"2026-08-19T09:50:59.84555Z","end":"2026-08-19T09:50:59.962508Z","steps":["trace[250616191] 'process raft request' (duration: 116.663796ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:51:00.305524Z","caller":"traceutil/trace.go:171","msg":"trace[1260734507] transaction","detail":"{read_only:false; response_revision:20286; number_of_response:1; }","duration":"144.477453ms","start":"2026-08-19T09:51:00.161017Z","end":"2026-08-19T09:51:00.305495Z","steps":["trace[1260734507] 'process raft request' (duration: 144.236587ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:51:00.469236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.145444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-19T09:51:00.469441Z","caller":"traceutil/trace.go:171","msg":"trace[1274807883] linearizableReadLoop","detail":"{readStateIndex:20978; appliedIndex:20976; }","duration":"160.384778ms","start":"2026-08-19T09:51:00.309038Z","end":"2026-08-19T09:51:00.469422Z","steps":["trace[1274807883] 'read index received' (duration: 59.826344ms)","trace[1274807883] 'applied index is now lower than readState.Index' (duration: 100.555534ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:51:00.469586Z","caller":"traceutil/trace.go:171","msg":"trace[523208408] transaction","detail":"{read_only:false; response_revision:20288; number_of_response:1; }","duration":"208.051467ms","start":"2026-08-19T09:51:00.261517Z","end":"2026-08-19T09:51:00.469568Z","steps":["trace[523208408] 'process raft request' (duration: 207.835931ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:51:00.46963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.601694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-60f0e41e-cf3f-4f7d-bd9b-7c89913e50d0\" ","response":"range_response_count:1 size:1304"} {"level":"info","ts":"2026-08-19T09:51:00.469604Z","caller":"traceutil/trace.go:171","msg":"trace[470094066] transaction","detail":"{read_only:false; response_revision:20287; number_of_response:1; }","duration":"208.296393ms","start":"2026-08-19T09:51:00.261268Z","end":"2026-08-19T09:51:00.469564Z","steps":["trace[470094066] 'process raft request' (duration: 107.733068ms)","trace[470094066] 'compare' (duration: 100.019882ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:51:00.469674Z","caller":"traceutil/trace.go:171","msg":"trace[1356139697] range","detail":"{range_begin:/registry/persistentvolumes/pvc-60f0e41e-cf3f-4f7d-bd9b-7c89913e50d0; range_end:; response_count:1; response_revision:20288; }","duration":"160.690426ms","start":"2026-08-19T09:51:00.308971Z","end":"2026-08-19T09:51:00.469662Z","steps":["trace[1356139697] 'agreement among raft nodes before linearized reading' (duration: 160.502851ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:51:00.742259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.501743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-19T09:51:00.74238Z","caller":"traceutil/trace.go:171","msg":"trace[1847571860] transaction","detail":"{read_only:false; response_revision:20289; number_of_response:1; }","duration":"158.126294ms","start":"2026-08-19T09:51:00.584233Z","end":"2026-08-19T09:51:00.742359Z","steps":["trace[1847571860] 'process raft request' (duration: 57.421586ms)","trace[1847571860] 'compare' (duration: 99.99696ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:51:01.238048Z","caller":"traceutil/trace.go:171","msg":"trace[1973760527] transaction","detail":"{read_only:false; response_revision:20290; number_of_response:1; }","duration":"162.230193ms","start":"2026-08-19T09:51:01.075789Z","end":"2026-08-19T09:51:01.238019Z","steps":["trace[1973760527] 'process raft request' (duration: 162.088719ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:51:01.50769Z","caller":"traceutil/trace.go:171","msg":"trace[1856401460] transaction","detail":"{read_only:false; response_revision:20291; number_of_response:1; }","duration":"110.070967ms","start":"2026-08-19T09:51:01.397597Z","end":"2026-08-19T09:51:01.507668Z","steps":["trace[1856401460] 'process raft request' (duration: 109.7775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:51:01.855821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.729014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-19T09:51:01.856081Z","caller":"traceutil/trace.go:171","msg":"trace[1494862526] linearizableReadLoop","detail":"{readStateIndex:20983; appliedIndex:20981; }","duration":"189.103456ms","start":"2026-08-19T09:51:01.666954Z","end":"2026-08-19T09:51:01.856057Z","steps":["trace[1494862526] 'read index received' (duration: 32.071µs)","trace[1494862526] 'applied index is now lower than readState.Index' (duration: 189.069985ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:51:01.856218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.26102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-19T09:51:01.856267Z","caller":"traceutil/trace.go:171","msg":"trace[1127138142] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:20293; }","duration":"189.354972ms","start":"2026-08-19T09:51:01.666898Z","end":"2026-08-19T09:51:01.856253Z","steps":["trace[1127138142] 'agreement among raft nodes before linearized reading' (duration: 189.239479ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:51:01.856329Z","caller":"traceutil/trace.go:171","msg":"trace[1307352346] transaction","detail":"{read_only:false; response_revision:20293; number_of_response:1; }","duration":"343.93026ms","start":"2026-08-19T09:51:01.512376Z","end":"2026-08-19T09:51:01.856306Z","steps":["trace[1307352346] 'process raft request' (duration: 343.583212ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:51:01.856333Z","caller":"traceutil/trace.go:171","msg":"trace[295847951] transaction","detail":"{read_only:false; response_revision:20292; number_of_response:1; }","duration":"344.472283ms","start":"2026-08-19T09:51:01.511818Z","end":"2026-08-19T09:51:01.856291Z","steps":["trace[295847951] 'process raft request' (duration: 128.131795ms)","trace[295847951] 'compare' (duration: 215.509688ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:51:01.856423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:51:01.51236Z","time spent":"344.018222ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:51:01.856664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:51:01.511797Z","time spent":"344.638517ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:54:44.514916Z","caller":"traceutil/trace.go:171","msg":"trace[1925687574] linearizableReadLoop","detail":"{readStateIndex:23178; appliedIndex:23177; }","duration":"139.746637ms","start":"2026-08-19T09:54:44.375149Z","end":"2026-08-19T09:54:44.514896Z","steps":["trace[1925687574] 'read index received' (duration: 139.564833ms)","trace[1925687574] 'applied index is now lower than readState.Index' (duration: 180.774µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:54:44.514968Z","caller":"traceutil/trace.go:171","msg":"trace[1512482933] transaction","detail":"{read_only:false; response_revision:22416; number_of_response:1; }","duration":"159.916007ms","start":"2026-08-19T09:54:44.355023Z","end":"2026-08-19T09:54:44.514939Z","steps":["trace[1512482933] 'process raft request' (duration: 159.651291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:54:44.515159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.986743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-08-19T09:54:44.515217Z","caller":"traceutil/trace.go:171","msg":"trace[873632188] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:22416; }","duration":"140.086725ms","start":"2026-08-19T09:54:44.375116Z","end":"2026-08-19T09:54:44.515202Z","steps":["trace[873632188] 'agreement among raft nodes before linearized reading' (duration: 139.910321ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:54:44.524312Z","caller":"traceutil/trace.go:171","msg":"trace[827131476] transaction","detail":"{read_only:false; response_revision:22417; number_of_response:1; }","duration":"148.218023ms","start":"2026-08-19T09:54:44.376049Z","end":"2026-08-19T09:54:44.524267Z","steps":["trace[827131476] 'process raft request' (duration: 147.911505ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:54:44.524455Z","caller":"traceutil/trace.go:171","msg":"trace[1995394030] transaction","detail":"{read_only:false; response_revision:22418; number_of_response:1; }","duration":"145.178238ms","start":"2026-08-19T09:54:44.379258Z","end":"2026-08-19T09:54:44.524436Z","steps":["trace[1995394030] 'process raft request' (duration: 144.891511ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:54:52.427616Z","caller":"traceutil/trace.go:171","msg":"trace[855228421] linearizableReadLoop","detail":"{readStateIndex:23438; appliedIndex:23437; }","duration":"181.106673ms","start":"2026-08-19T09:54:52.24649Z","end":"2026-08-19T09:54:52.427596Z","steps":["trace[855228421] 'read index received' (duration: 181.00823ms)","trace[855228421] 'applied index is now lower than readState.Index' (duration: 97.263µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:54:52.427938Z","caller":"traceutil/trace.go:171","msg":"trace[1683268532] transaction","detail":"{read_only:false; response_revision:22674; number_of_response:1; }","duration":"190.330087ms","start":"2026-08-19T09:54:52.237594Z","end":"2026-08-19T09:54:52.427924Z","steps":["trace[1683268532] 'process raft request' (duration: 189.865765ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:54:52.428381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.876631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-19T09:54:52.428432Z","caller":"traceutil/trace.go:171","msg":"trace[1814318084] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:22674; }","duration":"181.974143ms","start":"2026-08-19T09:54:52.246446Z","end":"2026-08-19T09:54:52.42842Z","steps":["trace[1814318084] 'agreement among raft nodes before linearized reading' (duration: 181.749578ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:54:52.450077Z","caller":"traceutil/trace.go:171","msg":"trace[1219241947] transaction","detail":"{read_only:false; response_revision:22675; number_of_response:1; }","duration":"161.439744ms","start":"2026-08-19T09:54:52.288602Z","end":"2026-08-19T09:54:52.450042Z","steps":["trace[1219241947] 'process raft request' (duration: 154.634588ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:55:14.326453Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20051} {"level":"info","ts":"2026-08-19T09:55:14.436842Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20051,"took":"107.712057ms","hash":2150887793,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":42385408,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-19T09:55:14.43693Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2150887793,"revision":20051,"compact-revision":17600} {"level":"info","ts":"2026-08-19T10:00:14.332843Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22925} {"level":"info","ts":"2026-08-19T10:00:14.465225Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22925,"took":"125.97037ms","hash":3240493652,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":32280576,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-19T10:00:14.465275Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3240493652,"revision":22925,"compact-revision":20051}