{"level":"warn","ts":"2026-07-01T00:44:40.793814Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T00:44:40.793931Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-07-01T00:44:40.794075Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T00:44:40.794296Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-07-01T00:44:40.794322Z","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-07-01T00:44:40.795024Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-07-01T00:44:40.795137Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-07-01T00:44:40.799594Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.205602ms"} {"level":"info","ts":"2026-07-01T00:44:40.813165Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-07-01T00:44:40.813279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T00:44:40.813309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-07-01T00:44:40.81332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T00:44:40.813381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-07-01T00:44:40.813435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-07-01T00:44:40.822575Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T00:44:40.824093Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T00:44:40.825261Z","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-07-01T00:44:40.827254Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T00:44:40.827405Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T00:44:40.827623Z","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-07-01T00:44:40.828554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-07-01T00:44:40.828787Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-07-01T00:44:40.82908Z","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-07-01T00:44:40.829139Z","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-07-01T00:44:40.832916Z","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-07-01T00:44:40.833115Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T00:44:40.833144Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T00:44:40.833232Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-07-01T00:44:40.833248Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-07-01T00:44:41.814103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T00:44:41.81418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T00:44:41.81422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-07-01T00:44:41.814242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-07-01T00:44:41.814255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-07-01T00:44:41.814271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-07-01T00:44:41.814285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-07-01T00:44:41.815327Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T00:44:41.815474Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T00:44:41.815396Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T00:44:41.815918Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T00:44:41.816128Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T00:44:41.81534Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:44:41.817835Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:44:41.817981Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:44:41.818016Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:44:41.818506Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T00:44:41.821622Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"warn","ts":"2026-07-01T00:45:26.199173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.481218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:45:26.199346Z","caller":"traceutil/trace.go:171","msg":"trace[825222899] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"202.697728ms","start":"2026-07-01T00:45:25.996609Z","end":"2026-07-01T00:45:26.199306Z","steps":["trace[825222899] 'process raft request' (duration: 94.448899ms)","trace[825222899] 'compare' (duration: 107.176706ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:45:26.761718Z","caller":"traceutil/trace.go:171","msg":"trace[590258467] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"261.354607ms","start":"2026-07-01T00:45:26.500331Z","end":"2026-07-01T00:45:26.761686Z","steps":["trace[590258467] 'process raft request' (duration: 261.113048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:31.341591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.370598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:45:31.342031Z","caller":"traceutil/trace.go:171","msg":"trace[1526887355] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"147.474251ms","start":"2026-07-01T00:45:31.194422Z","end":"2026-07-01T00:45:31.341896Z","steps":["trace[1526887355] 'process raft request' (duration: 38.688258ms)","trace[1526887355] 'compare' (duration: 108.122109ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:45:45.589134Z","caller":"traceutil/trace.go:171","msg":"trace[1567442073] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"121.179947ms","start":"2026-07-01T00:45:45.467929Z","end":"2026-07-01T00:45:45.589109Z","steps":["trace[1567442073] 'process raft request' (duration: 120.999061ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:46:16.370154Z","caller":"traceutil/trace.go:171","msg":"trace[271559251] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"105.262487ms","start":"2026-07-01T00:46:16.264864Z","end":"2026-07-01T00:46:16.370126Z","steps":["trace[271559251] 'process raft request' (duration: 104.829897ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:46:39.99324Z","caller":"traceutil/trace.go:171","msg":"trace[2103598540] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"101.08555ms","start":"2026-07-01T00:46:39.892054Z","end":"2026-07-01T00:46:39.993139Z","steps":["trace[2103598540] 'process raft request' (duration: 100.892767ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:46:40.614627Z","caller":"traceutil/trace.go:171","msg":"trace[1176848605] linearizableReadLoop","detail":"{readStateIndex:1487; appliedIndex:1486; }","duration":"135.126961ms","start":"2026-07-01T00:46:40.479474Z","end":"2026-07-01T00:46:40.6146Z","steps":["trace[1176848605] 'read index received' (duration: 121.916129ms)","trace[1176848605] 'applied index is now lower than readState.Index' (duration: 13.209432ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:46:40.614766Z","caller":"traceutil/trace.go:171","msg":"trace[1193456712] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"137.070636ms","start":"2026-07-01T00:46:40.477653Z","end":"2026-07-01T00:46:40.614724Z","steps":["trace[1193456712] 'process raft request' (duration: 123.932745ms)","trace[1193456712] 'compare' (duration: 12.856616ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:46:40.614955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.6062ms","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-07-01T00:46:40.614991Z","caller":"traceutil/trace.go:171","msg":"trace[617191135] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1441; }","duration":"135.703342ms","start":"2026-07-01T00:46:40.479277Z","end":"2026-07-01T00:46:40.614981Z","steps":["trace[617191135] 'agreement among raft nodes before linearized reading' (duration: 135.491158ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:04.762029Z","caller":"traceutil/trace.go:171","msg":"trace[1619626665] linearizableReadLoop","detail":"{readStateIndex:1916; appliedIndex:1915; }","duration":"196.293959ms","start":"2026-07-01T00:47:04.565711Z","end":"2026-07-01T00:47:04.762005Z","steps":["trace[1619626665] 'read index received' (duration: 196.112786ms)","trace[1619626665] 'applied index is now lower than readState.Index' (duration: 180.303µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:47:04.762178Z","caller":"traceutil/trace.go:171","msg":"trace[1025441225] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"268.156232ms","start":"2026-07-01T00:47:04.493908Z","end":"2026-07-01T00:47:04.762064Z","steps":["trace[1025441225] 'process raft request' (duration: 267.948009ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:04.762319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.583743ms","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":"info","ts":"2026-07-01T00:47:04.762386Z","caller":"traceutil/trace.go:171","msg":"trace[1769840304] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1844; }","duration":"196.706155ms","start":"2026-07-01T00:47:04.56566Z","end":"2026-07-01T00:47:04.762366Z","steps":["trace[1769840304] 'agreement among raft nodes before linearized reading' (duration: 196.492572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:05.361889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.954073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:47:05.362141Z","caller":"traceutil/trace.go:171","msg":"trace[105512811] linearizableReadLoop","detail":"{readStateIndex:1918; appliedIndex:1916; }","duration":"575.595917ms","start":"2026-07-01T00:47:04.786528Z","end":"2026-07-01T00:47:05.362124Z","steps":["trace[105512811] 'read index received' (duration: 139.27802ms)","trace[105512811] 'applied index is now lower than readState.Index' (duration: 436.317067ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:47:05.362224Z","caller":"traceutil/trace.go:171","msg":"trace[804633025] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"591.866411ms","start":"2026-07-01T00:47:04.77035Z","end":"2026-07-01T00:47:05.362217Z","steps":["trace[804633025] 'process raft request' (duration: 591.665418ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:05.362237Z","caller":"traceutil/trace.go:171","msg":"trace[1969518939] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"592.383668ms","start":"2026-07-01T00:47:04.769797Z","end":"2026-07-01T00:47:05.36218Z","steps":["trace[1969518939] 'process raft request' (duration: 156.03708ms)","trace[1969518939] 'compare' (duration: 434.543473ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:47:05.36242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.900351ms","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-07-01T00:47:05.362452Z","caller":"traceutil/trace.go:171","msg":"trace[872641147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1846; }","duration":"575.964272ms","start":"2026-07-01T00:47:04.78648Z","end":"2026-07-01T00:47:05.362444Z","steps":["trace[872641147] 'agreement among raft nodes before linearized reading' (duration: 575.84125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:05.362482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:04.786466Z","time spent":"576.010602ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T00:47:05.362484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:04.769777Z","time spent":"592.5554ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:47:05.362693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.752632ms","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-07-01T00:47:05.362722Z","caller":"traceutil/trace.go:171","msg":"trace[1027725379] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1846; }","duration":"509.825623ms","start":"2026-07-01T00:47:04.85289Z","end":"2026-07-01T00:47:05.362716Z","steps":["trace[1027725379] 'agreement among raft nodes before linearized reading' (duration: 509.755162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:05.362739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:04.852811Z","time spent":"509.924284ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T00:47:05.362275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:04.770328Z","time spent":"591.920241ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:47:05.36308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.673899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:47:05.363281Z","caller":"traceutil/trace.go:171","msg":"trace[1416960823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1846; }","duration":"181.921252ms","start":"2026-07-01T00:47:05.181353Z","end":"2026-07-01T00:47:05.363275Z","steps":["trace[1416960823] 'agreement among raft nodes before linearized reading' (duration: 181.703558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:05.363411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.41371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-01T00:47:05.36349Z","caller":"traceutil/trace.go:171","msg":"trace[677521448] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:1846; }","duration":"411.519341ms","start":"2026-07-01T00:47:04.95196Z","end":"2026-07-01T00:47:05.363479Z","steps":["trace[677521448] 'agreement among raft nodes before linearized reading' (duration: 411.41215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:05.363514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:04.951941Z","time spent":"411.566562ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-07-01T00:47:05.363665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.16845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b53d7a52-ae86-40d2-b368-32a21398d950\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-07-01T00:47:05.363694Z","caller":"traceutil/trace.go:171","msg":"trace[251336350] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b53d7a52-ae86-40d2-b368-32a21398d950; range_end:; response_count:1; response_revision:1846; }","duration":"508.22224ms","start":"2026-07-01T00:47:04.855463Z","end":"2026-07-01T00:47:05.363685Z","steps":["trace[251336350] 'agreement among raft nodes before linearized reading' (duration: 508.155889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:05.363711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:04.855447Z","time spent":"508.25957ms","remote":"127.0.0.1:52332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3768,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b53d7a52-ae86-40d2-b368-32a21398d950\" "} {"level":"warn","ts":"2026-07-01T00:47:05.712863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.663861ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-07-01T00:47:05.713108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:05.365452Z","time spent":"347.649305ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-01T00:47:05.71342Z","caller":"traceutil/trace.go:171","msg":"trace[1346506380] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"338.89594ms","start":"2026-07-01T00:47:05.374509Z","end":"2026-07-01T00:47:05.713405Z","steps":["trace[1346506380] 'process raft request' (duration: 338.853109ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:05.713361Z","caller":"traceutil/trace.go:171","msg":"trace[1746354791] linearizableReadLoop","detail":"{readStateIndex:1919; appliedIndex:1918; }","duration":"347.445882ms","start":"2026-07-01T00:47:05.365892Z","end":"2026-07-01T00:47:05.713338Z","steps":["trace[1746354791] 'read index received' (duration: 134.373024ms)","trace[1746354791] 'applied index is now lower than readState.Index' (duration: 213.070458ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:47:05.713581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:05.374502Z","time spent":"339.004491ms","remote":"127.0.0.1:52392","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-07-01T00:47:05.713584Z","caller":"traceutil/trace.go:171","msg":"trace[598099644] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"343.354084ms","start":"2026-07-01T00:47:05.370203Z","end":"2026-07-01T00:47:05.713557Z","steps":["trace[598099644] 'process raft request' (duration: 342.859196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:05.713683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.783568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T00:47:05.713715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:05.370187Z","time spent":"343.442685ms","remote":"127.0.0.1:52392","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-07-01T00:47:05.71379Z","caller":"traceutil/trace.go:171","msg":"trace[807278442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1849; }","duration":"347.853099ms","start":"2026-07-01T00:47:05.365853Z","end":"2026-07-01T00:47:05.713706Z","steps":["trace[807278442] 'agreement among raft nodes before linearized reading' (duration: 347.760318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:05.713826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:05.365837Z","time spent":"347.980581ms","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T00:47:05.713842Z","caller":"traceutil/trace.go:171","msg":"trace[750679579] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"343.243793ms","start":"2026-07-01T00:47:05.370586Z","end":"2026-07-01T00:47:05.713829Z","steps":["trace[750679579] 'process raft request' (duration: 342.675165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:05.713908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:05.370579Z","time spent":"343.295454ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:47:05.714201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.081021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18be03ede7951b93\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-07-01T00:47:05.714254Z","caller":"traceutil/trace.go:171","msg":"trace[1009245179] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18be03ede7951b93; range_end:; response_count:1; response_revision:1849; }","duration":"308.161843ms","start":"2026-07-01T00:47:05.406079Z","end":"2026-07-01T00:47:05.714241Z","steps":["trace[1009245179] 'agreement among raft nodes before linearized reading' (duration: 307.929919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:05.714285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:05.406066Z","time spent":"308.212353ms","remote":"127.0.0.1:52206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1006,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18be03ede7951b93\" "} {"level":"warn","ts":"2026-07-01T00:47:05.714928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.294996ms","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-07-01T00:47:05.71504Z","caller":"traceutil/trace.go:171","msg":"trace[589161578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1849; }","duration":"191.447739ms","start":"2026-07-01T00:47:05.523582Z","end":"2026-07-01T00:47:05.715029Z","steps":["trace[589161578] 'agreement among raft nodes before linearized reading' (duration: 190.861451ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:40.457477Z","caller":"traceutil/trace.go:171","msg":"trace[1545495475] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"116.510043ms","start":"2026-07-01T00:47:40.340936Z","end":"2026-07-01T00:47:40.457446Z","steps":["trace[1545495475] 'process raft request' (duration: 116.24425ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:40.503237Z","caller":"traceutil/trace.go:171","msg":"trace[1886105255] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"105.62575ms","start":"2026-07-01T00:47:40.397588Z","end":"2026-07-01T00:47:40.503214Z","steps":["trace[1886105255] 'process raft request' (duration: 74.0063ms)","trace[1886105255] 'compare' (duration: 31.056053ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:53:14.370572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.085717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-01T00:53:14.370646Z","caller":"traceutil/trace.go:171","msg":"trace[845590303] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4738; }","duration":"149.20791ms","start":"2026-07-01T00:53:14.221424Z","end":"2026-07-01T00:53:14.370632Z","steps":["trace[845590303] 'range keys from in-memory index tree' (duration: 148.947286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:14.370639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.982765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:53:14.370716Z","caller":"traceutil/trace.go:171","msg":"trace[1751327596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4738; }","duration":"188.105067ms","start":"2026-07-01T00:53:14.182593Z","end":"2026-07-01T00:53:14.370698Z","steps":["trace[1751327596] 'range keys from in-memory index tree' (duration: 187.913345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:14.370768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.810229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9429dbbb-76bf-48a4-920a-1d8ccf3f6f2b\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-01T00:53:14.370848Z","caller":"traceutil/trace.go:171","msg":"trace[1759794237] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9429dbbb-76bf-48a4-920a-1d8ccf3f6f2b; range_end:; response_count:1; response_revision:4738; }","duration":"300.922311ms","start":"2026-07-01T00:53:14.069902Z","end":"2026-07-01T00:53:14.370824Z","steps":["trace[1759794237] 'range keys from in-memory index tree' (duration: 300.587917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:14.370883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:14.069885Z","time spent":"300.991232ms","remote":"127.0.0.1:52332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3670,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9429dbbb-76bf-48a4-920a-1d8ccf3f6f2b\" "} {"level":"warn","ts":"2026-07-01T00:53:14.370806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.749025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:53:14.371Z","caller":"traceutil/trace.go:171","msg":"trace[184403084] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:4738; }","duration":"357.02638ms","start":"2026-07-01T00:53:14.01395Z","end":"2026-07-01T00:53:14.370977Z","steps":["trace[184403084] 'count revisions from in-memory index tree' (duration: 356.655934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:14.371082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:14.013929Z","time spent":"357.136901ms","remote":"127.0.0.1:34446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"info","ts":"2026-07-01T00:53:17.741686Z","caller":"traceutil/trace.go:171","msg":"trace[1259200081] transaction","detail":"{read_only:false; response_revision:4783; number_of_response:1; }","duration":"353.429606ms","start":"2026-07-01T00:53:17.388243Z","end":"2026-07-01T00:53:17.741672Z","steps":["trace[1259200081] 'process raft request' (duration: 353.327835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:17.741786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:17.388224Z","time spent":"353.509437ms","remote":"127.0.0.1:52332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3951,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:53:18.189818Z","caller":"traceutil/trace.go:171","msg":"trace[702008898] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"160.877023ms","start":"2026-07-01T00:53:18.028918Z","end":"2026-07-01T00:53:18.189795Z","steps":["trace[702008898] 'process raft request' (duration: 160.564209ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:27.694117Z","caller":"traceutil/trace.go:171","msg":"trace[1095113310] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"120.049508ms","start":"2026-07-01T00:53:27.574034Z","end":"2026-07-01T00:53:27.694083Z","steps":["trace[1095113310] 'process raft request' (duration: 119.880366ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:27.997833Z","caller":"traceutil/trace.go:171","msg":"trace[1575864626] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"104.648646ms","start":"2026-07-01T00:53:27.893143Z","end":"2026-07-01T00:53:27.997791Z","steps":["trace[1575864626] 'process raft request' (duration: 45.791511ms)","trace[1575864626] 'compare' (duration: 58.676783ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:54:06.104111Z","caller":"traceutil/trace.go:171","msg":"trace[1634674018] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"129.554668ms","start":"2026-07-01T00:54:05.974523Z","end":"2026-07-01T00:54:06.104078Z","steps":["trace[1634674018] 'process raft request' (duration: 129.343225ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:41.844916Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2857} {"level":"info","ts":"2026-07-01T00:54:41.9588Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2857,"took":"112.734262ms","hash":3232142107,"current-db-size-bytes":53760000,"current-db-size":"54 MB","current-db-size-in-use-bytes":28979200,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-01T00:54:41.95887Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3232142107,"revision":2857,"compact-revision":-1} {"level":"warn","ts":"2026-07-01T00:57:06.572841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.448791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:57:06.572938Z","caller":"traceutil/trace.go:171","msg":"trace[1141674766] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:6525; }","duration":"184.605942ms","start":"2026-07-01T00:57:06.388311Z","end":"2026-07-01T00:57:06.572917Z","steps":["trace[1141674766] 'count revisions from in-memory index tree' (duration: 184.346029ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:59:41.852137Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5533} {"level":"info","ts":"2026-07-01T00:59:41.932464Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5533,"took":"78.868809ms","hash":3375045194,"current-db-size-bytes":53760000,"current-db-size":"54 MB","current-db-size-in-use-bytes":18358272,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-01T00:59:41.932557Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3375045194,"revision":5533,"compact-revision":2857}