{"level":"warn","ts":"2026-04-06T21:04:20.834921Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-06T21:04:20.835012Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240: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.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-06T21:04:20.835073Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-06T21:04:20.835086Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-04-06T21:04:20.835104Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-06T21:04:20.835662Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-04-06T21:04:20.835732Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-06T21:04:20.839418Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.457325ms"} {"level":"info","ts":"2026-04-06T21:04:20.845745Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-04-06T21:04:20.845842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-04-06T21:04:20.845876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-04-06T21:04:20.845893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-06T21:04:20.845909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-04-06T21:04:20.845969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-04-06T21:04:20.856821Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-06T21:04:20.858493Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-06T21:04:20.859739Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-06T21:04:20.862118Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-06T21:04:20.862362Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-06T21:04:20.862457Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-06T21:04:20.86258Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-06T21:04:20.862606Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-06T21:04:20.864264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-04-06T21:04:20.86455Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-04-06T21:04:20.866748Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-06T21:04:20.866862Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-04-06T21:04:20.866923Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-04-06T21:04:20.866982Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-06T21:04:20.867211Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-06T21:04:21.047245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-04-06T21:04:21.047342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-06T21:04:21.047512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-04-06T21:04:21.047545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-04-06T21:04:21.047563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-04-06T21:04:21.047591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-04-06T21:04:21.04761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-04-06T21:04:21.048852Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-04-06T21:04:21.048857Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-06T21:04:21.048858Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T21:04:21.04905Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-06T21:04:21.04922Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-06T21:04:21.049275Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-06T21:04:21.049907Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T21:04:21.050043Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T21:04:21.050089Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T21:04:21.051352Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-04-06T21:04:21.052882Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-06T21:04:39.6423Z","caller":"traceutil/trace.go:171","msg":"trace[1526195674] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"175.692766ms","start":"2026-04-06T21:04:39.466583Z","end":"2026-04-06T21:04:39.642276Z","steps":["trace[1526195674] 'read index received' (duration: 119.655632ms)","trace[1526195674] 'applied index is now lower than readState.Index' (duration: 56.036194ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:04:39.642382Z","caller":"traceutil/trace.go:171","msg":"trace[1775767040] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"291.041145ms","start":"2026-04-06T21:04:39.351298Z","end":"2026-04-06T21:04:39.642339Z","steps":["trace[1775767040] 'process raft request' (duration: 235.022351ms)","trace[1775767040] 'compare' (duration: 55.843298ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:04:39.642464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.098491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:04:39.642533Z","caller":"traceutil/trace.go:171","msg":"trace[1224740603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"168.209693ms","start":"2026-04-06T21:04:39.474313Z","end":"2026-04-06T21:04:39.642523Z","steps":["trace[1224740603] 'agreement among raft nodes before linearized reading' (duration: 168.109431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:04:39.642519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.900603ms","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-04-06T21:04:39.642608Z","caller":"traceutil/trace.go:171","msg":"trace[360631227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:407; }","duration":"176.064427ms","start":"2026-04-06T21:04:39.466525Z","end":"2026-04-06T21:04:39.642589Z","steps":["trace[360631227] 'agreement among raft nodes before linearized reading' (duration: 175.850721ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:04:39.936003Z","caller":"traceutil/trace.go:171","msg":"trace[1934865956] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"242.505364ms","start":"2026-04-06T21:04:39.693467Z","end":"2026-04-06T21:04:39.935973Z","steps":["trace[1934865956] 'process raft request' (duration: 153.557005ms)","trace[1934865956] 'compare' (duration: 88.795556ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:04:57.191424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.727599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T21:04:57.191657Z","caller":"traceutil/trace.go:171","msg":"trace[202856576] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"291.085096ms","start":"2026-04-06T21:04:56.900553Z","end":"2026-04-06T21:04:57.191638Z","steps":["trace[202856576] 'process raft request' (duration: 291.038185ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:04:57.191687Z","caller":"traceutil/trace.go:171","msg":"trace[1933079492] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"506.888928ms","start":"2026-04-06T21:04:56.684758Z","end":"2026-04-06T21:04:57.191647Z","steps":["trace[1933079492] 'process raft request' (duration: 170.127094ms)","trace[1933079492] 'compare' (duration: 335.610206ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:04:57.191714Z","caller":"traceutil/trace.go:171","msg":"trace[1982916310] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:600; }","duration":"395.226911ms","start":"2026-04-06T21:04:56.796463Z","end":"2026-04-06T21:04:57.19169Z","steps":["trace[1982916310] 'read index received' (duration: 58.457137ms)","trace[1982916310] 'applied index is now lower than readState.Index' (duration: 336.768204ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:04:57.191721Z","caller":"traceutil/trace.go:171","msg":"trace[814710290] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"465.978996ms","start":"2026-04-06T21:04:56.725722Z","end":"2026-04-06T21:04:57.191701Z","steps":["trace[814710290] 'process raft request' (duration: 465.812262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:04:57.191827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.366294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-06T21:04:57.191836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:04:56.684731Z","time spent":"507.026291ms","remote":"127.0.0.1:54962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T21:04:57.191815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:04:56.725701Z","time spent":"466.071509ms","remote":"127.0.0.1:55286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T21:04:57.191884Z","caller":"traceutil/trace.go:171","msg":"trace[918366103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"395.467007ms","start":"2026-04-06T21:04:56.796404Z","end":"2026-04-06T21:04:57.191871Z","steps":["trace[918366103] 'agreement among raft nodes before linearized reading' (duration: 395.358514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:04:57.191924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:04:56.796381Z","time spent":"395.532268ms","remote":"127.0.0.1:54778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-06T21:04:57.227652Z","caller":"traceutil/trace.go:171","msg":"trace[1919594184] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"229.615196ms","start":"2026-04-06T21:04:56.997989Z","end":"2026-04-06T21:04:57.227604Z","steps":["trace[1919594184] 'process raft request' (duration: 229.470623ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:05:39.076823Z","caller":"traceutil/trace.go:171","msg":"trace[1141882301] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"204.353494ms","start":"2026-04-06T21:05:38.872445Z","end":"2026-04-06T21:05:39.076798Z","steps":["trace[1141882301] 'process raft request' (duration: 204.20488ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:05:39.11049Z","caller":"traceutil/trace.go:171","msg":"trace[537051732] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"186.042181ms","start":"2026-04-06T21:05:38.924425Z","end":"2026-04-06T21:05:39.110467Z","steps":["trace[537051732] 'process raft request' (duration: 185.889777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:24.708163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.542916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T21:06:24.708313Z","caller":"traceutil/trace.go:171","msg":"trace[468116018] linearizableReadLoop","detail":"{readStateIndex:1853; appliedIndex:1852; }","duration":"150.661632ms","start":"2026-04-06T21:06:24.557635Z","end":"2026-04-06T21:06:24.708297Z","steps":["trace[468116018] 'read index received' (duration: 26.31µs)","trace[468116018] 'applied index is now lower than readState.Index' (duration: 150.634172ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:06:24.708489Z","caller":"traceutil/trace.go:171","msg":"trace[1365353027] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"280.916203ms","start":"2026-04-06T21:06:24.427558Z","end":"2026-04-06T21:06:24.708475Z","steps":["trace[1365353027] 'process raft request' (duration: 38.970207ms)","trace[1365353027] 'compare' (duration: 241.440504ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:06:24.708577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.923738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-06T21:06:24.70865Z","caller":"traceutil/trace.go:171","msg":"trace[1756575786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1782; }","duration":"151.037741ms","start":"2026-04-06T21:06:24.557596Z","end":"2026-04-06T21:06:24.708634Z","steps":["trace[1756575786] 'agreement among raft nodes before linearized reading' (duration: 150.869427ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:06:37.949924Z","caller":"traceutil/trace.go:171","msg":"trace[1552587511] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"164.295628ms","start":"2026-04-06T21:06:37.785557Z","end":"2026-04-06T21:06:37.949853Z","steps":["trace[1552587511] 'process raft request' (duration: 163.136221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:37.950045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.690983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:06:37.950129Z","caller":"traceutil/trace.go:171","msg":"trace[318234792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1899; }","duration":"153.846078ms","start":"2026-04-06T21:06:37.796265Z","end":"2026-04-06T21:06:37.950111Z","steps":["trace[318234792] 'agreement among raft nodes before linearized reading' (duration: 153.656943ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:06:37.949871Z","caller":"traceutil/trace.go:171","msg":"trace[806307595] linearizableReadLoop","detail":"{readStateIndex:1975; appliedIndex:1974; }","duration":"153.483558ms","start":"2026-04-06T21:06:37.796331Z","end":"2026-04-06T21:06:37.949815Z","steps":["trace[806307595] 'read index received' (duration: 152.27928ms)","trace[806307595] 'applied index is now lower than readState.Index' (duration: 1.202658ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:07:11.818311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.913673ms","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-04-06T21:07:11.81848Z","caller":"traceutil/trace.go:171","msg":"trace[1888931793] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2094; }","duration":"238.159518ms","start":"2026-04-06T21:07:11.580301Z","end":"2026-04-06T21:07:11.818461Z","steps":["trace[1888931793] 'range keys from in-memory index tree' (duration: 237.703988ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:07:11.818561Z","caller":"traceutil/trace.go:171","msg":"trace[10858771] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"105.452726ms","start":"2026-04-06T21:07:11.713049Z","end":"2026-04-06T21:07:11.818502Z","steps":["trace[10858771] 'process raft request' (duration: 93.069803ms)","trace[10858771] 'compare' (duration: 11.870511ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:08:38.246159Z","caller":"traceutil/trace.go:171","msg":"trace[1439286803] transaction","detail":"{read_only:false; response_revision:2756; number_of_response:1; }","duration":"120.663232ms","start":"2026-04-06T21:08:38.125479Z","end":"2026-04-06T21:08:38.246142Z","steps":["trace[1439286803] 'process raft request' (duration: 120.57218ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:08:38.456763Z","caller":"traceutil/trace.go:171","msg":"trace[119202476] transaction","detail":"{read_only:false; response_revision:2758; number_of_response:1; }","duration":"106.43233ms","start":"2026-04-06T21:08:38.350306Z","end":"2026-04-06T21:08:38.456738Z","steps":["trace[119202476] 'process raft request' (duration: 106.274277ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:08:38.751232Z","caller":"traceutil/trace.go:171","msg":"trace[175710986] transaction","detail":"{read_only:false; response_revision:2760; number_of_response:1; }","duration":"162.33973ms","start":"2026-04-06T21:08:38.588864Z","end":"2026-04-06T21:08:38.751203Z","steps":["trace[175710986] 'process raft request' (duration: 162.188947ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:12:24.206009Z","caller":"traceutil/trace.go:171","msg":"trace[484360795] transaction","detail":"{read_only:false; response_revision:4757; number_of_response:1; }","duration":"135.912613ms","start":"2026-04-06T21:12:24.070059Z","end":"2026-04-06T21:12:24.205971Z","steps":["trace[484360795] 'process raft request' (duration: 37.067312ms)","trace[484360795] 'compare' (duration: 98.556003ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:12:27.879221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.35178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-06T21:12:27.879279Z","caller":"traceutil/trace.go:171","msg":"trace[1174264276] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4885; }","duration":"136.457873ms","start":"2026-04-06T21:12:27.742809Z","end":"2026-04-06T21:12:27.879267Z","steps":["trace[1174264276] 'range keys from in-memory index tree' (duration: 136.122215ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:12:28.009125Z","caller":"traceutil/trace.go:171","msg":"trace[126623282] linearizableReadLoop","detail":"{readStateIndex:5076; appliedIndex:5075; }","duration":"117.57296ms","start":"2026-04-06T21:12:27.891531Z","end":"2026-04-06T21:12:28.009104Z","steps":["trace[126623282] 'read index received' (duration: 117.366275ms)","trace[126623282] 'applied index is now lower than readState.Index' (duration: 205.805µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:12:28.009469Z","caller":"traceutil/trace.go:171","msg":"trace[254684011] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"127.084847ms","start":"2026-04-06T21:12:27.882364Z","end":"2026-04-06T21:12:28.009448Z","steps":["trace[254684011] 'process raft request' (duration: 126.590395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:12:28.00997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.42614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-06T21:12:28.010018Z","caller":"traceutil/trace.go:171","msg":"trace[931912601] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4886; }","duration":"118.500511ms","start":"2026-04-06T21:12:27.891506Z","end":"2026-04-06T21:12:28.010006Z","steps":["trace[931912601] 'agreement among raft nodes before linearized reading' (duration: 118.300536ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:12:30.198439Z","caller":"traceutil/trace.go:171","msg":"trace[445336225] linearizableReadLoop","detail":"{readStateIndex:5119; appliedIndex:5118; }","duration":"122.525298ms","start":"2026-04-06T21:12:30.075892Z","end":"2026-04-06T21:12:30.198418Z","steps":["trace[445336225] 'read index received' (duration: 122.356514ms)","trace[445336225] 'applied index is now lower than readState.Index' (duration: 167.914µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:12:30.198574Z","caller":"traceutil/trace.go:171","msg":"trace[1809392819] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"181.745318ms","start":"2026-04-06T21:12:30.016792Z","end":"2026-04-06T21:12:30.198537Z","steps":["trace[1809392819] 'process raft request' (duration: 181.509793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:12:30.198695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.795214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-06T21:12:30.198739Z","caller":"traceutil/trace.go:171","msg":"trace[2107380530] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4927; }","duration":"122.890337ms","start":"2026-04-06T21:12:30.075838Z","end":"2026-04-06T21:12:30.198728Z","steps":["trace[2107380530] 'agreement among raft nodes before linearized reading' (duration: 122.700442ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:12:47.325161Z","caller":"traceutil/trace.go:171","msg":"trace[363669470] linearizableReadLoop","detail":"{readStateIndex:5488; appliedIndex:5487; }","duration":"158.193373ms","start":"2026-04-06T21:12:47.166942Z","end":"2026-04-06T21:12:47.325135Z","steps":["trace[363669470] 'read index received' (duration: 130.510919ms)","trace[363669470] 'applied index is now lower than readState.Index' (duration: 27.681234ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:12:47.325254Z","caller":"traceutil/trace.go:171","msg":"trace[90814925] transaction","detail":"{read_only:false; response_revision:5286; number_of_response:1; }","duration":"192.193328ms","start":"2026-04-06T21:12:47.133033Z","end":"2026-04-06T21:12:47.325227Z","steps":["trace[90814925] 'process raft request' (duration: 164.441613ms)","trace[90814925] 'compare' (duration: 27.359706ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:12:47.325381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.423039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-s5gd9\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-06T21:12:47.32543Z","caller":"traceutil/trace.go:171","msg":"trace[1738201835] range","detail":"{range_begin:/registry/pods/openstack/keepalived-s5gd9; range_end:; response_count:1; response_revision:5286; }","duration":"158.532051ms","start":"2026-04-06T21:12:47.166885Z","end":"2026-04-06T21:12:47.325417Z","steps":["trace[1738201835] 'agreement among raft nodes before linearized reading' (duration: 158.361727ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:12:47.413519Z","caller":"traceutil/trace.go:171","msg":"trace[1230658145] transaction","detail":"{read_only:false; response_revision:5287; number_of_response:1; }","duration":"229.971635ms","start":"2026-04-06T21:12:47.183493Z","end":"2026-04-06T21:12:47.413465Z","steps":["trace[1230658145] 'process raft request' (duration: 229.539545ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:14:21.558402Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2995} {"level":"info","ts":"2026-04-06T21:14:21.62555Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2995,"took":"65.752123ms","hash":3779073123,"current-db-size-bytes":57847808,"current-db-size":"58 MB","current-db-size-in-use-bytes":34140160,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-06T21:14:21.625624Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3779073123,"revision":2995,"compact-revision":-1} {"level":"info","ts":"2026-04-06T21:18:48.178094Z","caller":"traceutil/trace.go:171","msg":"trace[1083363943] transaction","detail":"{read_only:false; response_revision:8094; number_of_response:1; }","duration":"188.731385ms","start":"2026-04-06T21:18:47.989338Z","end":"2026-04-06T21:18:48.17807Z","steps":["trace[1083363943] 'process raft request' (duration: 188.576621ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:18:48.306221Z","caller":"traceutil/trace.go:171","msg":"trace[201629229] linearizableReadLoop","detail":"{readStateIndex:8383; appliedIndex:8380; }","duration":"275.217819ms","start":"2026-04-06T21:18:48.030975Z","end":"2026-04-06T21:18:48.306193Z","steps":["trace[201629229] 'read index received' (duration: 147.004819ms)","trace[201629229] 'applied index is now lower than readState.Index' (duration: 128.21172ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:18:48.306405Z","caller":"traceutil/trace.go:171","msg":"trace[464236883] transaction","detail":"{read_only:false; response_revision:8096; number_of_response:1; }","duration":"314.98325ms","start":"2026-04-06T21:18:47.991407Z","end":"2026-04-06T21:18:48.30639Z","steps":["trace[464236883] 'process raft request' (duration: 314.733684ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:18:48.306441Z","caller":"traceutil/trace.go:171","msg":"trace[212683507] transaction","detail":"{read_only:false; response_revision:8095; number_of_response:1; }","duration":"315.472011ms","start":"2026-04-06T21:18:47.990929Z","end":"2026-04-06T21:18:48.306401Z","steps":["trace[212683507] 'process raft request' (duration: 315.041751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:18:48.306525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:18:47.991401Z","time spent":"315.038061ms","remote":"127.0.0.1:55026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T21:18:48.30662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:18:47.99092Z","time spent":"315.611124ms","remote":"127.0.0.1:55026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T21:18:48.30696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.981897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-04-06T21:18:48.307005Z","caller":"traceutil/trace.go:171","msg":"trace[273854268] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8096; }","duration":"276.050549ms","start":"2026-04-06T21:18:48.030943Z","end":"2026-04-06T21:18:48.306993Z","steps":["trace[273854268] 'agreement among raft nodes before linearized reading' (duration: 275.904556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:18:48.307176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.36898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-06T21:18:48.307265Z","caller":"traceutil/trace.go:171","msg":"trace[313517569] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:8096; }","duration":"259.490983ms","start":"2026-04-06T21:18:48.047757Z","end":"2026-04-06T21:18:48.307248Z","steps":["trace[313517569] 'agreement among raft nodes before linearized reading' (duration: 259.314309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:18:48.307261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.215778ms","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-04-06T21:18:48.307357Z","caller":"traceutil/trace.go:171","msg":"trace[546815948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8096; }","duration":"241.355021ms","start":"2026-04-06T21:18:48.065984Z","end":"2026-04-06T21:18:48.307339Z","steps":["trace[546815948] 'agreement among raft nodes before linearized reading' (duration: 241.092025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:18:59.683995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.446529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-s5gd9\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-06T21:18:59.684062Z","caller":"traceutil/trace.go:171","msg":"trace[930621734] range","detail":"{range_begin:/registry/pods/openstack/keepalived-s5gd9; range_end:; response_count:1; response_revision:8152; }","duration":"202.533071ms","start":"2026-04-06T21:18:59.481515Z","end":"2026-04-06T21:18:59.684048Z","steps":["trace[930621734] 'range keys from in-memory index tree' (duration: 202.275355ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:18:59.684327Z","caller":"traceutil/trace.go:171","msg":"trace[49013396] transaction","detail":"{read_only:false; response_revision:8153; number_of_response:1; }","duration":"129.193741ms","start":"2026-04-06T21:18:59.555095Z","end":"2026-04-06T21:18:59.684289Z","steps":["trace[49013396] 'process raft request' (duration: 81.365171ms)","trace[49013396] 'compare' (duration: 47.510863ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:18:59.684334Z","caller":"traceutil/trace.go:171","msg":"trace[521472209] linearizableReadLoop","detail":"{readStateIndex:8442; appliedIndex:8441; }","duration":"118.498916ms","start":"2026-04-06T21:18:59.565785Z","end":"2026-04-06T21:18:59.684284Z","steps":["trace[521472209] 'read index received' (duration: 70.590954ms)","trace[521472209] 'applied index is now lower than readState.Index' (duration: 47.906352ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:18:59.684556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.757903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-7779784579-zzjrd.18a3e13a84a3ea5e\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-04-06T21:18:59.684626Z","caller":"traceutil/trace.go:171","msg":"trace[751412650] range","detail":"{range_begin:/registry/events/openstack/barbican-api-7779784579-zzjrd.18a3e13a84a3ea5e; range_end:; response_count:1; response_revision:8153; }","duration":"118.857466ms","start":"2026-04-06T21:18:59.565747Z","end":"2026-04-06T21:18:59.684604Z","steps":["trace[751412650] 'agreement among raft nodes before linearized reading' (duration: 118.679881ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:21.566738Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6109} {"level":"info","ts":"2026-04-06T21:19:21.661153Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6109,"took":"92.693371ms","hash":3734917414,"current-db-size-bytes":57847808,"current-db-size":"58 MB","current-db-size-in-use-bytes":22978560,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-06T21:19:21.661225Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3734917414,"revision":6109,"compact-revision":2995} {"level":"info","ts":"2026-04-06T21:19:28.236392Z","caller":"traceutil/trace.go:171","msg":"trace[236147669] linearizableReadLoop","detail":"{readStateIndex:8661; appliedIndex:8660; }","duration":"389.948633ms","start":"2026-04-06T21:19:27.846416Z","end":"2026-04-06T21:19:28.236364Z","steps":["trace[236147669] 'read index received' (duration: 389.764759ms)","trace[236147669] 'applied index is now lower than readState.Index' (duration: 182.834µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:19:28.236555Z","caller":"traceutil/trace.go:171","msg":"trace[750388305] transaction","detail":"{read_only:false; response_revision:8362; number_of_response:1; }","duration":"404.175592ms","start":"2026-04-06T21:19:27.832362Z","end":"2026-04-06T21:19:28.236537Z","steps":["trace[750388305] 'process raft request' (duration: 403.832624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:19:28.236684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:19:27.832334Z","time spent":"404.259865ms","remote":"127.0.0.1:55026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T21:19:28.236859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.329918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-06T21:19:28.236958Z","caller":"traceutil/trace.go:171","msg":"trace[821438700] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8362; }","duration":"345.464472ms","start":"2026-04-06T21:19:27.891471Z","end":"2026-04-06T21:19:28.236936Z","steps":["trace[821438700] 'agreement among raft nodes before linearized reading' (duration: 345.208926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:19:28.237017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:19:27.891447Z","time spent":"345.556834ms","remote":"127.0.0.1:55026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-06T21:19:28.237194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.768182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-s5gd9\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-06T21:19:28.237256Z","caller":"traceutil/trace.go:171","msg":"trace[1975272781] range","detail":"{range_begin:/registry/pods/openstack/keepalived-s5gd9; range_end:; response_count:1; response_revision:8362; }","duration":"390.876026ms","start":"2026-04-06T21:19:27.846365Z","end":"2026-04-06T21:19:28.237241Z","steps":["trace[1975272781] 'agreement among raft nodes before linearized reading' (duration: 390.729852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:19:28.237303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:19:27.846346Z","time spent":"390.944227ms","remote":"127.0.0.1:54962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-s5gd9\" "} {"level":"info","ts":"2026-04-06T21:19:30.382991Z","caller":"traceutil/trace.go:171","msg":"trace[1582488109] transaction","detail":"{read_only:false; response_revision:8372; number_of_response:1; }","duration":"232.811274ms","start":"2026-04-06T21:19:30.150152Z","end":"2026-04-06T21:19:30.382963Z","steps":["trace[1582488109] 'process raft request' (duration: 232.6455ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:30.383639Z","caller":"traceutil/trace.go:171","msg":"trace[1059195489] linearizableReadLoop","detail":"{readStateIndex:8672; appliedIndex:8672; }","duration":"141.930927ms","start":"2026-04-06T21:19:30.241679Z","end":"2026-04-06T21:19:30.38361Z","steps":["trace[1059195489] 'read index received' (duration: 141.922417ms)","trace[1059195489] 'applied index is now lower than readState.Index' (duration: 6.1µs)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:19:30.383804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.119251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-06T21:19:30.383864Z","caller":"traceutil/trace.go:171","msg":"trace[536296144] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8372; }","duration":"142.234674ms","start":"2026-04-06T21:19:30.24162Z","end":"2026-04-06T21:19:30.383855Z","steps":["trace[536296144] 'agreement among raft nodes before linearized reading' (duration: 142.057299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:19:30.388415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.83795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-06T21:19:30.388492Z","caller":"traceutil/trace.go:171","msg":"trace[1166195579] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8373; }","duration":"140.977383ms","start":"2026-04-06T21:19:30.247498Z","end":"2026-04-06T21:19:30.388475Z","steps":["trace[1166195579] 'agreement among raft nodes before linearized reading' (duration: 140.758768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:19:30.388774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.613058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-s5gd9\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-06T21:19:30.388801Z","caller":"traceutil/trace.go:171","msg":"trace[1133392990] range","detail":"{range_begin:/registry/pods/openstack/keepalived-s5gd9; range_end:; response_count:1; response_revision:8373; }","duration":"138.676849ms","start":"2026-04-06T21:19:30.250117Z","end":"2026-04-06T21:19:30.388794Z","steps":["trace[1133392990] 'agreement among raft nodes before linearized reading' (duration: 138.605577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:19:40.288783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.625876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-wt8jk\" ","response":"range_response_count:1 size:2804"} {"level":"info","ts":"2026-04-06T21:19:40.288858Z","caller":"traceutil/trace.go:171","msg":"trace[1758772003] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-wt8jk; range_end:; response_count:1; response_revision:8465; }","duration":"104.747829ms","start":"2026-04-06T21:19:40.184092Z","end":"2026-04-06T21:19:40.28884Z","steps":["trace[1758772003] 'range keys from in-memory index tree' (duration: 104.489723ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:40.29793Z","caller":"traceutil/trace.go:171","msg":"trace[1431999322] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"112.293749ms","start":"2026-04-06T21:19:40.185611Z","end":"2026-04-06T21:19:40.297905Z","steps":["trace[1431999322] 'process raft request' (duration: 112.137315ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:41.041772Z","caller":"traceutil/trace.go:171","msg":"trace[765683780] transaction","detail":"{read_only:false; response_revision:8477; number_of_response:1; }","duration":"253.817966ms","start":"2026-04-06T21:19:40.787901Z","end":"2026-04-06T21:19:41.041719Z","steps":["trace[765683780] 'process raft request' (duration: 163.091331ms)","trace[765683780] 'compare' (duration: 89.70652ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:19:41.041943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.651231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:19:41.041782Z","caller":"traceutil/trace.go:171","msg":"trace[1485447641] linearizableReadLoop","detail":"{readStateIndex:8782; appliedIndex:8781; }","duration":"245.480517ms","start":"2026-04-06T21:19:40.796268Z","end":"2026-04-06T21:19:41.041748Z","steps":["trace[1485447641] 'read index received' (duration: 154.715251ms)","trace[1485447641] 'applied index is now lower than readState.Index' (duration: 90.763716ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:19:41.042021Z","caller":"traceutil/trace.go:171","msg":"trace[980180557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8478; }","duration":"245.772384ms","start":"2026-04-06T21:19:40.796229Z","end":"2026-04-06T21:19:41.042002Z","steps":["trace[980180557] 'agreement among raft nodes before linearized reading' (duration: 245.630741ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:41.724541Z","caller":"traceutil/trace.go:171","msg":"trace[2108889096] transaction","detail":"{read_only:false; response_revision:8482; number_of_response:1; }","duration":"189.104641ms","start":"2026-04-06T21:19:41.535412Z","end":"2026-04-06T21:19:41.724517Z","steps":["trace[2108889096] 'process raft request' (duration: 188.782583ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:42.034985Z","caller":"traceutil/trace.go:171","msg":"trace[211143420] transaction","detail":"{read_only:false; response_revision:8483; number_of_response:1; }","duration":"169.930124ms","start":"2026-04-06T21:19:41.86502Z","end":"2026-04-06T21:19:42.03495Z","steps":["trace[211143420] 'process raft request' (duration: 122.057812ms)","trace[211143420] 'compare' (duration: 47.735709ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:20:30.279341Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002436.wal"} {"level":"info","ts":"2026-04-06T21:20:57.063816Z","caller":"traceutil/trace.go:171","msg":"trace[987852086] linearizableReadLoop","detail":"{readStateIndex:9546; appliedIndex:9545; }","duration":"244.806336ms","start":"2026-04-06T21:20:56.818978Z","end":"2026-04-06T21:20:57.063784Z","steps":["trace[987852086] 'read index received' (duration: 244.581111ms)","trace[987852086] 'applied index is now lower than readState.Index' (duration: 224.165µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:20:57.063919Z","caller":"traceutil/trace.go:171","msg":"trace[22219128] transaction","detail":"{read_only:false; response_revision:9220; number_of_response:1; }","duration":"261.699098ms","start":"2026-04-06T21:20:56.802185Z","end":"2026-04-06T21:20:57.063884Z","steps":["trace[22219128] 'process raft request' (duration: 261.36049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:20:57.064074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.067192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-06T21:20:57.064118Z","caller":"traceutil/trace.go:171","msg":"trace[107287594] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9220; }","duration":"245.152514ms","start":"2026-04-06T21:20:56.818955Z","end":"2026-04-06T21:20:57.064108Z","steps":["trace[107287594] 'agreement among raft nodes before linearized reading' (duration: 244.9863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:20:57.064297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.993142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-s5gd9\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-06T21:20:57.064419Z","caller":"traceutil/trace.go:171","msg":"trace[1760887682] range","detail":"{range_begin:/registry/pods/openstack/keepalived-s5gd9; range_end:; response_count:1; response_revision:9220; }","duration":"149.176986ms","start":"2026-04-06T21:20:56.915222Z","end":"2026-04-06T21:20:57.064399Z","steps":["trace[1760887682] 'agreement among raft nodes before linearized reading' (duration: 148.835048ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:21:57.683322Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e46287fb5aa4785","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-06T21:21:57.688737Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-06T21:21:57.688852Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-06T21:24:21.573927Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8283} {"level":"info","ts":"2026-04-06T21:24:21.64863Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8283,"took":"72.683268ms","hash":1075810601,"current-db-size-bytes":57847808,"current-db-size":"58 MB","current-db-size-in-use-bytes":24932352,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-06T21:24:21.648711Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1075810601,"revision":8283,"compact-revision":6109} {"level":"info","ts":"2026-04-06T21:29:21.580366Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10677} {"level":"info","ts":"2026-04-06T21:29:21.657785Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10677,"took":"75.666919ms","hash":897210442,"current-db-size-bytes":57847808,"current-db-size":"58 MB","current-db-size-in-use-bytes":20287488,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-06T21:29:21.657853Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":897210442,"revision":10677,"compact-revision":8283}