{"level":"warn","ts":"2026-05-28T21:43:07.732404Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T21:43:07.732484Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-05-28T21:43:07.732548Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T21:43:07.732567Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-05-28T21:43:07.732584Z","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-05-28T21:43:07.733188Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-05-28T21:43:07.733274Z","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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-05-28T21:43:07.739343Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.833624ms"} {"level":"info","ts":"2026-05-28T21:43:07.749075Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-05-28T21:43:07.749164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-05-28T21:43:07.749188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-05-28T21:43:07.749206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-28T21:43:07.749216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-05-28T21:43:07.74926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-05-28T21:43:07.762365Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-28T21:43:07.764359Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-28T21:43:07.766121Z","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-05-28T21:43:07.770311Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-28T21:43:07.770435Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-28T21:43:07.770595Z","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-05-28T21:43:07.770628Z","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-05-28T21:43:07.770637Z","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-05-28T21:43:07.771959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-05-28T21:43:07.772285Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-05-28T21:43:07.775669Z","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-05-28T21:43:07.775778Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-05-28T21:43:07.77582Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-05-28T21:43:07.776001Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-28T21:43:07.776031Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-28T21:43:08.350269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-05-28T21:43:08.350356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-28T21:43:08.350572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-05-28T21:43:08.350591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-05-28T21:43:08.350608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-05-28T21:43:08.350629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-05-28T21:43:08.350646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-05-28T21:43:08.351857Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:43:08.352711Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T21:43:08.352731Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T21:43:08.352682Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-05-28T21:43:08.353161Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-28T21:43:08.353218Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-28T21:43:08.353317Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:43:08.353447Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:43:08.35348Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:43:08.356877Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-05-28T21:43:08.358429Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-28T21:43:22.918499Z","caller":"traceutil/trace.go:171","msg":"trace[1709787637] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"120.301569ms","start":"2026-05-28T21:43:22.79817Z","end":"2026-05-28T21:43:22.918472Z","steps":["trace[1709787637] 'process raft request' (duration: 120.147645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:43:45.789107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.717104ms","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-05-28T21:43:45.789267Z","caller":"traceutil/trace.go:171","msg":"trace[1689092080] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:563; }","duration":"113.92769ms","start":"2026-05-28T21:43:45.675314Z","end":"2026-05-28T21:43:45.789242Z","steps":["trace[1689092080] 'range keys from in-memory index tree' (duration: 113.476697ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:43:45.900951Z","caller":"traceutil/trace.go:171","msg":"trace[2038047409] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"108.335592ms","start":"2026-05-28T21:43:45.792594Z","end":"2026-05-28T21:43:45.90093Z","steps":["trace[2038047409] 'process raft request' (duration: 108.171338ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:43:45.902051Z","caller":"traceutil/trace.go:171","msg":"trace[748081977] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"108.809226ms","start":"2026-05-28T21:43:45.793209Z","end":"2026-05-28T21:43:45.902018Z","steps":["trace[748081977] 'process raft request' (duration: 108.676922ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:43:49.693737Z","caller":"traceutil/trace.go:171","msg":"trace[1655228915] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"148.678375ms","start":"2026-05-28T21:43:49.545033Z","end":"2026-05-28T21:43:49.693712Z","steps":["trace[1655228915] 'read index received' (duration: 147.563364ms)","trace[1655228915] 'applied index is now lower than readState.Index' (duration: 1.114101ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:43:49.693793Z","caller":"traceutil/trace.go:171","msg":"trace[621248118] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"365.798973ms","start":"2026-05-28T21:43:49.327963Z","end":"2026-05-28T21:43:49.693762Z","steps":["trace[621248118] 'process raft request' (duration: 365.158845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:43:49.69407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.025195ms","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-05-28T21:43:49.694129Z","caller":"traceutil/trace.go:171","msg":"trace[1032194894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:588; }","duration":"149.143459ms","start":"2026-05-28T21:43:49.544974Z","end":"2026-05-28T21:43:49.694118Z","steps":["trace[1032194894] 'agreement among raft nodes before linearized reading' (duration: 148.894992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:43:49.694546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:43:49.327936Z","time spent":"365.989338ms","remote":"127.0.0.1:46694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:43:50.213151Z","caller":"traceutil/trace.go:171","msg":"trace[1683473583] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"296.735613ms","start":"2026-05-28T21:43:49.916389Z","end":"2026-05-28T21:43:50.213125Z","steps":["trace[1683473583] 'read index received' (duration: 296.592219ms)","trace[1683473583] 'applied index is now lower than readState.Index' (duration: 142.184µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:43:50.213174Z","caller":"traceutil/trace.go:171","msg":"trace[288376546] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"303.094502ms","start":"2026-05-28T21:43:49.910057Z","end":"2026-05-28T21:43:50.213151Z","steps":["trace[288376546] 'process raft request' (duration: 302.904986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:43:50.213397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:43:49.910038Z","time spent":"303.249937ms","remote":"127.0.0.1:46792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:43:50.213409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.006521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-28T21:43:50.213465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.479842ms","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-05-28T21:43:50.213478Z","caller":"traceutil/trace.go:171","msg":"trace[1761277601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"297.184775ms","start":"2026-05-28T21:43:49.916277Z","end":"2026-05-28T21:43:50.213462Z","steps":["trace[1761277601] 'agreement among raft nodes before linearized reading' (duration: 296.953519ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:43:50.213494Z","caller":"traceutil/trace.go:171","msg":"trace[117114330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"277.525014ms","start":"2026-05-28T21:43:49.935963Z","end":"2026-05-28T21:43:50.213488Z","steps":["trace[117114330] 'agreement among raft nodes before linearized reading' (duration: 277.486353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:43:50.213432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.339682ms","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-05-28T21:43:50.213548Z","caller":"traceutil/trace.go:171","msg":"trace[1241978024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"291.466525ms","start":"2026-05-28T21:43:49.922068Z","end":"2026-05-28T21:43:50.213535Z","steps":["trace[1241978024] 'agreement among raft nodes before linearized reading' (duration: 291.229399ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:43:50.393735Z","caller":"traceutil/trace.go:171","msg":"trace[844227473] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"176.081455ms","start":"2026-05-28T21:43:50.217626Z","end":"2026-05-28T21:43:50.393708Z","steps":["trace[844227473] 'process raft request' (duration: 154.739606ms)","trace[844227473] 'compare' (duration: 21.177275ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:43:50.395211Z","caller":"traceutil/trace.go:171","msg":"trace[759635026] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"177.546436ms","start":"2026-05-28T21:43:50.217654Z","end":"2026-05-28T21:43:50.3952Z","steps":["trace[759635026] 'process raft request' (duration: 177.460564ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:43:50.395589Z","caller":"traceutil/trace.go:171","msg":"trace[699542256] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"177.768022ms","start":"2026-05-28T21:43:50.217784Z","end":"2026-05-28T21:43:50.395552Z","steps":["trace[699542256] 'process raft request' (duration: 177.388431ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:43:50.396369Z","caller":"traceutil/trace.go:171","msg":"trace[461725178] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:612; }","duration":"149.858618ms","start":"2026-05-28T21:43:50.2465Z","end":"2026-05-28T21:43:50.396358Z","steps":["trace[461725178] 'read index received' (duration: 125.939217ms)","trace[461725178] 'applied index is now lower than readState.Index' (duration: 23.918811ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:43:50.396451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.9449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:43:50.39651Z","caller":"traceutil/trace.go:171","msg":"trace[730565345] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"128.316813ms","start":"2026-05-28T21:43:50.26818Z","end":"2026-05-28T21:43:50.396497Z","steps":["trace[730565345] 'process raft request' (duration: 127.386377ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:43:50.396613Z","caller":"traceutil/trace.go:171","msg":"trace[582285513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"150.146546ms","start":"2026-05-28T21:43:50.246454Z","end":"2026-05-28T21:43:50.396601Z","steps":["trace[582285513] 'agreement among raft nodes before linearized reading' (duration: 149.957991ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:44:10.946991Z","caller":"traceutil/trace.go:171","msg":"trace[1465314268] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"104.361369ms","start":"2026-05-28T21:44:10.842605Z","end":"2026-05-28T21:44:10.946966Z","steps":["trace[1465314268] 'process raft request' (duration: 104.227106ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:27.285308Z","caller":"traceutil/trace.go:171","msg":"trace[1818813551] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"195.891174ms","start":"2026-05-28T21:45:27.08939Z","end":"2026-05-28T21:45:27.285281Z","steps":["trace[1818813551] 'process raft request' (duration: 194.773184ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:27.285753Z","caller":"traceutil/trace.go:171","msg":"trace[1417776580] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"111.6883ms","start":"2026-05-28T21:45:27.174002Z","end":"2026-05-28T21:45:27.28569Z","steps":["trace[1417776580] 'process raft request' (duration: 111.451873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:32.176917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.080956ms","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-05-28T21:45:32.177004Z","caller":"traceutil/trace.go:171","msg":"trace[65862530] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1891; }","duration":"104.2321ms","start":"2026-05-28T21:45:32.072754Z","end":"2026-05-28T21:45:32.176986Z","steps":["trace[65862530] 'range keys from in-memory index tree' (duration: 103.88864ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:38.397076Z","caller":"traceutil/trace.go:171","msg":"trace[2087666679] linearizableReadLoop","detail":"{readStateIndex:2006; appliedIndex:2005; }","duration":"119.241792ms","start":"2026-05-28T21:45:38.277813Z","end":"2026-05-28T21:45:38.397054Z","steps":["trace[2087666679] 'read index received' (duration: 119.103558ms)","trace[2087666679] 'applied index is now lower than readState.Index' (duration: 137.213µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:38.397152Z","caller":"traceutil/trace.go:171","msg":"trace[72823686] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"171.636994ms","start":"2026-05-28T21:45:38.225487Z","end":"2026-05-28T21:45:38.397124Z","steps":["trace[72823686] 'process raft request' (duration: 171.446269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:38.397301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.465427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T21:45:38.397387Z","caller":"traceutil/trace.go:171","msg":"trace[113178453] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1933; }","duration":"119.609511ms","start":"2026-05-28T21:45:38.277762Z","end":"2026-05-28T21:45:38.397371Z","steps":["trace[113178453] 'agreement among raft nodes before linearized reading' (duration: 119.435396ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:10.697247Z","caller":"traceutil/trace.go:171","msg":"trace[705034420] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"153.035796ms","start":"2026-05-28T21:46:10.544182Z","end":"2026-05-28T21:46:10.697218Z","steps":["trace[705034420] 'process raft request' (duration: 92.238439ms)","trace[705034420] 'compare' (duration: 60.620182ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:46:10.697247Z","caller":"traceutil/trace.go:171","msg":"trace[1312038178] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2202; }","duration":"103.842429ms","start":"2026-05-28T21:46:10.593377Z","end":"2026-05-28T21:46:10.697219Z","steps":["trace[1312038178] 'read index received' (duration: 42.702553ms)","trace[1312038178] 'applied index is now lower than readState.Index' (duration: 61.137986ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:46:10.697458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.060716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-05-28T21:46:10.69753Z","caller":"traceutil/trace.go:171","msg":"trace[360063086] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2124; }","duration":"104.195299ms","start":"2026-05-28T21:46:10.593323Z","end":"2026-05-28T21:46:10.697518Z","steps":["trace[360063086] 'agreement among raft nodes before linearized reading' (duration: 103.931442ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:16.306934Z","caller":"traceutil/trace.go:171","msg":"trace[322460391] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"134.958362ms","start":"2026-05-28T21:46:16.171954Z","end":"2026-05-28T21:46:16.306913Z","steps":["trace[322460391] 'process raft request' (duration: 134.833249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:16.884095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.370396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:46:16.884338Z","caller":"traceutil/trace.go:171","msg":"trace[1530451738] linearizableReadLoop","detail":"{readStateIndex:2238; appliedIndex:2236; }","duration":"164.611906ms","start":"2026-05-28T21:46:16.719703Z","end":"2026-05-28T21:46:16.884315Z","steps":["trace[1530451738] 'read index received' (duration: 108.210966ms)","trace[1530451738] 'applied index is now lower than readState.Index' (duration: 56.39964ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:46:16.884561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.858682ms","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-05-28T21:46:16.884549Z","caller":"traceutil/trace.go:171","msg":"trace[1146962915] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"316.492961ms","start":"2026-05-28T21:46:16.568018Z","end":"2026-05-28T21:46:16.884511Z","steps":["trace[1146962915] 'process raft request' (duration: 119.200751ms)","trace[1146962915] 'compare' (duration: 196.025406ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:46:16.884601Z","caller":"traceutil/trace.go:171","msg":"trace[2047869587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2157; }","duration":"164.939654ms","start":"2026-05-28T21:46:16.71965Z","end":"2026-05-28T21:46:16.884589Z","steps":["trace[2047869587] 'agreement among raft nodes before linearized reading' (duration: 164.735539ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:16.884593Z","caller":"traceutil/trace.go:171","msg":"trace[1743940535] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"273.433848ms","start":"2026-05-28T21:46:16.611116Z","end":"2026-05-28T21:46:16.88455Z","steps":["trace[1743940535] 'process raft request' (duration: 273.11985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:16.884727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:16.567991Z","time spent":"316.636806ms","remote":"127.0.0.1:46872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:46:17.218258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.011596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:46:17.218455Z","caller":"traceutil/trace.go:171","msg":"trace[1129342125] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"330.056505ms","start":"2026-05-28T21:46:16.888367Z","end":"2026-05-28T21:46:17.218423Z","steps":["trace[1129342125] 'process raft request' (duration: 198.7272ms)","trace[1129342125] 'compare' (duration: 130.429361ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:46:17.218663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:16.888345Z","time spent":"330.169557ms","remote":"127.0.0.1:46872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:46:17.233136Z","caller":"traceutil/trace.go:171","msg":"trace[1353974804] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"324.591588ms","start":"2026-05-28T21:46:16.908521Z","end":"2026-05-28T21:46:17.233112Z","steps":["trace[1353974804] 'process raft request' (duration: 324.347242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:17.233257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:16.908499Z","time spent":"324.703102ms","remote":"127.0.0.1:46782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:39.423366Z","caller":"traceutil/trace.go:171","msg":"trace[1332059187] transaction","detail":"{read_only:false; response_revision:2797; number_of_response:1; }","duration":"103.090129ms","start":"2026-05-28T21:47:39.320247Z","end":"2026-05-28T21:47:39.423337Z","steps":["trace[1332059187] 'process raft request' (duration: 102.940985ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.952882Z","caller":"traceutil/trace.go:171","msg":"trace[1300648182] transaction","detail":"{read_only:false; response_revision:2801; number_of_response:1; }","duration":"332.120764ms","start":"2026-05-28T21:47:39.620737Z","end":"2026-05-28T21:47:39.952858Z","steps":["trace[1300648182] 'process raft request' (duration: 332.083923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:39.953033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:39.62073Z","time spent":"332.226087ms","remote":"127.0.0.1:46872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:39.95318Z","caller":"traceutil/trace.go:171","msg":"trace[812321806] transaction","detail":"{read_only:false; response_revision:2799; number_of_response:1; }","duration":"333.796071ms","start":"2026-05-28T21:47:39.61935Z","end":"2026-05-28T21:47:39.953146Z","steps":["trace[812321806] 'process raft request' (duration: 333.330608ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.95322Z","caller":"traceutil/trace.go:171","msg":"trace[651837978] transaction","detail":"{read_only:false; response_revision:2800; number_of_response:1; }","duration":"332.584447ms","start":"2026-05-28T21:47:39.620624Z","end":"2026-05-28T21:47:39.953209Z","steps":["trace[651837978] 'process raft request' (duration: 332.160005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:39.953277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:39.620611Z","time spent":"332.638269ms","remote":"127.0.0.1:46872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:39.953315Z","caller":"traceutil/trace.go:171","msg":"trace[2053971996] transaction","detail":"{read_only:false; response_revision:2802; number_of_response:1; }","duration":"197.019096ms","start":"2026-05-28T21:47:39.756271Z","end":"2026-05-28T21:47:39.95329Z","steps":["trace[2053971996] 'process raft request' (duration: 196.729328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:39.953385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:39.619337Z","time spent":"333.916244ms","remote":"127.0.0.1:46872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:39.95341Z","caller":"traceutil/trace.go:171","msg":"trace[2016360179] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"173.903825ms","start":"2026-05-28T21:47:39.779496Z","end":"2026-05-28T21:47:39.9534Z","steps":["trace[2016360179] 'process raft request' (duration: 173.622997ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:51:21.791424Z","caller":"traceutil/trace.go:171","msg":"trace[772157013] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"103.240766ms","start":"2026-05-28T21:51:21.688163Z","end":"2026-05-28T21:51:21.791404Z","steps":["trace[772157013] 'process raft request' (duration: 103.133323ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:51:27.191076Z","caller":"traceutil/trace.go:171","msg":"trace[1620881795] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"153.730912ms","start":"2026-05-28T21:51:27.037317Z","end":"2026-05-28T21:51:27.191048Z","steps":["trace[1620881795] 'process raft request' (duration: 153.596119ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:51:27.327927Z","caller":"traceutil/trace.go:171","msg":"trace[96786702] linearizableReadLoop","detail":"{readStateIndex:5113; appliedIndex:5111; }","duration":"278.391182ms","start":"2026-05-28T21:51:27.049513Z","end":"2026-05-28T21:51:27.327904Z","steps":["trace[96786702] 'read index received' (duration: 141.423772ms)","trace[96786702] 'applied index is now lower than readState.Index' (duration: 136.96642ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:51:27.327982Z","caller":"traceutil/trace.go:171","msg":"trace[885726331] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"286.246319ms","start":"2026-05-28T21:51:27.041709Z","end":"2026-05-28T21:51:27.327956Z","steps":["trace[885726331] 'process raft request' (duration: 286.054194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:27.330383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.81684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728895"} {"level":"info","ts":"2026-05-28T21:51:27.33058Z","caller":"traceutil/trace.go:171","msg":"trace[779716091] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4924; }","duration":"281.046265ms","start":"2026-05-28T21:51:27.049469Z","end":"2026-05-28T21:51:27.330515Z","steps":["trace[779716091] 'agreement among raft nodes before linearized reading' (duration: 278.567156ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:51:35.005416Z","caller":"traceutil/trace.go:171","msg":"trace[517595623] transaction","detail":"{read_only:false; response_revision:5094; number_of_response:1; }","duration":"116.387792ms","start":"2026-05-28T21:51:34.88897Z","end":"2026-05-28T21:51:35.005358Z","steps":["trace[517595623] 'process raft request' (duration: 116.169316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:35.340013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.337679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:51:35.340196Z","caller":"traceutil/trace.go:171","msg":"trace[1300544803] transaction","detail":"{read_only:false; response_revision:5100; number_of_response:1; }","duration":"143.536363ms","start":"2026-05-28T21:51:35.196642Z","end":"2026-05-28T21:51:35.340178Z","steps":["trace[1300544803] 'process raft request' (duration: 143.478491ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:51:35.340337Z","caller":"traceutil/trace.go:171","msg":"trace[555441658] transaction","detail":"{read_only:false; response_revision:5099; number_of_response:1; }","duration":"209.621592ms","start":"2026-05-28T21:51:35.13068Z","end":"2026-05-28T21:51:35.340301Z","steps":["trace[555441658] 'process raft request' (duration: 53.905542ms)","trace[555441658] 'compare' (duration: 155.220336ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:51:35.340475Z","caller":"traceutil/trace.go:171","msg":"trace[713091096] linearizableReadLoop","detail":"{readStateIndex:5295; appliedIndex:5294; }","duration":"209.105718ms","start":"2026-05-28T21:51:35.131352Z","end":"2026-05-28T21:51:35.340458Z","steps":["trace[713091096] 'read index received' (duration: 53.196033ms)","trace[713091096] 'applied index is now lower than readState.Index' (duration: 155.907365ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:51:35.340642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.234532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:51:35.340825Z","caller":"traceutil/trace.go:171","msg":"trace[287403174] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:5100; }","duration":"209.473788ms","start":"2026-05-28T21:51:35.131336Z","end":"2026-05-28T21:51:35.34081Z","steps":["trace[287403174] 'agreement among raft nodes before linearized reading' (duration: 209.17708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:35.341078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.865004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-28T21:51:35.341112Z","caller":"traceutil/trace.go:171","msg":"trace[111442309] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5100; }","duration":"207.913375ms","start":"2026-05-28T21:51:35.133191Z","end":"2026-05-28T21:51:35.341104Z","steps":["trace[111442309] 'agreement among raft nodes before linearized reading' (duration: 207.789822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:35.341308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.14392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-28T21:51:35.341389Z","caller":"traceutil/trace.go:171","msg":"trace[2044883575] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5100; }","duration":"170.254733ms","start":"2026-05-28T21:51:35.171119Z","end":"2026-05-28T21:51:35.341373Z","steps":["trace[2044883575] 'agreement among raft nodes before linearized reading' (duration: 170.113839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:35.342731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.599874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:110524"} {"level":"info","ts":"2026-05-28T21:51:35.342812Z","caller":"traceutil/trace.go:171","msg":"trace[1979040609] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:5100; }","duration":"208.712247ms","start":"2026-05-28T21:51:35.13408Z","end":"2026-05-28T21:51:35.342792Z","steps":["trace[1979040609] 'agreement among raft nodes before linearized reading' (duration: 207.209445ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:51:48.032672Z","caller":"traceutil/trace.go:171","msg":"trace[798178837] transaction","detail":"{read_only:false; response_revision:5339; number_of_response:1; }","duration":"170.228012ms","start":"2026-05-28T21:51:47.862403Z","end":"2026-05-28T21:51:48.032632Z","steps":["trace[798178837] 'process raft request' (duration: 169.979355ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:08.37893Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2959} {"level":"info","ts":"2026-05-28T21:53:08.448483Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2959,"took":"67.998023ms","hash":3113661665,"current-db-size-bytes":59424768,"current-db-size":"59 MB","current-db-size-in-use-bytes":34418688,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-28T21:53:08.448624Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3113661665,"revision":2959,"compact-revision":-1} {"level":"info","ts":"2026-05-28T21:57:58.281914Z","caller":"traceutil/trace.go:171","msg":"trace[93156780] transaction","detail":"{read_only:false; response_revision:8207; number_of_response:1; }","duration":"137.467744ms","start":"2026-05-28T21:57:58.144425Z","end":"2026-05-28T21:57:58.281892Z","steps":["trace[93156780] 'process raft request' (duration: 137.33261ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:58:08.395156Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6090} {"level":"info","ts":"2026-05-28T21:58:08.714995Z","caller":"traceutil/trace.go:171","msg":"trace[877550118] transaction","detail":"{read_only:false; response_revision:8265; number_of_response:1; }","duration":"263.53851ms","start":"2026-05-28T21:58:08.451178Z","end":"2026-05-28T21:58:08.714717Z","steps":["trace[877550118] 'process raft request' (duration: 240.852943ms)","trace[877550118] 'compare' (duration: 22.242934ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:58:09.067806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6090,"took":"669.826962ms","hash":2311313549,"current-db-size-bytes":59424768,"current-db-size":"59 MB","current-db-size-in-use-bytes":23363584,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-28T21:58:09.067932Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2311313549,"revision":6090,"compact-revision":2959} {"level":"warn","ts":"2026-05-28T21:58:37.493291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.086002ms","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-05-28T21:58:37.493367Z","caller":"traceutil/trace.go:171","msg":"trace[2096414655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8476; }","duration":"244.207486ms","start":"2026-05-28T21:58:37.249144Z","end":"2026-05-28T21:58:37.493352Z","steps":["trace[2096414655] 'range keys from in-memory index tree' (duration: 243.932018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:58:41.450223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.718211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:58:41.450388Z","caller":"traceutil/trace.go:171","msg":"trace[1022876913] linearizableReadLoop","detail":"{readStateIndex:8792; appliedIndex:8791; }","duration":"271.968393ms","start":"2026-05-28T21:58:41.178396Z","end":"2026-05-28T21:58:41.450365Z","steps":["trace[1022876913] 'read index received' (duration: 56.970384ms)","trace[1022876913] 'applied index is now lower than readState.Index' (duration: 214.995808ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:58:41.450694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.310132ms","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-05-28T21:58:41.450733Z","caller":"traceutil/trace.go:171","msg":"trace[63675843] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8493; }","duration":"272.404864ms","start":"2026-05-28T21:58:41.178316Z","end":"2026-05-28T21:58:41.450721Z","steps":["trace[63675843] 'agreement among raft nodes before linearized reading' (duration: 272.157398ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:58:41.450453Z","caller":"traceutil/trace.go:171","msg":"trace[1758835259] transaction","detail":"{read_only:false; response_revision:8493; number_of_response:1; }","duration":"276.104457ms","start":"2026-05-28T21:58:41.174282Z","end":"2026-05-28T21:58:41.450386Z","steps":["trace[1758835259] 'process raft request' (duration: 61.073067ms)","trace[1758835259] 'compare' (duration: 214.503436ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:58:41.450747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.640867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:58:41.450848Z","caller":"traceutil/trace.go:171","msg":"trace[1528641283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8493; }","duration":"202.798722ms","start":"2026-05-28T21:58:41.248024Z","end":"2026-05-28T21:58:41.450823Z","steps":["trace[1528641283] 'agreement among raft nodes before linearized reading' (duration: 202.624957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:58:41.450856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.406073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-28T21:58:41.450932Z","caller":"traceutil/trace.go:171","msg":"trace[512571460] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:8493; }","duration":"117.581318ms","start":"2026-05-28T21:58:41.333332Z","end":"2026-05-28T21:58:41.450913Z","steps":["trace[512571460] 'agreement among raft nodes before linearized reading' (duration: 117.31256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:58:41.450947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.522347ms","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-05-28T21:58:41.451067Z","caller":"traceutil/trace.go:171","msg":"trace[1272439509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8493; }","duration":"226.711602ms","start":"2026-05-28T21:58:41.224326Z","end":"2026-05-28T21:58:41.451037Z","steps":["trace[1272439509] 'agreement among raft nodes before linearized reading' (duration: 226.325491ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:58:51.141033Z","caller":"traceutil/trace.go:171","msg":"trace[685093514] transaction","detail":"{read_only:false; response_revision:8588; number_of_response:1; }","duration":"132.5095ms","start":"2026-05-28T21:58:51.00849Z","end":"2026-05-28T21:58:51.140999Z","steps":["trace[685093514] 'process raft request' (duration: 131.936714ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:59:06.377024Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000234e.wal"} {"level":"info","ts":"2026-05-28T21:59:55.963921Z","caller":"traceutil/trace.go:171","msg":"trace[931329362] transaction","detail":"{read_only:false; response_revision:9272; number_of_response:1; }","duration":"101.383811ms","start":"2026-05-28T21:59:55.862496Z","end":"2026-05-28T21:59:55.96388Z","steps":["trace[931329362] 'process raft request' (duration: 101.188826ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:08.198508Z","caller":"traceutil/trace.go:171","msg":"trace[2046355224] linearizableReadLoop","detail":"{readStateIndex:9668; appliedIndex:9667; }","duration":"129.912278ms","start":"2026-05-28T22:00:08.068568Z","end":"2026-05-28T22:00:08.19848Z","steps":["trace[2046355224] 'read index received' (duration: 129.296081ms)","trace[2046355224] 'applied index is now lower than readState.Index' (duration: 615.197µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:00:08.198603Z","caller":"traceutil/trace.go:171","msg":"trace[332200386] transaction","detail":"{read_only:false; response_revision:9341; number_of_response:1; }","duration":"178.538942ms","start":"2026-05-28T22:00:08.02003Z","end":"2026-05-28T22:00:08.198569Z","steps":["trace[332200386] 'process raft request' (duration: 177.948615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:08.199039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.170156ms","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-05-28T22:00:08.199091Z","caller":"traceutil/trace.go:171","msg":"trace[336179414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9341; }","duration":"130.567557ms","start":"2026-05-28T22:00:08.068511Z","end":"2026-05-28T22:00:08.199078Z","steps":["trace[336179414] 'agreement among raft nodes before linearized reading' (duration: 130.095584ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:08.355217Z","caller":"traceutil/trace.go:171","msg":"trace[368618804] linearizableReadLoop","detail":"{readStateIndex:9670; appliedIndex:9669; }","duration":"107.552421ms","start":"2026-05-28T22:00:08.247637Z","end":"2026-05-28T22:00:08.355189Z","steps":["trace[368618804] 'read index received' (duration: 98.123891ms)","trace[368618804] 'applied index is now lower than readState.Index' (duration: 9.4271ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:00:08.355426Z","caller":"traceutil/trace.go:171","msg":"trace[1918278811] transaction","detail":"{read_only:false; response_revision:9343; number_of_response:1; }","duration":"122.554525ms","start":"2026-05-28T22:00:08.232826Z","end":"2026-05-28T22:00:08.35538Z","steps":["trace[1918278811] 'process raft request' (duration: 112.919879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:08.355509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.836869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:00:08.355683Z","caller":"traceutil/trace.go:171","msg":"trace[363847939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9343; }","duration":"108.048714ms","start":"2026-05-28T22:00:08.247611Z","end":"2026-05-28T22:00:08.35566Z","steps":["trace[363847939] 'agreement among raft nodes before linearized reading' (duration: 107.808297ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:52.405444Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-28T22:00:52.411987Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-28T22:00:52.412143Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-28T22:03:08.401954Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8264} {"level":"info","ts":"2026-05-28T22:03:08.480673Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8264,"took":"76.409832ms","hash":3474727777,"current-db-size-bytes":59424768,"current-db-size":"59 MB","current-db-size-in-use-bytes":25452544,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-28T22:03:08.480742Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3474727777,"revision":8264,"compact-revision":6090} {"level":"info","ts":"2026-05-28T22:03:50.672539Z","caller":"traceutil/trace.go:171","msg":"trace[1490462297] linearizableReadLoop","detail":"{readStateIndex:11567; appliedIndex:11566; }","duration":"106.152694ms","start":"2026-05-28T22:03:50.566357Z","end":"2026-05-28T22:03:50.672509Z","steps":["trace[1490462297] 'read index received' (duration: 96.936469ms)","trace[1490462297] 'applied index is now lower than readState.Index' (duration: 9.214805ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:03:50.672639Z","caller":"traceutil/trace.go:171","msg":"trace[1316608744] transaction","detail":"{read_only:false; response_revision:11185; number_of_response:1; }","duration":"144.283339ms","start":"2026-05-28T22:03:50.528325Z","end":"2026-05-28T22:03:50.672608Z","steps":["trace[1316608744] 'process raft request' (duration: 134.978431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:03:50.672841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.468523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-28T22:03:50.6729Z","caller":"traceutil/trace.go:171","msg":"trace[706731274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11185; }","duration":"106.580516ms","start":"2026-05-28T22:03:50.566302Z","end":"2026-05-28T22:03:50.672882Z","steps":["trace[706731274] 'agreement among raft nodes before linearized reading' (duration: 106.374501ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:08:08.407679Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10756} {"level":"info","ts":"2026-05-28T22:08:08.491932Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10756,"took":"82.192505ms","hash":2497972849,"current-db-size-bytes":59424768,"current-db-size":"59 MB","current-db-size-in-use-bytes":27934720,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-28T22:08:08.492016Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2497972849,"revision":10756,"compact-revision":8264} {"level":"warn","ts":"2026-05-28T22:10:23.062893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.420252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T22:10:23.062932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.717779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-28T22:10:23.063045Z","caller":"traceutil/trace.go:171","msg":"trace[1873400382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13842; }","duration":"168.860562ms","start":"2026-05-28T22:10:22.894134Z","end":"2026-05-28T22:10:23.062995Z","steps":["trace[1873400382] 'range keys from in-memory index tree' (duration: 168.548414ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:10:23.063041Z","caller":"traceutil/trace.go:171","msg":"trace[700130736] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:13842; }","duration":"159.567096ms","start":"2026-05-28T22:10:22.90342Z","end":"2026-05-28T22:10:23.062987Z","steps":["trace[700130736] 'range keys from in-memory index tree' (duration: 159.35419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:10:23.063035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.149881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-zn9d2\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-05-28T22:10:23.063138Z","caller":"traceutil/trace.go:171","msg":"trace[20077815] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-zn9d2; range_end:; response_count:1; response_revision:13842; }","duration":"169.288925ms","start":"2026-05-28T22:10:22.893828Z","end":"2026-05-28T22:10:23.063117Z","steps":["trace[20077815] 'range keys from in-memory index tree' (duration: 168.956575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:10:32.675755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.102771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18954"} {"level":"info","ts":"2026-05-28T22:10:32.67585Z","caller":"traceutil/trace.go:171","msg":"trace[2027729879] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:14014; }","duration":"147.243785ms","start":"2026-05-28T22:10:32.528587Z","end":"2026-05-28T22:10:32.67583Z","steps":["trace[2027729879] 'range keys from in-memory index tree' (duration: 146.942637ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:10:32.864521Z","caller":"traceutil/trace.go:171","msg":"trace[1013377140] linearizableReadLoop","detail":"{readStateIndex:14494; appliedIndex:14492; }","duration":"183.298241ms","start":"2026-05-28T22:10:32.681198Z","end":"2026-05-28T22:10:32.864496Z","steps":["trace[1013377140] 'read index received' (duration: 37.923358ms)","trace[1013377140] 'applied index is now lower than readState.Index' (duration: 145.373853ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:10:32.864704Z","caller":"traceutil/trace.go:171","msg":"trace[231890770] transaction","detail":"{read_only:false; response_revision:14016; number_of_response:1; }","duration":"183.767095ms","start":"2026-05-28T22:10:32.680898Z","end":"2026-05-28T22:10:32.864665Z","steps":["trace[231890770] 'process raft request' (duration: 183.444636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:10:32.864994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.774775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18954"} {"level":"info","ts":"2026-05-28T22:10:32.865077Z","caller":"traceutil/trace.go:171","msg":"trace[1190227549] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:14016; }","duration":"183.896988ms","start":"2026-05-28T22:10:32.681161Z","end":"2026-05-28T22:10:32.865058Z","steps":["trace[1190227549] 'agreement among raft nodes before linearized reading' (duration: 183.535598ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:12:11.56725Z","caller":"traceutil/trace.go:171","msg":"trace[870347998] transaction","detail":"{read_only:false; response_revision:15095; number_of_response:1; }","duration":"132.469763ms","start":"2026-05-28T22:12:11.434744Z","end":"2026-05-28T22:12:11.567214Z","steps":["trace[870347998] 'process raft request' (duration: 132.349349ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:12:11.567342Z","caller":"traceutil/trace.go:171","msg":"trace[395514103] linearizableReadLoop","detail":"{readStateIndex:15607; appliedIndex:15606; }","duration":"145.447232ms","start":"2026-05-28T22:12:11.421869Z","end":"2026-05-28T22:12:11.567316Z","steps":["trace[395514103] 'read index received' (duration: 118.597759ms)","trace[395514103] 'applied index is now lower than readState.Index' (duration: 26.848033ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:12:11.567358Z","caller":"traceutil/trace.go:171","msg":"trace[775613041] transaction","detail":"{read_only:false; response_revision:15096; number_of_response:1; }","duration":"127.080384ms","start":"2026-05-28T22:12:11.440258Z","end":"2026-05-28T22:12:11.567339Z","steps":["trace[775613041] 'process raft request' (duration: 126.903049ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:12:11.567264Z","caller":"traceutil/trace.go:171","msg":"trace[488012898] transaction","detail":"{read_only:false; response_revision:15094; number_of_response:1; }","duration":"172.386347ms","start":"2026-05-28T22:12:11.394845Z","end":"2026-05-28T22:12:11.567231Z","steps":["trace[488012898] 'process raft request' (duration: 171.969226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:12:11.567586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.694349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-cn96q\" ","response":"range_response_count:1 size:20746"} {"level":"info","ts":"2026-05-28T22:12:11.567624Z","caller":"traceutil/trace.go:171","msg":"trace[1039147240] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-cn96q; range_end:; response_count:1; response_revision:15096; }","duration":"145.786642ms","start":"2026-05-28T22:12:11.421826Z","end":"2026-05-28T22:12:11.567613Z","steps":["trace[1039147240] 'agreement among raft nodes before linearized reading' (duration: 145.607327ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:12:11.664462Z","caller":"traceutil/trace.go:171","msg":"trace[778115556] transaction","detail":"{read_only:false; response_revision:15097; number_of_response:1; }","duration":"123.238418ms","start":"2026-05-28T22:12:11.541198Z","end":"2026-05-28T22:12:11.664436Z","steps":["trace[778115556] 'process raft request' (duration: 123.032712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:12:11.665167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.239841ms","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-05-28T22:12:11.665215Z","caller":"traceutil/trace.go:171","msg":"trace[33773184] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15097; }","duration":"130.327864ms","start":"2026-05-28T22:12:11.534875Z","end":"2026-05-28T22:12:11.665203Z","steps":["trace[33773184] 'agreement among raft nodes before linearized reading' (duration: 130.198631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:12:11.665433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.882157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-snprz\" ","response":"range_response_count:1 size:22093"} {"level":"info","ts":"2026-05-28T22:12:11.665465Z","caller":"traceutil/trace.go:171","msg":"trace[715053307] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-snprz; range_end:; response_count:1; response_revision:15097; }","duration":"165.944859ms","start":"2026-05-28T22:12:11.499511Z","end":"2026-05-28T22:12:11.665456Z","steps":["trace[715053307] 'agreement among raft nodes before linearized reading' (duration: 165.876447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:12:11.665626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.107987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-snprz\" ","response":"range_response_count:1 size:22093"} {"level":"info","ts":"2026-05-28T22:12:11.665653Z","caller":"traceutil/trace.go:171","msg":"trace[1349768509] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-snprz; range_end:; response_count:1; response_revision:15097; }","duration":"173.170888ms","start":"2026-05-28T22:12:11.492475Z","end":"2026-05-28T22:12:11.665646Z","steps":["trace[1349768509] 'agreement among raft nodes before linearized reading' (duration: 173.117607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:12:11.665881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.098424ms","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-05-28T22:12:11.66592Z","caller":"traceutil/trace.go:171","msg":"trace[1329092609] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15097; }","duration":"187.176206ms","start":"2026-05-28T22:12:11.478735Z","end":"2026-05-28T22:12:11.665911Z","steps":["trace[1329092609] 'agreement among raft nodes before linearized reading' (duration: 187.078053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:12:11.666056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.09949ms","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-05-28T22:12:11.66609Z","caller":"traceutil/trace.go:171","msg":"trace[1085325784] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15097; }","duration":"189.184942ms","start":"2026-05-28T22:12:11.476897Z","end":"2026-05-28T22:12:11.666082Z","steps":["trace[1085325784] 'agreement among raft nodes before linearized reading' (duration: 189.13696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:12:11.666211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.271704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:12:11.666241Z","caller":"traceutil/trace.go:171","msg":"trace[951948200] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15097; }","duration":"189.331656ms","start":"2026-05-28T22:12:11.476902Z","end":"2026-05-28T22:12:11.666233Z","steps":["trace[951948200] 'agreement among raft nodes before linearized reading' (duration: 189.289635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:12:11.66634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.876918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:12:11.66642Z","caller":"traceutil/trace.go:171","msg":"trace[2144820296] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15097; }","duration":"190.95057ms","start":"2026-05-28T22:12:11.475412Z","end":"2026-05-28T22:12:11.666363Z","steps":["trace[2144820296] 'agreement among raft nodes before linearized reading' (duration: 190.909299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:12:11.666659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.398862ms","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-05-28T22:12:11.666696Z","caller":"traceutil/trace.go:171","msg":"trace[1258680248] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:15097; }","duration":"221.462624ms","start":"2026-05-28T22:12:11.445221Z","end":"2026-05-28T22:12:11.666684Z","steps":["trace[1258680248] 'agreement among raft nodes before linearized reading' (duration: 221.31576ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:13:08.414484Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12955} {"level":"info","ts":"2026-05-28T22:13:08.495349Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12955,"took":"78.13322ms","hash":251329150,"current-db-size-bytes":59424768,"current-db-size":"59 MB","current-db-size-in-use-bytes":30982144,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-28T22:13:08.495412Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":251329150,"revision":12955,"compact-revision":10756} {"level":"info","ts":"2026-05-28T22:17:10.903356Z","caller":"traceutil/trace.go:171","msg":"trace[466081534] transaction","detail":"{read_only:false; response_revision:17310; number_of_response:1; }","duration":"306.313833ms","start":"2026-05-28T22:17:10.59702Z","end":"2026-05-28T22:17:10.903334Z","steps":["trace[466081534] 'process raft request' (duration: 306.177439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:17:10.90353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:17:10.597006Z","time spent":"306.455058ms","remote":"127.0.0.1:46792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9283,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:17:10.904145Z","caller":"traceutil/trace.go:171","msg":"trace[292268542] linearizableReadLoop","detail":"{readStateIndex:17897; appliedIndex:17894; }","duration":"264.067258ms","start":"2026-05-28T22:17:10.640056Z","end":"2026-05-28T22:17:10.904124Z","steps":["trace[292268542] 'read index received' (duration: 263.204974ms)","trace[292268542] 'applied index is now lower than readState.Index' (duration: 861.284µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:17:10.904177Z","caller":"traceutil/trace.go:171","msg":"trace[1283215671] transaction","detail":"{read_only:false; response_revision:17311; number_of_response:1; }","duration":"269.702094ms","start":"2026-05-28T22:17:10.634453Z","end":"2026-05-28T22:17:10.904155Z","steps":["trace[1283215671] 'process raft request' (duration: 269.519089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:17:10.904369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.310607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-28T22:17:10.90441Z","caller":"traceutil/trace.go:171","msg":"trace[613456198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17312; }","duration":"134.40958ms","start":"2026-05-28T22:17:10.769989Z","end":"2026-05-28T22:17:10.904399Z","steps":["trace[613456198] 'agreement among raft nodes before linearized reading' (duration: 134.252355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:17:10.904406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.342675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-28T22:17:10.904875Z","caller":"traceutil/trace.go:171","msg":"trace[2035277456] transaction","detail":"{read_only:false; response_revision:17312; number_of_response:1; }","duration":"269.909949ms","start":"2026-05-28T22:17:10.634948Z","end":"2026-05-28T22:17:10.904858Z","steps":["trace[2035277456] 'process raft request' (duration: 269.134728ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:17:10.904489Z","caller":"traceutil/trace.go:171","msg":"trace[2056984255] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17312; }","duration":"264.450349ms","start":"2026-05-28T22:17:10.640024Z","end":"2026-05-28T22:17:10.904475Z","steps":["trace[2056984255] 'agreement among raft nodes before linearized reading' (duration: 264.242273ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:17:55.46568Z","caller":"traceutil/trace.go:171","msg":"trace[1436866277] transaction","detail":"{read_only:false; response_revision:17637; number_of_response:1; }","duration":"218.320835ms","start":"2026-05-28T22:17:55.247338Z","end":"2026-05-28T22:17:55.465659Z","steps":["trace[1436866277] 'process raft request' (duration: 218.171581ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:17:55.616484Z","caller":"traceutil/trace.go:171","msg":"trace[1939407251] transaction","detail":"{read_only:false; response_revision:17638; number_of_response:1; }","duration":"361.60072ms","start":"2026-05-28T22:17:55.254857Z","end":"2026-05-28T22:17:55.616457Z","steps":["trace[1939407251] 'process raft request' (duration: 361.394254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:17:55.616631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:17:55.254832Z","time spent":"361.725732ms","remote":"127.0.0.1:46792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20966,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:17:55.61669Z","caller":"traceutil/trace.go:171","msg":"trace[293500435] linearizableReadLoop","detail":"{readStateIndex:18236; appliedIndex:18234; }","duration":"311.827836ms","start":"2026-05-28T22:17:55.304792Z","end":"2026-05-28T22:17:55.61662Z","steps":["trace[293500435] 'read index received' (duration: 160.707266ms)","trace[293500435] 'applied index is now lower than readState.Index' (duration: 151.11857ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:17:55.616819Z","caller":"traceutil/trace.go:171","msg":"trace[2099545965] transaction","detail":"{read_only:false; response_revision:17639; number_of_response:1; }","duration":"159.155353ms","start":"2026-05-28T22:17:55.457635Z","end":"2026-05-28T22:17:55.61679Z","steps":["trace[2099545965] 'process raft request' (duration: 158.748272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:17:55.616918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.110264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:17:55.616988Z","caller":"traceutil/trace.go:171","msg":"trace[882519587] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:17639; }","duration":"312.235857ms","start":"2026-05-28T22:17:55.304736Z","end":"2026-05-28T22:17:55.616972Z","steps":["trace[882519587] 'agreement among raft nodes before linearized reading' (duration: 312.075362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:17:55.617034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:17:55.304709Z","time spent":"312.314929ms","remote":"127.0.0.1:39342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"warn","ts":"2026-05-28T22:17:55.617209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.903939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T22:17:55.617253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.869107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-05-28T22:17:55.617259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.599721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-28T22:17:55.617298Z","caller":"traceutil/trace.go:171","msg":"trace[1325871750] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:17639; }","duration":"166.033902ms","start":"2026-05-28T22:17:55.451239Z","end":"2026-05-28T22:17:55.617273Z","steps":["trace[1325871750] 'agreement among raft nodes before linearized reading' (duration: 165.889908ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:17:55.617323Z","caller":"traceutil/trace.go:171","msg":"trace[393426413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17639; }","duration":"302.657773ms","start":"2026-05-28T22:17:55.314644Z","end":"2026-05-28T22:17:55.617302Z","steps":["trace[393426413] 'agreement among raft nodes before linearized reading' (duration: 302.505869ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:17:55.617305Z","caller":"traceutil/trace.go:171","msg":"trace[511890987] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17639; }","duration":"140.95647ms","start":"2026-05-28T22:17:55.476335Z","end":"2026-05-28T22:17:55.617291Z","steps":["trace[511890987] 'agreement among raft nodes before linearized reading' (duration: 140.572699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:17:55.617387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:17:55.314611Z","time spent":"302.767676ms","remote":"127.0.0.1:46872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T22:17:55.617509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.278208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-28T22:17:55.617586Z","caller":"traceutil/trace.go:171","msg":"trace[604415501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17639; }","duration":"308.37497ms","start":"2026-05-28T22:17:55.309197Z","end":"2026-05-28T22:17:55.617572Z","steps":["trace[604415501] 'agreement among raft nodes before linearized reading' (duration: 307.691332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:17:55.61762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:17:55.309186Z","time spent":"308.424242ms","remote":"127.0.0.1:46872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T22:17:55.617678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.519839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-28T22:17:55.617712Z","caller":"traceutil/trace.go:171","msg":"trace[1742899091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17639; }","duration":"302.588881ms","start":"2026-05-28T22:17:55.315112Z","end":"2026-05-28T22:17:55.617701Z","steps":["trace[1742899091] 'agreement among raft nodes before linearized reading' (duration: 301.914582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:17:55.617738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:17:55.315092Z","time spent":"302.639502ms","remote":"127.0.0.1:46872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-28T22:18:08.423298Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15457} {"level":"info","ts":"2026-05-28T22:18:08.525195Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15457,"took":"98.646262ms","hash":654135073,"current-db-size-bytes":59424768,"current-db-size":"59 MB","current-db-size-in-use-bytes":31789056,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-28T22:18:08.525261Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":654135073,"revision":15457,"compact-revision":12955} {"level":"info","ts":"2026-05-28T22:23:04.829384Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-28T22:23:04.835777Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-28T22:23:04.835888Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-28T22:23:08.429799Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17780} {"level":"info","ts":"2026-05-28T22:23:08.524479Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17780,"took":"91.450557ms","hash":9922940,"current-db-size-bytes":59424768,"current-db-size":"59 MB","current-db-size-in-use-bytes":26787840,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-28T22:23:08.524536Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":9922940,"revision":17780,"compact-revision":15457}