{"level":"warn","ts":"2026-06-29T20:56:43.633092Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-29T20:56:43.633172Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.118: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.118:2380","--initial-cluster=instance=https://199.19.213.118:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.118:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.118: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-06-29T20:56:43.633235Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-29T20:56:43.633247Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.118:2380"]} {"level":"info","ts":"2026-06-29T20:56:43.633262Z","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-06-29T20:56:43.633945Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.118:2379"]} {"level":"info","ts":"2026-06-29T20:56:43.63402Z","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.118:2380"],"listen-peer-urls":["https://199.19.213.118:2380"],"advertise-client-urls":["https://199.19.213.118:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.118:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.118: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-06-29T20:56:43.637864Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.63982ms"} {"level":"info","ts":"2026-06-29T20:56:43.64359Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"511d8c552db01fa8","cluster-id":"8d545d29b73b98f3"} {"level":"info","ts":"2026-06-29T20:56:43.64367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 switched to configuration voters=()"} {"level":"info","ts":"2026-06-29T20:56:43.643697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 became follower at term 0"} {"level":"info","ts":"2026-06-29T20:56:43.643717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 511d8c552db01fa8 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-29T20:56:43.643725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 became follower at term 1"} {"level":"info","ts":"2026-06-29T20:56:43.643764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 switched to configuration voters=(5844982188863397800)"} {"level":"warn","ts":"2026-06-29T20:56:43.654981Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-29T20:56:43.656806Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-29T20:56:43.658559Z","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-06-29T20:56:43.662774Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"511d8c552db01fa8","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-29T20:56:43.662948Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"511d8c552db01fa8","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-29T20:56:43.663168Z","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-06-29T20:56:43.663489Z","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-06-29T20:56:43.663568Z","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-06-29T20:56:43.664311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 switched to configuration voters=(5844982188863397800)"} {"level":"info","ts":"2026-06-29T20:56:43.665753Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8d545d29b73b98f3","local-member-id":"511d8c552db01fa8","added-peer-id":"511d8c552db01fa8","added-peer-peer-urls":["https://199.19.213.118:2380"]} {"level":"info","ts":"2026-06-29T20:56:43.668046Z","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-06-29T20:56:43.668319Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"511d8c552db01fa8","initial-advertise-peer-urls":["https://199.19.213.118:2380"],"listen-peer-urls":["https://199.19.213.118:2380"],"advertise-client-urls":["https://199.19.213.118:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.118:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-29T20:56:43.668366Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-29T20:56:43.669348Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.118:2380"} {"level":"info","ts":"2026-06-29T20:56:43.669406Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.118:2380"} {"level":"info","ts":"2026-06-29T20:56:44.0447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 is starting a new election at term 1"} {"level":"info","ts":"2026-06-29T20:56:44.044781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-29T20:56:44.044838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 received MsgPreVoteResp from 511d8c552db01fa8 at term 1"} {"level":"info","ts":"2026-06-29T20:56:44.044884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 became candidate at term 2"} {"level":"info","ts":"2026-06-29T20:56:44.04491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 received MsgVoteResp from 511d8c552db01fa8 at term 2"} {"level":"info","ts":"2026-06-29T20:56:44.044931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 became leader at term 2"} {"level":"info","ts":"2026-06-29T20:56:44.044951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 511d8c552db01fa8 elected leader 511d8c552db01fa8 at term 2"} {"level":"info","ts":"2026-06-29T20:56:44.045959Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"511d8c552db01fa8","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.118:2379]}","request-path":"/0/members/511d8c552db01fa8/attributes","cluster-id":"8d545d29b73b98f3","publish-timeout":"7s"} {"level":"info","ts":"2026-06-29T20:56:44.045977Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-29T20:56:44.046296Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-29T20:56:44.046336Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-29T20:56:44.046427Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-29T20:56:44.04657Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-29T20:56:44.047391Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8d545d29b73b98f3","local-member-id":"511d8c552db01fa8","cluster-version":"3.5"} {"level":"info","ts":"2026-06-29T20:56:44.047572Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-29T20:56:44.047651Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-29T20:56:44.050463Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.118:2379"} {"level":"info","ts":"2026-06-29T20:56:44.050753Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-29T20:57:00.849779Z","caller":"traceutil/trace.go:171","msg":"trace[730502587] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"182.95041ms","start":"2026-06-29T20:57:00.666801Z","end":"2026-06-29T20:57:00.849752Z","steps":["trace[730502587] 'process raft request' (duration: 182.802797ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T20:57:22.124444Z","caller":"traceutil/trace.go:171","msg":"trace[581053092] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"118.130534ms","start":"2026-06-29T20:57:22.006272Z","end":"2026-06-29T20:57:22.124403Z","steps":["trace[581053092] 'process raft request' (duration: 113.590156ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T20:57:28.577534Z","caller":"traceutil/trace.go:171","msg":"trace[1477251916] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"276.106997ms","start":"2026-06-29T20:57:28.301384Z","end":"2026-06-29T20:57:28.577491Z","steps":["trace[1477251916] 'process raft request' (duration: 272.435518ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T20:57:28.966525Z","caller":"traceutil/trace.go:171","msg":"trace[17137861] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"194.661784ms","start":"2026-06-29T20:57:28.771823Z","end":"2026-06-29T20:57:28.966485Z","steps":["trace[17137861] 'read index received' (duration: 95.697327ms)","trace[17137861] 'applied index is now lower than readState.Index' (duration: 98.962817ms)"],"step_count":2} {"level":"warn","ts":"2026-06-29T20:57:28.96679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.896078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-29T20:57:28.966895Z","caller":"traceutil/trace.go:171","msg":"trace[1687948390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"195.110993ms","start":"2026-06-29T20:57:28.771763Z","end":"2026-06-29T20:57:28.966874Z","steps":["trace[1687948390] 'agreement among raft nodes before linearized reading' (duration: 194.868148ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T20:57:28.966929Z","caller":"traceutil/trace.go:171","msg":"trace[1549858631] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"328.310705ms","start":"2026-06-29T20:57:28.638571Z","end":"2026-06-29T20:57:28.966882Z","steps":["trace[1549858631] 'process raft request' (duration: 229.015711ms)","trace[1549858631] 'compare' (duration: 98.61711ms)"],"step_count":2} {"level":"warn","ts":"2026-06-29T20:57:28.967502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T20:57:28.638538Z","time spent":"328.474049ms","remote":"127.0.0.1:54226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-29T20:57:29.497055Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2281248124536979610,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-29T20:57:29.910436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.711494ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-29T20:57:29.910607Z","caller":"traceutil/trace.go:171","msg":"trace[1474720667] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:644; }","duration":"757.900978ms","start":"2026-06-29T20:57:29.152686Z","end":"2026-06-29T20:57:29.910587Z","steps":["trace[1474720667] 'range keys from in-memory index tree' (duration: 757.690844ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T20:57:29.910689Z","caller":"traceutil/trace.go:171","msg":"trace[2101443399] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"928.212869ms","start":"2026-06-29T20:57:28.982423Z","end":"2026-06-29T20:57:29.910635Z","steps":["trace[2101443399] 'process raft request' (duration: 908.635863ms)","trace[2101443399] 'compare' (duration: 19.017344ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T20:57:29.910763Z","caller":"traceutil/trace.go:171","msg":"trace[1397914527] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"193.387484ms","start":"2026-06-29T20:57:29.717308Z","end":"2026-06-29T20:57:29.910695Z","steps":["trace[1397914527] 'process raft request' (duration: 193.257762ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T20:57:29.910874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T20:57:28.982409Z","time spent":"928.375252ms","remote":"127.0.0.1:54190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-29T20:57:29.911029Z","caller":"traceutil/trace.go:171","msg":"trace[154635421] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"914.350966ms","start":"2026-06-29T20:57:28.996617Z","end":"2026-06-29T20:57:29.910968Z","steps":["trace[154635421] 'read index received' (duration: 894.459393ms)","trace[154635421] 'applied index is now lower than readState.Index' (duration: 19.886973ms)"],"step_count":2} {"level":"warn","ts":"2026-06-29T20:57:29.911339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.695534ms","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-06-29T20:57:29.911376Z","caller":"traceutil/trace.go:171","msg":"trace[1651535442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"914.753716ms","start":"2026-06-29T20:57:28.996611Z","end":"2026-06-29T20:57:29.911365Z","steps":["trace[1651535442] 'agreement among raft nodes before linearized reading' (duration: 914.549951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T20:57:29.911403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T20:57:28.996578Z","time spent":"914.818816ms","remote":"127.0.0.1:54264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-29T20:59:01.160825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.999763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-29T20:59:01.161141Z","caller":"traceutil/trace.go:171","msg":"trace[730732876] linearizableReadLoop","detail":"{readStateIndex:1954; appliedIndex:1950; }","duration":"385.655028ms","start":"2026-06-29T20:59:00.775469Z","end":"2026-06-29T20:59:01.161124Z","steps":["trace[730732876] 'read index received' (duration: 45.098523ms)","trace[730732876] 'applied index is now lower than readState.Index' (duration: 340.554125ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T20:59:01.161245Z","caller":"traceutil/trace.go:171","msg":"trace[1469666976] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"396.922136ms","start":"2026-06-29T20:59:00.764313Z","end":"2026-06-29T20:59:01.161235Z","steps":["trace[1469666976] 'process raft request' (duration: 396.660661ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T20:59:01.161317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T20:59:00.76428Z","time spent":"396.999117ms","remote":"127.0.0.1:54166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-29T20:59:01.161391Z","caller":"traceutil/trace.go:171","msg":"trace[819847411] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"397.059079ms","start":"2026-06-29T20:59:00.764267Z","end":"2026-06-29T20:59:01.161326Z","steps":["trace[819847411] 'process raft request' (duration: 56.175757ms)","trace[819847411] 'compare' (duration: 339.84493ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T20:59:01.161543Z","caller":"traceutil/trace.go:171","msg":"trace[1231335724] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"396.355275ms","start":"2026-06-29T20:59:00.765144Z","end":"2026-06-29T20:59:01.161499Z","steps":["trace[1231335724] 'process raft request' (duration: 395.935515ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T20:59:01.161662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.141477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7c108ffd-a3b0-4d93-bb49-bd6a614a092f\" ","response":"range_response_count:1 size:3741"} {"level":"info","ts":"2026-06-29T20:59:01.161765Z","caller":"traceutil/trace.go:171","msg":"trace[22193201] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7c108ffd-a3b0-4d93-bb49-bd6a614a092f; range_end:; response_count:1; response_revision:1883; }","duration":"330.332111ms","start":"2026-06-29T20:59:00.831407Z","end":"2026-06-29T20:59:01.161739Z","steps":["trace[22193201] 'agreement among raft nodes before linearized reading' (duration: 330.078316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T20:59:01.161824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T20:59:00.765124Z","time spent":"396.6049ms","remote":"127.0.0.1:54166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":977,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-29T20:59:01.161838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T20:59:00.831379Z","time spent":"330.441923ms","remote":"127.0.0.1:54190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3764,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7c108ffd-a3b0-4d93-bb49-bd6a614a092f\" "} {"level":"warn","ts":"2026-06-29T20:59:01.161833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T20:59:00.76424Z","time spent":"397.353996ms","remote":"127.0.0.1:54272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-29T20:59:01.161897Z","caller":"traceutil/trace.go:171","msg":"trace[1006400429] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"397.201993ms","start":"2026-06-29T20:59:00.764676Z","end":"2026-06-29T20:59:01.161878Z","steps":["trace[1006400429] 'process raft request' (duration: 396.364474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T20:59:01.16192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.415825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-29T20:59:01.161999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T20:59:00.76464Z","time spent":"397.305545ms","remote":"127.0.0.1:54272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-29T20:59:01.162066Z","caller":"traceutil/trace.go:171","msg":"trace[1151778720] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1883; }","duration":"386.608219ms","start":"2026-06-29T20:59:00.77542Z","end":"2026-06-29T20:59:01.162029Z","steps":["trace[1151778720] 'agreement among raft nodes before linearized reading' (duration: 386.253872ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T20:59:01.162135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T20:59:00.775409Z","time spent":"386.708771ms","remote":"127.0.0.1:60608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-29T20:59:01.162128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.161826ms","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-06-29T20:59:01.162198Z","caller":"traceutil/trace.go:171","msg":"trace[560749555] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1883; }","duration":"275.268679ms","start":"2026-06-29T20:59:00.886912Z","end":"2026-06-29T20:59:01.16218Z","steps":["trace[560749555] 'agreement among raft nodes before linearized reading' (duration: 275.054814ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T20:59:45.890694Z","caller":"traceutil/trace.go:171","msg":"trace[1703428102] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"112.908337ms","start":"2026-06-29T20:59:45.777754Z","end":"2026-06-29T20:59:45.890663Z","steps":["trace[1703428102] 'process raft request' (duration: 112.729453ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T20:59:52.111935Z","caller":"traceutil/trace.go:171","msg":"trace[553439326] linearizableReadLoop","detail":"{readStateIndex:2289; appliedIndex:2288; }","duration":"123.65611ms","start":"2026-06-29T20:59:51.988236Z","end":"2026-06-29T20:59:52.111892Z","steps":["trace[553439326] 'read index received' (duration: 123.19471ms)","trace[553439326] 'applied index is now lower than readState.Index' (duration: 459.77µs)"],"step_count":2} {"level":"info","ts":"2026-06-29T20:59:52.112066Z","caller":"traceutil/trace.go:171","msg":"trace[30613152] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"202.994109ms","start":"2026-06-29T20:59:51.909038Z","end":"2026-06-29T20:59:52.112033Z","steps":["trace[30613152] 'process raft request' (duration: 202.57433ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T20:59:52.112789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.908106ms","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":"info","ts":"2026-06-29T20:59:52.112886Z","caller":"traceutil/trace.go:171","msg":"trace[54754716] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2207; }","duration":"124.680483ms","start":"2026-06-29T20:59:51.988183Z","end":"2026-06-29T20:59:52.112864Z","steps":["trace[54754716] 'agreement among raft nodes before linearized reading' (duration: 123.804523ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:01:22.634628Z","caller":"traceutil/trace.go:171","msg":"trace[789839484] transaction","detail":"{read_only:false; response_revision:2892; number_of_response:1; }","duration":"132.563762ms","start":"2026-06-29T21:01:22.502035Z","end":"2026-06-29T21:01:22.634599Z","steps":["trace[789839484] 'process raft request' (duration: 132.229105ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:01:22.634678Z","caller":"traceutil/trace.go:171","msg":"trace[523037250] transaction","detail":"{read_only:false; response_revision:2893; number_of_response:1; }","duration":"131.602751ms","start":"2026-06-29T21:01:22.503041Z","end":"2026-06-29T21:01:22.634644Z","steps":["trace[523037250] 'process raft request' (duration: 131.395167ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:03:48.050302Z","caller":"traceutil/trace.go:171","msg":"trace[1531749580] linearizableReadLoop","detail":"{readStateIndex:4002; appliedIndex:4001; }","duration":"322.343518ms","start":"2026-06-29T21:03:47.727927Z","end":"2026-06-29T21:03:48.05027Z","steps":["trace[1531749580] 'read index received' (duration: 322.121023ms)","trace[1531749580] 'applied index is now lower than readState.Index' (duration: 215.855µs)"],"step_count":2} {"level":"info","ts":"2026-06-29T21:03:48.050346Z","caller":"traceutil/trace.go:171","msg":"trace[796798214] transaction","detail":"{read_only:false; response_revision:3858; number_of_response:1; }","duration":"326.08379ms","start":"2026-06-29T21:03:47.724228Z","end":"2026-06-29T21:03:48.050312Z","steps":["trace[796798214] 'process raft request' (duration: 325.828024ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T21:03:48.050519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T21:03:47.724203Z","time spent":"326.217832ms","remote":"127.0.0.1:54264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-29T21:03:48.050534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.577733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-29T21:03:48.050578Z","caller":"traceutil/trace.go:171","msg":"trace[2041050566] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:3858; }","duration":"322.672714ms","start":"2026-06-29T21:03:47.727892Z","end":"2026-06-29T21:03:48.050565Z","steps":["trace[2041050566] 'agreement among raft nodes before linearized reading' (duration: 322.538581ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T21:03:48.050632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T21:03:47.72788Z","time spent":"322.736766ms","remote":"127.0.0.1:55852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":23,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-06-29T21:03:48.062205Z","caller":"traceutil/trace.go:171","msg":"trace[742343065] transaction","detail":"{read_only:false; response_revision:3859; number_of_response:1; }","duration":"334.210756ms","start":"2026-06-29T21:03:47.727938Z","end":"2026-06-29T21:03:48.062149Z","steps":["trace[742343065] 'process raft request' (duration: 333.842568ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T21:03:48.062229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.807787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-29T21:03:48.062433Z","caller":"traceutil/trace.go:171","msg":"trace[1120786958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3859; }","duration":"128.060632ms","start":"2026-06-29T21:03:47.934348Z","end":"2026-06-29T21:03:48.062409Z","steps":["trace[1120786958] 'agreement among raft nodes before linearized reading' (duration: 127.649643ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T21:03:48.062536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.438898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-29T21:03:48.06259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T21:03:47.727928Z","time spent":"334.527643ms","remote":"127.0.0.1:54264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-29T21:03:48.062638Z","caller":"traceutil/trace.go:171","msg":"trace[1167884681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3859; }","duration":"127.565411ms","start":"2026-06-29T21:03:47.935047Z","end":"2026-06-29T21:03:48.062613Z","steps":["trace[1167884681] 'agreement among raft nodes before linearized reading' (duration: 127.272674ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T21:03:48.062526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.701882ms","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-06-29T21:03:48.062764Z","caller":"traceutil/trace.go:171","msg":"trace[762371102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3859; }","duration":"127.183452ms","start":"2026-06-29T21:03:47.935558Z","end":"2026-06-29T21:03:48.062741Z","steps":["trace[762371102] 'agreement among raft nodes before linearized reading' (duration: 126.61169ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:05:12.28956Z","caller":"traceutil/trace.go:171","msg":"trace[1479257822] transaction","detail":"{read_only:false; response_revision:5043; number_of_response:1; }","duration":"144.716412ms","start":"2026-06-29T21:05:12.144823Z","end":"2026-06-29T21:05:12.289539Z","steps":["trace[1479257822] 'process raft request' (duration: 144.60967ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:05:12.455655Z","caller":"traceutil/trace.go:171","msg":"trace[26201653] transaction","detail":"{read_only:false; response_revision:5044; number_of_response:1; }","duration":"157.995993ms","start":"2026-06-29T21:05:12.297635Z","end":"2026-06-29T21:05:12.455631Z","steps":["trace[26201653] 'process raft request' (duration: 157.878671ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:05:12.455981Z","caller":"traceutil/trace.go:171","msg":"trace[1888812848] linearizableReadLoop","detail":"{readStateIndex:5229; appliedIndex:5229; }","duration":"119.899482ms","start":"2026-06-29T21:05:12.336068Z","end":"2026-06-29T21:05:12.455968Z","steps":["trace[1888812848] 'read index received' (duration: 119.893862ms)","trace[1888812848] 'applied index is now lower than readState.Index' (duration: 4.64µs)"],"step_count":2} {"level":"warn","ts":"2026-06-29T21:05:12.456186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.109677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5cd58915-f316-47e2-a83d-f430b76965f7\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-06-29T21:05:12.456347Z","caller":"traceutil/trace.go:171","msg":"trace[1619315229] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5cd58915-f316-47e2-a83d-f430b76965f7; range_end:; response_count:1; response_revision:5044; }","duration":"120.193709ms","start":"2026-06-29T21:05:12.336021Z","end":"2026-06-29T21:05:12.456215Z","steps":["trace[1619315229] 'agreement among raft nodes before linearized reading' (duration: 119.997244ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:05:14.826873Z","caller":"traceutil/trace.go:171","msg":"trace[381832610] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"109.529317ms","start":"2026-06-29T21:05:14.717317Z","end":"2026-06-29T21:05:14.826846Z","steps":["trace[381832610] 'process raft request' (duration: 109.327702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T21:05:32.964986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.441319ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-29T21:05:32.965521Z","caller":"traceutil/trace.go:171","msg":"trace[1815990423] linearizableReadLoop","detail":"{readStateIndex:5647; appliedIndex:5645; }","duration":"105.788975ms","start":"2026-06-29T21:05:32.8597Z","end":"2026-06-29T21:05:32.965489Z","steps":["trace[1815990423] 'read index received' (duration: 85.055894ms)","trace[1815990423] 'applied index is now lower than readState.Index' (duration: 20.731121ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T21:05:32.965545Z","caller":"traceutil/trace.go:171","msg":"trace[22268064] transaction","detail":"{read_only:false; response_revision:5449; number_of_response:1; }","duration":"111.928519ms","start":"2026-06-29T21:05:32.853581Z","end":"2026-06-29T21:05:32.96551Z","steps":["trace[22268064] 'process raft request' (duration: 111.51565ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T21:05:33.08137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.641949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-369e95ce-60e2-420c-a026-2dc647ae4283\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-06-29T21:05:33.081375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.321355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-29T21:05:33.081399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.559294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-369e95ce-60e2-420c-a026-2dc647ae4283\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-29T21:05:33.081484Z","caller":"traceutil/trace.go:171","msg":"trace[1000365019] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-369e95ce-60e2-420c-a026-2dc647ae4283; range_end:; response_count:1; response_revision:5449; }","duration":"221.764472ms","start":"2026-06-29T21:05:32.859696Z","end":"2026-06-29T21:05:33.08146Z","steps":["trace[1000365019] 'agreement among raft nodes before linearized reading' (duration: 105.924668ms)","trace[1000365019] 'range keys from in-memory index tree' (duration: 115.603369ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T21:05:33.081505Z","caller":"traceutil/trace.go:171","msg":"trace[13612036] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:5449; }","duration":"105.518019ms","start":"2026-06-29T21:05:32.975957Z","end":"2026-06-29T21:05:33.081475Z","steps":["trace[13612036] 'count revisions from in-memory index tree' (duration: 105.225312ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:05:33.081514Z","caller":"traceutil/trace.go:171","msg":"trace[1906268393] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-369e95ce-60e2-420c-a026-2dc647ae4283; range_end:; response_count:1; response_revision:5449; }","duration":"118.730646ms","start":"2026-06-29T21:05:32.962762Z","end":"2026-06-29T21:05:33.081493Z","steps":["trace[1906268393] 'range keys from in-memory index tree' (duration: 115.580818ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:05:33.195598Z","caller":"traceutil/trace.go:171","msg":"trace[2078161911] transaction","detail":"{read_only:false; response_revision:5450; number_of_response:1; }","duration":"106.665634ms","start":"2026-06-29T21:05:33.088909Z","end":"2026-06-29T21:05:33.195574Z","steps":["trace[2078161911] 'process raft request' (duration: 106.497101ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:05:44.144909Z","caller":"traceutil/trace.go:171","msg":"trace[262884974] transaction","detail":"{read_only:false; response_revision:5603; number_of_response:1; }","duration":"153.71984ms","start":"2026-06-29T21:05:43.991154Z","end":"2026-06-29T21:05:44.144874Z","steps":["trace[262884974] 'process raft request' (duration: 91.572976ms)","trace[262884974] 'compare' (duration: 61.910859ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T21:06:44.243272Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3010} {"level":"info","ts":"2026-06-29T21:06:44.326313Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3010,"took":"81.674208ms","hash":2664587778,"current-db-size-bytes":59416576,"current-db-size":"59 MB","current-db-size-in-use-bytes":31911936,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-29T21:06:44.3264Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2664587778,"revision":3010,"compact-revision":-1} {"level":"info","ts":"2026-06-29T21:11:44.138503Z","caller":"traceutil/trace.go:171","msg":"trace[158080419] transaction","detail":"{read_only:false; response_revision:8306; number_of_response:1; }","duration":"266.366926ms","start":"2026-06-29T21:11:43.872108Z","end":"2026-06-29T21:11:44.138475Z","steps":["trace[158080419] 'process raft request' (duration: 266.193863ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:11:44.278456Z","caller":"traceutil/trace.go:171","msg":"trace[1437406720] transaction","detail":"{read_only:false; response_revision:8307; number_of_response:1; }","duration":"393.980363ms","start":"2026-06-29T21:11:43.884447Z","end":"2026-06-29T21:11:44.278427Z","steps":["trace[1437406720] 'process raft request' (duration: 374.099962ms)","trace[1437406720] 'compare' (duration: 19.632505ms)"],"step_count":2} {"level":"warn","ts":"2026-06-29T21:11:44.278931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T21:11:43.884409Z","time spent":"394.413022ms","remote":"127.0.0.1:54264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-29T21:11:44.278493Z","caller":"traceutil/trace.go:171","msg":"trace[1277891336] linearizableReadLoop","detail":"{readStateIndex:8594; appliedIndex:8592; }","duration":"339.590363ms","start":"2026-06-29T21:11:43.938869Z","end":"2026-06-29T21:11:44.27846Z","steps":["trace[1277891336] 'read index received' (duration: 199.512186ms)","trace[1277891336] 'applied index is now lower than readState.Index' (duration: 140.077387ms)"],"step_count":2} {"level":"warn","ts":"2026-06-29T21:11:44.278636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.755297ms","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-06-29T21:11:44.27975Z","caller":"traceutil/trace.go:171","msg":"trace[1595116761] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8308; }","duration":"340.690037ms","start":"2026-06-29T21:11:43.938829Z","end":"2026-06-29T21:11:44.279519Z","steps":["trace[1595116761] 'agreement among raft nodes before linearized reading' (duration: 339.705086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T21:11:44.2799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T21:11:43.938808Z","time spent":"341.078365ms","remote":"127.0.0.1:54264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-29T21:11:44.282788Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6115} {"level":"info","ts":"2026-06-29T21:11:44.380069Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6115,"took":"95.443699ms","hash":4212966427,"current-db-size-bytes":59416576,"current-db-size":"59 MB","current-db-size-in-use-bytes":23523328,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-29T21:11:44.380143Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4212966427,"revision":6115,"compact-revision":3010} {"level":"info","ts":"2026-06-29T21:11:56.469572Z","caller":"traceutil/trace.go:171","msg":"trace[624790273] transaction","detail":"{read_only:false; response_revision:8370; number_of_response:1; }","duration":"295.037559ms","start":"2026-06-29T21:11:56.174503Z","end":"2026-06-29T21:11:56.469541Z","steps":["trace[624790273] 'process raft request' (duration: 294.825124ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:11:56.480348Z","caller":"traceutil/trace.go:171","msg":"trace[488211397] linearizableReadLoop","detail":"{readStateIndex:8662; appliedIndex:8659; }","duration":"145.397343ms","start":"2026-06-29T21:11:56.334923Z","end":"2026-06-29T21:11:56.48032Z","steps":["trace[488211397] 'read index received' (duration: 134.365273ms)","trace[488211397] 'applied index is now lower than readState.Index' (duration: 11.03074ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T21:11:56.480388Z","caller":"traceutil/trace.go:171","msg":"trace[834818067] transaction","detail":"{read_only:false; response_revision:8371; number_of_response:1; }","duration":"249.689294ms","start":"2026-06-29T21:11:56.230678Z","end":"2026-06-29T21:11:56.480367Z","steps":["trace[834818067] 'process raft request' (duration: 249.412228ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:11:56.480459Z","caller":"traceutil/trace.go:171","msg":"trace[1013300231] transaction","detail":"{read_only:false; response_revision:8372; number_of_response:1; }","duration":"162.287729ms","start":"2026-06-29T21:11:56.318161Z","end":"2026-06-29T21:11:56.480449Z","steps":["trace[1013300231] 'process raft request' (duration: 162.102565ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T21:11:56.480554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.612308ms","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-06-29T21:11:56.480586Z","caller":"traceutil/trace.go:171","msg":"trace[559395594] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8372; }","duration":"145.694669ms","start":"2026-06-29T21:11:56.334883Z","end":"2026-06-29T21:11:56.480578Z","steps":["trace[559395594] 'agreement among raft nodes before linearized reading' (duration: 145.579697ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:12:23.780359Z","caller":"traceutil/trace.go:171","msg":"trace[2055826631] transaction","detail":"{read_only:false; response_revision:8573; number_of_response:1; }","duration":"228.260581ms","start":"2026-06-29T21:12:23.552078Z","end":"2026-06-29T21:12:23.780338Z","steps":["trace[2055826631] 'process raft request' (duration: 227.677689ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:12:36.159883Z","caller":"traceutil/trace.go:171","msg":"trace[1578822537] transaction","detail":"{read_only:false; response_revision:8679; number_of_response:1; }","duration":"142.251996ms","start":"2026-06-29T21:12:36.017604Z","end":"2026-06-29T21:12:36.159856Z","steps":["trace[1578822537] 'process raft request' (duration: 142.080792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T21:12:36.944231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.031476ms","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-06-29T21:12:36.94432Z","caller":"traceutil/trace.go:171","msg":"trace[1960131762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8685; }","duration":"110.17555ms","start":"2026-06-29T21:12:36.834123Z","end":"2026-06-29T21:12:36.944298Z","steps":["trace[1960131762] 'range keys from in-memory index tree' (duration: 109.823422ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T21:12:36.944229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.265016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-29T21:12:36.944519Z","caller":"traceutil/trace.go:171","msg":"trace[769741578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8685; }","duration":"101.617324ms","start":"2026-06-29T21:12:36.842887Z","end":"2026-06-29T21:12:36.944504Z","steps":["trace[769741578] 'range keys from in-memory index tree' (duration: 101.076253ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T21:12:36.945058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.631463ms","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-06-29T21:12:36.945182Z","caller":"traceutil/trace.go:171","msg":"trace[1449983883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8685; }","duration":"112.802247ms","start":"2026-06-29T21:12:36.832354Z","end":"2026-06-29T21:12:36.945156Z","steps":["trace[1449983883] 'range keys from in-memory index tree' (duration: 111.830125ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:12:37.088684Z","caller":"traceutil/trace.go:171","msg":"trace[132303632] transaction","detail":"{read_only:false; response_revision:8687; number_of_response:1; }","duration":"139.054046ms","start":"2026-06-29T21:12:36.949604Z","end":"2026-06-29T21:12:37.088658Z","steps":["trace[132303632] 'process raft request' (duration: 138.864841ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:12:37.088988Z","caller":"traceutil/trace.go:171","msg":"trace[796590705] transaction","detail":"{read_only:false; response_revision:8688; number_of_response:1; }","duration":"139.305132ms","start":"2026-06-29T21:12:36.949669Z","end":"2026-06-29T21:12:37.088974Z","steps":["trace[796590705] 'process raft request' (duration: 138.947194ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:12:37.251112Z","caller":"traceutil/trace.go:171","msg":"trace[628844829] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"147.198113ms","start":"2026-06-29T21:12:37.103889Z","end":"2026-06-29T21:12:37.251087Z","steps":["trace[628844829] 'process raft request' (duration: 147.059859ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:12:52.669446Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023aa.wal"} {"level":"info","ts":"2026-06-29T21:14:27.471998Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"511d8c552db01fa8","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-29T21:14:27.477327Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-29T21:14:27.477461Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-29T21:16:44.289693Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8308} {"level":"info","ts":"2026-06-29T21:16:44.370129Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8308,"took":"78.598989ms","hash":2363044408,"current-db-size-bytes":59416576,"current-db-size":"59 MB","current-db-size-in-use-bytes":24526848,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-29T21:16:44.370201Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2363044408,"revision":8308,"compact-revision":6115} {"level":"info","ts":"2026-06-29T21:19:30.97848Z","caller":"traceutil/trace.go:171","msg":"trace[1250665754] transaction","detail":"{read_only:false; response_revision:11860; number_of_response:1; }","duration":"128.812183ms","start":"2026-06-29T21:19:30.849623Z","end":"2026-06-29T21:19:30.978436Z","steps":["trace[1250665754] 'process raft request' (duration: 128.627539ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:19:31.20557Z","caller":"traceutil/trace.go:171","msg":"trace[1351960717] transaction","detail":"{read_only:false; response_revision:11863; number_of_response:1; }","duration":"181.542935ms","start":"2026-06-29T21:19:31.023999Z","end":"2026-06-29T21:19:31.205542Z","steps":["trace[1351960717] 'process raft request' (duration: 181.258399ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:19:31.31157Z","caller":"traceutil/trace.go:171","msg":"trace[515450855] transaction","detail":"{read_only:false; response_revision:11865; number_of_response:1; }","duration":"100.629552ms","start":"2026-06-29T21:19:31.210918Z","end":"2026-06-29T21:19:31.311548Z","steps":["trace[515450855] 'process raft request' (duration: 100.040379ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:19:31.311649Z","caller":"traceutil/trace.go:171","msg":"trace[1045447540] transaction","detail":"{read_only:false; response_revision:11866; number_of_response:1; }","duration":"100.262484ms","start":"2026-06-29T21:19:31.211352Z","end":"2026-06-29T21:19:31.311615Z","steps":["trace[1045447540] 'process raft request' (duration: 99.770044ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:21:44.297049Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10534} {"level":"info","ts":"2026-06-29T21:21:44.372985Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10534,"took":"73.599686ms","hash":908438367,"current-db-size-bytes":59416576,"current-db-size":"59 MB","current-db-size-in-use-bytes":25812992,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-29T21:21:44.373076Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":908438367,"revision":10534,"compact-revision":8308} {"level":"warn","ts":"2026-06-29T21:26:02.549678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.692177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-29T21:26:02.549761Z","caller":"traceutil/trace.go:171","msg":"trace[1216833542] linearizableReadLoop","detail":"{readStateIndex:14963; appliedIndex:14962; }","duration":"131.768229ms","start":"2026-06-29T21:26:02.417981Z","end":"2026-06-29T21:26:02.549749Z","steps":["trace[1216833542] 'read index received' (duration: 23.849197ms)","trace[1216833542] 'applied index is now lower than readState.Index' (duration: 107.918242ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T21:26:02.549927Z","caller":"traceutil/trace.go:171","msg":"trace[634586904] transaction","detail":"{read_only:false; response_revision:14472; number_of_response:1; }","duration":"135.96285ms","start":"2026-06-29T21:26:02.413955Z","end":"2026-06-29T21:26:02.549918Z","steps":["trace[634586904] 'process raft request' (duration: 27.968477ms)","trace[634586904] 'compare' (duration: 107.612784ms)"],"step_count":2} {"level":"warn","ts":"2026-06-29T21:26:02.550134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.141307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-29T21:26:02.5502Z","caller":"traceutil/trace.go:171","msg":"trace[971478292] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:14472; }","duration":"132.25416ms","start":"2026-06-29T21:26:02.417929Z","end":"2026-06-29T21:26:02.550183Z","steps":["trace[971478292] 'agreement among raft nodes before linearized reading' (duration: 132.159238ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:26:07.025203Z","caller":"traceutil/trace.go:171","msg":"trace[1215575906] transaction","detail":"{read_only:false; response_revision:14494; number_of_response:1; }","duration":"137.323309ms","start":"2026-06-29T21:26:06.887831Z","end":"2026-06-29T21:26:07.025155Z","steps":["trace[1215575906] 'process raft request' (duration: 137.156885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T21:26:33.530439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.53832ms","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-06-29T21:26:33.530552Z","caller":"traceutil/trace.go:171","msg":"trace[892632648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14839; }","duration":"223.725333ms","start":"2026-06-29T21:26:33.306809Z","end":"2026-06-29T21:26:33.530534Z","steps":["trace[892632648] 'range keys from in-memory index tree' (duration: 223.344245ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:26:33.568507Z","caller":"traceutil/trace.go:171","msg":"trace[1044233022] transaction","detail":"{read_only:false; response_revision:14840; number_of_response:1; }","duration":"252.479237ms","start":"2026-06-29T21:26:33.316Z","end":"2026-06-29T21:26:33.56848Z","steps":["trace[1044233022] 'process raft request' (duration: 252.326554ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:26:33.568509Z","caller":"traceutil/trace.go:171","msg":"trace[1310683794] linearizableReadLoop","detail":"{readStateIndex:15349; appliedIndex:15349; }","duration":"244.261519ms","start":"2026-06-29T21:26:33.32422Z","end":"2026-06-29T21:26:33.568482Z","steps":["trace[1310683794] 'read index received' (duration: 244.250099ms)","trace[1310683794] 'applied index is now lower than readState.Index' (duration: 8.93µs)"],"step_count":2} {"level":"warn","ts":"2026-06-29T21:26:33.569031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.744359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-29T21:26:33.56913Z","caller":"traceutil/trace.go:171","msg":"trace[651321196] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14840; }","duration":"244.886532ms","start":"2026-06-29T21:26:33.324196Z","end":"2026-06-29T21:26:33.569083Z","steps":["trace[651321196] 'agreement among raft nodes before linearized reading' (duration: 244.476473ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:26:33.653372Z","caller":"traceutil/trace.go:171","msg":"trace[732678934] transaction","detail":"{read_only:false; response_revision:14841; number_of_response:1; }","duration":"118.936551ms","start":"2026-06-29T21:26:33.53441Z","end":"2026-06-29T21:26:33.653346Z","steps":["trace[732678934] 'process raft request' (duration: 104.364594ms)","trace[732678934] 'compare' (duration: 14.374381ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T21:26:44.308779Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12759} {"level":"info","ts":"2026-06-29T21:26:44.384258Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12759,"took":"72.941832ms","hash":3932743927,"current-db-size-bytes":59416576,"current-db-size":"59 MB","current-db-size-in-use-bytes":28938240,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-29T21:26:44.384332Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3932743927,"revision":12759,"compact-revision":10534} {"level":"info","ts":"2026-06-29T21:27:50.076367Z","caller":"traceutil/trace.go:171","msg":"trace[1425775701] transaction","detail":"{read_only:false; response_revision:15602; number_of_response:1; }","duration":"114.747748ms","start":"2026-06-29T21:27:49.961602Z","end":"2026-06-29T21:27:50.076349Z","steps":["trace[1425775701] 'process raft request' (duration: 114.39424ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T21:27:50.331478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.912654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-29T21:27:50.331694Z","caller":"traceutil/trace.go:171","msg":"trace[1128456006] transaction","detail":"{read_only:false; response_revision:15604; number_of_response:1; }","duration":"251.318446ms","start":"2026-06-29T21:27:50.080357Z","end":"2026-06-29T21:27:50.331675Z","steps":["trace[1128456006] 'process raft request' (duration: 251.242194ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:27:50.331703Z","caller":"traceutil/trace.go:171","msg":"trace[156491131] transaction","detail":"{read_only:false; response_revision:15603; number_of_response:1; }","duration":"267.553618ms","start":"2026-06-29T21:27:50.064079Z","end":"2026-06-29T21:27:50.331633Z","steps":["trace[156491131] 'process raft request' (duration: 150.342327ms)","trace[156491131] 'compare' (duration: 116.771722ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T21:27:50.331733Z","caller":"traceutil/trace.go:171","msg":"trace[207741778] linearizableReadLoop","detail":"{readStateIndex:16132; appliedIndex:16131; }","duration":"255.560588ms","start":"2026-06-29T21:27:50.07613Z","end":"2026-06-29T21:27:50.331691Z","steps":["trace[207741778] 'read index received' (duration: 138.308277ms)","trace[207741778] 'applied index is now lower than readState.Index' (duration: 117.248191ms)"],"step_count":2} {"level":"warn","ts":"2026-06-29T21:27:50.332056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.149064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-29T21:27:50.33212Z","caller":"traceutil/trace.go:171","msg":"trace[1412106799] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15604; }","duration":"282.248186ms","start":"2026-06-29T21:27:50.049854Z","end":"2026-06-29T21:27:50.332102Z","steps":["trace[1412106799] 'agreement among raft nodes before linearized reading' (duration: 282.026381ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T21:27:50.332413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.78664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-29T21:27:50.332461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.638691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-29T21:27:50.33249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.235744ms","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-06-29T21:27:50.332517Z","caller":"traceutil/trace.go:171","msg":"trace[1079296917] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:15604; }","duration":"297.940418ms","start":"2026-06-29T21:27:50.03456Z","end":"2026-06-29T21:27:50.3325Z","steps":["trace[1079296917] 'agreement among raft nodes before linearized reading' (duration: 297.851046ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:27:50.332512Z","caller":"traceutil/trace.go:171","msg":"trace[2041291898] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15604; }","duration":"288.888182ms","start":"2026-06-29T21:27:50.043601Z","end":"2026-06-29T21:27:50.332489Z","steps":["trace[2041291898] 'agreement among raft nodes before linearized reading' (duration: 288.747219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T21:27:50.332508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.81865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-29T21:27:50.332505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.329538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-b2455\" ","response":"range_response_count:1 size:9791"} {"level":"warn","ts":"2026-06-29T21:27:50.332556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.358976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-29T21:27:50.33257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.921963ms","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-06-29T21:27:50.332587Z","caller":"traceutil/trace.go:171","msg":"trace[370569117] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15604; }","duration":"288.920923ms","start":"2026-06-29T21:27:50.043647Z","end":"2026-06-29T21:27:50.332568Z","steps":["trace[370569117] 'agreement among raft nodes before linearized reading' (duration: 288.79947ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:27:50.332605Z","caller":"traceutil/trace.go:171","msg":"trace[1016947773] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-b2455; range_end:; response_count:1; response_revision:15604; }","duration":"156.481201ms","start":"2026-06-29T21:27:50.176096Z","end":"2026-06-29T21:27:50.332577Z","steps":["trace[1016947773] 'agreement among raft nodes before linearized reading' (duration: 156.228016ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:27:50.332614Z","caller":"traceutil/trace.go:171","msg":"trace[469016132] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15604; }","duration":"316.438298ms","start":"2026-06-29T21:27:50.016158Z","end":"2026-06-29T21:27:50.332596Z","steps":["trace[469016132] 'agreement among raft nodes before linearized reading' (duration: 316.337485ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T21:27:50.332583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.422303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-29T21:27:50.33286Z","caller":"traceutil/trace.go:171","msg":"trace[146206635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15604; }","duration":"313.613397ms","start":"2026-06-29T21:27:50.01912Z","end":"2026-06-29T21:27:50.332733Z","steps":["trace[146206635] 'agreement among raft nodes before linearized reading' (duration: 313.31818ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T21:27:50.332433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.792851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-06-29T21:27:50.332931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T21:27:50.019108Z","time spent":"313.80317ms","remote":"127.0.0.1:54264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-29T21:27:50.332967Z","caller":"traceutil/trace.go:171","msg":"trace[535575525] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15604; }","duration":"314.351072ms","start":"2026-06-29T21:27:50.018601Z","end":"2026-06-29T21:27:50.332952Z","steps":["trace[535575525] 'agreement among raft nodes before linearized reading' (duration: 313.685707ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T21:27:50.333024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T21:27:50.018589Z","time spent":"314.420594ms","remote":"127.0.0.1:54226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5544,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-06-29T21:27:50.33262Z","caller":"traceutil/trace.go:171","msg":"trace[350774477] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15604; }","duration":"316.328926ms","start":"2026-06-29T21:27:50.016194Z","end":"2026-06-29T21:27:50.332523Z","steps":["trace[350774477] 'agreement among raft nodes before linearized reading' (duration: 316.152232ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T21:27:50.333153Z","caller":"traceutil/trace.go:171","msg":"trace[1141195395] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15604; }","duration":"289.067986ms","start":"2026-06-29T21:27:50.043632Z","end":"2026-06-29T21:27:50.3327Z","steps":["trace[1141195395] 'agreement among raft nodes before linearized reading' (duration: 288.878482ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T21:27:50.332664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T21:27:50.016149Z","time spent":"316.5011ms","remote":"127.0.0.1:54226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-06-29T21:27:50.333255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T21:27:50.016171Z","time spent":"316.896008ms","remote":"127.0.0.1:54166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-06-29T21:31:44.315529Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14927} {"level":"info","ts":"2026-06-29T21:31:44.40906Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14927,"took":"91.103078ms","hash":2022233252,"current-db-size-bytes":59416576,"current-db-size":"59 MB","current-db-size-in-use-bytes":30429184,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-29T21:31:44.40918Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2022233252,"revision":14927,"compact-revision":12759} {"level":"info","ts":"2026-06-29T21:36:44.322224Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17378} {"level":"info","ts":"2026-06-29T21:36:44.422903Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17378,"took":"97.38608ms","hash":2816848691,"current-db-size-bytes":59416576,"current-db-size":"59 MB","current-db-size-in-use-bytes":29532160,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-29T21:36:44.423008Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2816848691,"revision":17378,"compact-revision":14927} {"level":"info","ts":"2026-06-29T21:37:21.013089Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"511d8c552db01fa8","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-29T21:37:21.018442Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-29T21:37:21.01853Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-29T21:41:44.329245Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19145} {"level":"info","ts":"2026-06-29T21:41:44.413824Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19145,"took":"81.065618ms","hash":693541453,"current-db-size-bytes":59416576,"current-db-size":"59 MB","current-db-size-in-use-bytes":23842816,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-29T21:41:44.413902Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":693541453,"revision":19145,"compact-revision":17378}