{"level":"warn","ts":"2026-05-22T23:29:28.861119Z","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-22T23:29:28.861205Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.19: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.19:2380","--initial-cluster=instance=https://199.204.45.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.19: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-22T23:29:28.861265Z","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-22T23:29:28.861278Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-05-22T23:29:28.861293Z","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-22T23:29:28.861921Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"]} {"level":"info","ts":"2026-05-22T23:29:28.86203Z","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.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.19: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-22T23:29:28.866563Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.337565ms"} {"level":"info","ts":"2026-05-22T23:29:28.871455Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a847c2e9c7010db6","cluster-id":"2b11a68e27507b05"} {"level":"info","ts":"2026-05-22T23:29:28.871527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=()"} {"level":"info","ts":"2026-05-22T23:29:28.871553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 0"} {"level":"info","ts":"2026-05-22T23:29:28.871568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a847c2e9c7010db6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-22T23:29:28.87158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 1"} {"level":"info","ts":"2026-05-22T23:29:28.871617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"warn","ts":"2026-05-22T23:29:28.877202Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-22T23:29:28.878872Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-22T23:29:28.880034Z","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-22T23:29:28.883034Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a847c2e9c7010db6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-22T23:29:28.883211Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a847c2e9c7010db6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-22T23:29:28.883251Z","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-22T23:29:28.883392Z","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-22T23:29:28.883423Z","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-22T23:29:28.884407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"info","ts":"2026-05-22T23:29:28.884767Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","added-peer-id":"a847c2e9c7010db6","added-peer-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-05-22T23:29:28.888344Z","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-22T23:29:28.888487Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-05-22T23:29:28.88857Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-05-22T23:29:28.888721Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a847c2e9c7010db6","initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-22T23:29:28.888798Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-22T23:29:29.472628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 is starting a new election at term 1"} {"level":"info","ts":"2026-05-22T23:29:29.472704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-22T23:29:29.472728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgPreVoteResp from a847c2e9c7010db6 at term 1"} {"level":"info","ts":"2026-05-22T23:29:29.472747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became candidate at term 2"} {"level":"info","ts":"2026-05-22T23:29:29.472759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgVoteResp from a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-05-22T23:29:29.472775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became leader at term 2"} {"level":"info","ts":"2026-05-22T23:29:29.472789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a847c2e9c7010db6 elected leader a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-05-22T23:29:29.473938Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a847c2e9c7010db6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.19:2379]}","request-path":"/0/members/a847c2e9c7010db6/attributes","cluster-id":"2b11a68e27507b05","publish-timeout":"7s"} {"level":"info","ts":"2026-05-22T23:29:29.473944Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-22T23:29:29.473951Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-22T23:29:29.47399Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-22T23:29:29.474268Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-22T23:29:29.474303Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-22T23:29:29.474804Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","cluster-version":"3.5"} {"level":"info","ts":"2026-05-22T23:29:29.474922Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-22T23:29:29.474959Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-22T23:29:29.477081Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.19:2379"} {"level":"info","ts":"2026-05-22T23:29:29.477368Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-22T23:29:47.492623Z","caller":"traceutil/trace.go:171","msg":"trace[1486678777] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"102.340492ms","start":"2026-05-22T23:29:47.390262Z","end":"2026-05-22T23:29:47.492602Z","steps":["trace[1486678777] 'process raft request' (duration: 102.163047ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:30:11.497437Z","caller":"traceutil/trace.go:171","msg":"trace[1796282723] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"106.679662ms","start":"2026-05-22T23:30:11.390732Z","end":"2026-05-22T23:30:11.497411Z","steps":["trace[1796282723] 'process raft request' (duration: 103.065093ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:30:12.282981Z","caller":"traceutil/trace.go:171","msg":"trace[548116913] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"243.23651ms","start":"2026-05-22T23:30:12.039699Z","end":"2026-05-22T23:30:12.282936Z","steps":["trace[548116913] 'process raft request' (duration: 242.982593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:31:51.384044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.1481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-22T23:31:51.384248Z","caller":"traceutil/trace.go:171","msg":"trace[1066043160] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"370.174318ms","start":"2026-05-22T23:31:51.014037Z","end":"2026-05-22T23:31:51.384211Z","steps":["trace[1066043160] 'process raft request' (duration: 262.874997ms)","trace[1066043160] 'compare' (duration: 105.987335ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:31:51.384383Z","caller":"traceutil/trace.go:171","msg":"trace[1028267359] linearizableReadLoop","detail":"{readStateIndex:1920; appliedIndex:1919; }","duration":"321.945304ms","start":"2026-05-22T23:31:51.062408Z","end":"2026-05-22T23:31:51.384353Z","steps":["trace[1028267359] 'read index received' (duration: 214.52059ms)","trace[1028267359] 'applied index is now lower than readState.Index' (duration: 107.422824ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T23:31:51.384426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:31:51.01402Z","time spent":"370.307412ms","remote":"127.0.0.1:51002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-22T23:31:51.384479Z","caller":"traceutil/trace.go:171","msg":"trace[1059806571] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"282.393692ms","start":"2026-05-22T23:31:51.102023Z","end":"2026-05-22T23:31:51.384416Z","steps":["trace[1059806571] 'process raft request' (duration: 282.184217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:31:51.384534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.120228ms","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":"warn","ts":"2026-05-22T23:31:51.384634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.996372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5e7a2073-7432-4ab9-a442-1c78fcad63dc\" ","response":"range_response_count:1 size:3746"} {"level":"warn","ts":"2026-05-22T23:31:51.384633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.616806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T23:31:51.384662Z","caller":"traceutil/trace.go:171","msg":"trace[445868072] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1850; }","duration":"322.281462ms","start":"2026-05-22T23:31:51.062369Z","end":"2026-05-22T23:31:51.384651Z","steps":["trace[445868072] 'agreement among raft nodes before linearized reading' (duration: 322.118918ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:31:51.38468Z","caller":"traceutil/trace.go:171","msg":"trace[1343281006] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5e7a2073-7432-4ab9-a442-1c78fcad63dc; range_end:; response_count:1; response_revision:1850; }","duration":"139.152016ms","start":"2026-05-22T23:31:51.245514Z","end":"2026-05-22T23:31:51.384666Z","steps":["trace[1343281006] 'agreement among raft nodes before linearized reading' (duration: 139.050003ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:31:51.384693Z","caller":"traceutil/trace.go:171","msg":"trace[2065106903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1850; }","duration":"162.740909ms","start":"2026-05-22T23:31:51.221933Z","end":"2026-05-22T23:31:51.384674Z","steps":["trace[2065106903] 'agreement among raft nodes before linearized reading' (duration: 162.629266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:31:51.384705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:31:51.062352Z","time spent":"322.341674ms","remote":"127.0.0.1:39190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-05-22T23:31:51.384676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.076222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-22T23:31:51.384726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.970203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-22T23:31:51.384803Z","caller":"traceutil/trace.go:171","msg":"trace[354385174] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1850; }","duration":"186.076095ms","start":"2026-05-22T23:31:51.198706Z","end":"2026-05-22T23:31:51.384782Z","steps":["trace[354385174] 'agreement among raft nodes before linearized reading' (duration: 185.864819ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:31:51.384823Z","caller":"traceutil/trace.go:171","msg":"trace[629628133] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1850; }","duration":"257.248277ms","start":"2026-05-22T23:31:51.127525Z","end":"2026-05-22T23:31:51.384773Z","steps":["trace[629628133] 'agreement among raft nodes before linearized reading' (duration: 257.032391ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:31:51.384605Z","caller":"traceutil/trace.go:171","msg":"trace[1995728610] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"119.564411ms","start":"2026-05-22T23:31:51.265028Z","end":"2026-05-22T23:31:51.384593Z","steps":["trace[1995728610] 'process raft request' (duration: 119.252873ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:31:51.573808Z","caller":"traceutil/trace.go:171","msg":"trace[160405868] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"122.703314ms","start":"2026-05-22T23:31:51.45108Z","end":"2026-05-22T23:31:51.573783Z","steps":["trace[160405868] 'process raft request' (duration: 122.603312ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:31:51.573824Z","caller":"traceutil/trace.go:171","msg":"trace[569387032] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"123.547238ms","start":"2026-05-22T23:31:51.450244Z","end":"2026-05-22T23:31:51.573791Z","steps":["trace[569387032] 'process raft request' (duration: 123.255139ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:31:51.574045Z","caller":"traceutil/trace.go:171","msg":"trace[348855444] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1925; }","duration":"109.218322ms","start":"2026-05-22T23:31:51.464788Z","end":"2026-05-22T23:31:51.574007Z","steps":["trace[348855444] 'read index received' (duration: 108.658886ms)","trace[348855444] 'applied index is now lower than readState.Index' (duration: 556.126µs)"],"step_count":2} {"level":"warn","ts":"2026-05-22T23:31:51.574351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.53192ms","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-05-22T23:31:51.574462Z","caller":"traceutil/trace.go:171","msg":"trace[1565843816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1856; }","duration":"109.697765ms","start":"2026-05-22T23:31:51.464741Z","end":"2026-05-22T23:31:51.574439Z","steps":["trace[1565843816] 'agreement among raft nodes before linearized reading' (duration: 109.414077ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:31:51.574879Z","caller":"traceutil/trace.go:171","msg":"trace[588606968] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"121.673237ms","start":"2026-05-22T23:31:51.45247Z","end":"2026-05-22T23:31:51.574144Z","steps":["trace[588606968] 'process raft request' (duration: 121.274456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:31:51.848353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.016906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-22T23:31:51.848783Z","caller":"traceutil/trace.go:171","msg":"trace[934110738] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"269.780954ms","start":"2026-05-22T23:31:51.578955Z","end":"2026-05-22T23:31:51.848736Z","steps":["trace[934110738] 'process raft request' (duration: 58.290986ms)","trace[934110738] 'compare' (duration: 210.877531ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:32:36.255848Z","caller":"traceutil/trace.go:171","msg":"trace[1377107124] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"100.381615ms","start":"2026-05-22T23:32:36.155436Z","end":"2026-05-22T23:32:36.255818Z","steps":["trace[1377107124] 'process raft request' (duration: 68.153209ms)","trace[1377107124] 'compare' (duration: 32.066221ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:32:42.354881Z","caller":"traceutil/trace.go:171","msg":"trace[1242231235] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"118.692177ms","start":"2026-05-22T23:32:42.236165Z","end":"2026-05-22T23:32:42.354857Z","steps":["trace[1242231235] 'process raft request' (duration: 118.526722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:32:42.576369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.557112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-22T23:32:42.576577Z","caller":"traceutil/trace.go:171","msg":"trace[56441661] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"229.331697ms","start":"2026-05-22T23:32:42.347211Z","end":"2026-05-22T23:32:42.576542Z","steps":["trace[56441661] 'process raft request' (duration: 62.491768ms)","trace[56441661] 'compare' (duration: 165.855183ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:32:42.576688Z","caller":"traceutil/trace.go:171","msg":"trace[1444980391] linearizableReadLoop","detail":"{readStateIndex:2263; appliedIndex:2262; }","duration":"221.825565ms","start":"2026-05-22T23:32:42.354818Z","end":"2026-05-22T23:32:42.576643Z","steps":["trace[1444980391] 'read index received' (duration: 54.884113ms)","trace[1444980391] 'applied index is now lower than readState.Index' (duration: 166.938592ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T23:32:42.576802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.958463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-22T23:32:42.57688Z","caller":"traceutil/trace.go:171","msg":"trace[1793707103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2180; }","duration":"230.061366ms","start":"2026-05-22T23:32:42.346802Z","end":"2026-05-22T23:32:42.576863Z","steps":["trace[1793707103] 'agreement among raft nodes before linearized reading' (duration: 229.892361ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:32:42.576997Z","caller":"traceutil/trace.go:171","msg":"trace[1203932024] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"218.063524ms","start":"2026-05-22T23:32:42.358922Z","end":"2026-05-22T23:32:42.576986Z","steps":["trace[1203932024] 'process raft request' (duration: 217.569251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:32:42.577033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.045388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-22T23:32:42.577085Z","caller":"traceutil/trace.go:171","msg":"trace[1328255043] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2181; }","duration":"264.11511ms","start":"2026-05-22T23:32:42.312953Z","end":"2026-05-22T23:32:42.577068Z","steps":["trace[1328255043] 'agreement among raft nodes before linearized reading' (duration: 263.885614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:32:42.577112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.901979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-22T23:32:42.577169Z","caller":"traceutil/trace.go:171","msg":"trace[886578664] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:1; response_revision:2181; }","duration":"176.96105ms","start":"2026-05-22T23:32:42.400193Z","end":"2026-05-22T23:32:42.577154Z","steps":["trace[886578664] 'agreement among raft nodes before linearized reading' (duration: 176.792906ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:32:42.765096Z","caller":"traceutil/trace.go:171","msg":"trace[1371748615] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"184.171184ms","start":"2026-05-22T23:32:42.580898Z","end":"2026-05-22T23:32:42.765069Z","steps":["trace[1371748615] 'process raft request' (duration: 184.064142ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:34:04.074079Z","caller":"traceutil/trace.go:171","msg":"trace[1036153798] transaction","detail":"{read_only:false; response_revision:2820; number_of_response:1; }","duration":"105.271674ms","start":"2026-05-22T23:34:03.968769Z","end":"2026-05-22T23:34:04.074041Z","steps":["trace[1036153798] 'process raft request' (duration: 104.913934ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:36:39.596214Z","caller":"traceutil/trace.go:171","msg":"trace[1198546299] transaction","detail":"{read_only:false; response_revision:3839; number_of_response:1; }","duration":"186.953246ms","start":"2026-05-22T23:36:39.409234Z","end":"2026-05-22T23:36:39.596187Z","steps":["trace[1198546299] 'process raft request' (duration: 186.794741ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:38:25.286535Z","caller":"traceutil/trace.go:171","msg":"trace[1429646187] transaction","detail":"{read_only:false; response_revision:5365; number_of_response:1; }","duration":"189.398649ms","start":"2026-05-22T23:38:25.097109Z","end":"2026-05-22T23:38:25.286507Z","steps":["trace[1429646187] 'process raft request' (duration: 101.65824ms)","trace[1429646187] 'compare' (duration: 87.432771ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:38:25.287145Z","caller":"traceutil/trace.go:171","msg":"trace[1263027030] transaction","detail":"{read_only:false; response_revision:5366; number_of_response:1; }","duration":"189.254185ms","start":"2026-05-22T23:38:25.097799Z","end":"2026-05-22T23:38:25.287053Z","steps":["trace[1263027030] 'process raft request' (duration: 188.576087ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:38:25.287934Z","caller":"traceutil/trace.go:171","msg":"trace[684545093] transaction","detail":"{read_only:false; response_revision:5367; number_of_response:1; }","duration":"189.273965ms","start":"2026-05-22T23:38:25.0984Z","end":"2026-05-22T23:38:25.287674Z","steps":["trace[684545093] 'process raft request' (duration: 188.579806ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:38:42.42663Z","caller":"traceutil/trace.go:171","msg":"trace[170984940] linearizableReadLoop","detail":"{readStateIndex:5798; appliedIndex:5796; }","duration":"107.47419ms","start":"2026-05-22T23:38:42.319125Z","end":"2026-05-22T23:38:42.426599Z","steps":["trace[170984940] 'read index received' (duration: 39.234147ms)","trace[170984940] 'applied index is now lower than readState.Index' (duration: 68.238533ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:38:42.427019Z","caller":"traceutil/trace.go:171","msg":"trace[1052013975] transaction","detail":"{read_only:false; response_revision:5593; number_of_response:1; }","duration":"111.539883ms","start":"2026-05-22T23:38:42.315455Z","end":"2026-05-22T23:38:42.426995Z","steps":["trace[1052013975] 'process raft request' (duration: 81.794087ms)","trace[1052013975] 'compare' (duration: 29.130959ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T23:38:42.427008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.866321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rbn8b\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-22T23:38:42.42722Z","caller":"traceutil/trace.go:171","msg":"trace[1786755492] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rbn8b; range_end:; response_count:1; response_revision:5593; }","duration":"108.124298ms","start":"2026-05-22T23:38:42.319069Z","end":"2026-05-22T23:38:42.427193Z","steps":["trace[1786755492] 'agreement among raft nodes before linearized reading' (duration: 107.759548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:38:47.410918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.159112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T23:38:47.411007Z","caller":"traceutil/trace.go:171","msg":"trace[272439465] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:5629; }","duration":"370.303105ms","start":"2026-05-22T23:38:47.040684Z","end":"2026-05-22T23:38:47.410987Z","steps":["trace[272439465] 'count revisions from in-memory index tree' (duration: 370.037668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:38:47.411049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:38:47.040654Z","time spent":"370.384398ms","remote":"127.0.0.1:50166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"warn","ts":"2026-05-22T23:38:47.411426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.649778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-22T23:38:47.411597Z","caller":"traceutil/trace.go:171","msg":"trace[198298497] linearizableReadLoop","detail":"{readStateIndex:5837; appliedIndex:5835; }","duration":"190.060447ms","start":"2026-05-22T23:38:47.221517Z","end":"2026-05-22T23:38:47.411577Z","steps":["trace[198298497] 'read index received' (duration: 95.822µs)","trace[198298497] 'applied index is now lower than readState.Index' (duration: 189.963755ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:38:47.411683Z","caller":"traceutil/trace.go:171","msg":"trace[91998750] transaction","detail":"{read_only:false; response_revision:5631; number_of_response:1; }","duration":"299.559814ms","start":"2026-05-22T23:38:47.112112Z","end":"2026-05-22T23:38:47.411672Z","steps":["trace[91998750] 'process raft request' (duration: 299.41663ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:38:47.411732Z","caller":"traceutil/trace.go:171","msg":"trace[2100639524] transaction","detail":"{read_only:false; response_revision:5630; number_of_response:1; }","duration":"303.927154ms","start":"2026-05-22T23:38:47.107782Z","end":"2026-05-22T23:38:47.411709Z","steps":["trace[2100639524] 'process raft request' (duration: 35.907346ms)","trace[2100639524] 'compare' (duration: 267.552315ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T23:38:47.411851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:38:47.10775Z","time spent":"304.027207ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-22T23:38:47.411905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.383717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T23:38:47.411937Z","caller":"traceutil/trace.go:171","msg":"trace[537945256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5631; }","duration":"190.447848ms","start":"2026-05-22T23:38:47.22148Z","end":"2026-05-22T23:38:47.411927Z","steps":["trace[537945256] 'agreement among raft nodes before linearized reading' (duration: 190.389446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:38:47.412163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.190972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-lpnx4\" ","response":"range_response_count:1 size:8432"} {"level":"info","ts":"2026-05-22T23:38:47.412261Z","caller":"traceutil/trace.go:171","msg":"trace[523173499] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-lpnx4; range_end:; response_count:1; response_revision:5631; }","duration":"154.336686ms","start":"2026-05-22T23:38:47.257899Z","end":"2026-05-22T23:38:47.412236Z","steps":["trace[523173499] 'agreement among raft nodes before linearized reading' (duration: 154.09312ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:38:47.595832Z","caller":"traceutil/trace.go:171","msg":"trace[1568855823] transaction","detail":"{read_only:false; response_revision:5634; number_of_response:1; }","duration":"115.186442ms","start":"2026-05-22T23:38:47.480613Z","end":"2026-05-22T23:38:47.5958Z","steps":["trace[1568855823] 'process raft request' (duration: 40.207194ms)","trace[1568855823] 'compare' (duration: 74.763112ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:38:47.595871Z","caller":"traceutil/trace.go:171","msg":"trace[81734269] transaction","detail":"{read_only:false; response_revision:5635; number_of_response:1; }","duration":"115.037218ms","start":"2026-05-22T23:38:47.480808Z","end":"2026-05-22T23:38:47.595845Z","steps":["trace[81734269] 'process raft request' (duration: 114.901914ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:38:47.595882Z","caller":"traceutil/trace.go:171","msg":"trace[810100065] transaction","detail":"{read_only:false; response_revision:5636; number_of_response:1; }","duration":"114.894124ms","start":"2026-05-22T23:38:47.480966Z","end":"2026-05-22T23:38:47.59586Z","steps":["trace[810100065] 'process raft request' (duration: 114.801522ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:38:47.596227Z","caller":"traceutil/trace.go:171","msg":"trace[14608183] transaction","detail":"{read_only:false; response_revision:5637; number_of_response:1; }","duration":"112.778457ms","start":"2026-05-22T23:38:47.483429Z","end":"2026-05-22T23:38:47.596208Z","steps":["trace[14608183] 'process raft request' (duration: 112.377145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:38:47.740814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.673333ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-22T23:38:47.741059Z","caller":"traceutil/trace.go:171","msg":"trace[1128088678] linearizableReadLoop","detail":"{readStateIndex:5844; appliedIndex:5839; }","duration":"157.632359ms","start":"2026-05-22T23:38:47.583397Z","end":"2026-05-22T23:38:47.74103Z","steps":["trace[1128088678] 'read index received' (duration: 45.961µs)","trace[1128088678] 'applied index is now lower than readState.Index' (duration: 157.584468ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:38:47.741109Z","caller":"traceutil/trace.go:171","msg":"trace[1718725978] transaction","detail":"{read_only:false; response_revision:5638; number_of_response:1; }","duration":"136.198881ms","start":"2026-05-22T23:38:47.604893Z","end":"2026-05-22T23:38:47.741092Z","steps":["trace[1718725978] 'process raft request' (duration: 136.025877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:38:47.74125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.845385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-22T23:38:47.74129Z","caller":"traceutil/trace.go:171","msg":"trace[283373823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5638; }","duration":"157.923356ms","start":"2026-05-22T23:38:47.583354Z","end":"2026-05-22T23:38:47.741278Z","steps":["trace[283373823] 'agreement among raft nodes before linearized reading' (duration: 157.760552ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:38:48.246053Z","caller":"traceutil/trace.go:171","msg":"trace[1036021562] transaction","detail":"{read_only:false; response_revision:5640; number_of_response:1; }","duration":"163.612754ms","start":"2026-05-22T23:38:48.082414Z","end":"2026-05-22T23:38:48.246026Z","steps":["trace[1036021562] 'process raft request' (duration: 163.451998ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:38:48.247137Z","caller":"traceutil/trace.go:171","msg":"trace[531827640] transaction","detail":"{read_only:false; response_revision:5641; number_of_response:1; }","duration":"164.22611ms","start":"2026-05-22T23:38:48.082884Z","end":"2026-05-22T23:38:48.24711Z","steps":["trace[531827640] 'process raft request' (duration: 164.086246ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:38:48.409932Z","caller":"traceutil/trace.go:171","msg":"trace[170305845] transaction","detail":"{read_only:false; response_revision:5642; number_of_response:1; }","duration":"157.989908ms","start":"2026-05-22T23:38:48.251898Z","end":"2026-05-22T23:38:48.409888Z","steps":["trace[170305845] 'process raft request' (duration: 133.085175ms)","trace[170305845] 'compare' (duration: 24.693587ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:39:29.510541Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2960} {"level":"info","ts":"2026-05-22T23:39:29.591964Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2960,"took":"78.934293ms","hash":3955829685,"current-db-size-bytes":57995264,"current-db-size":"58 MB","current-db-size-in-use-bytes":33701888,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-22T23:39:29.592036Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3955829685,"revision":2960,"compact-revision":-1} {"level":"info","ts":"2026-05-22T23:44:29.521712Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6013} {"level":"info","ts":"2026-05-22T23:44:29.619714Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6013,"took":"95.969519ms","hash":673605979,"current-db-size-bytes":57995264,"current-db-size":"58 MB","current-db-size-in-use-bytes":21786624,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-22T23:44:29.6198Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":673605979,"revision":6013,"compact-revision":2960} {"level":"info","ts":"2026-05-22T23:44:49.715683Z","caller":"traceutil/trace.go:171","msg":"trace[1523658914] transaction","detail":"{read_only:false; response_revision:8374; number_of_response:1; }","duration":"143.324245ms","start":"2026-05-22T23:44:49.572334Z","end":"2026-05-22T23:44:49.715658Z","steps":["trace[1523658914] 'process raft request' (duration: 143.172741ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:44:49.85182Z","caller":"traceutil/trace.go:171","msg":"trace[1131142536] linearizableReadLoop","detail":"{readStateIndex:8670; appliedIndex:8669; }","duration":"212.284451ms","start":"2026-05-22T23:44:49.639507Z","end":"2026-05-22T23:44:49.851791Z","steps":["trace[1131142536] 'read index received' (duration: 76.244593ms)","trace[1131142536] 'applied index is now lower than readState.Index' (duration: 136.038738ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:44:49.852008Z","caller":"traceutil/trace.go:171","msg":"trace[905227223] transaction","detail":"{read_only:false; response_revision:8375; number_of_response:1; }","duration":"268.719354ms","start":"2026-05-22T23:44:49.583231Z","end":"2026-05-22T23:44:49.85195Z","steps":["trace[905227223] 'process raft request' (duration: 177.380001ms)","trace[905227223] 'compare' (duration: 91.039894ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T23:44:49.852135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.61362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-22T23:44:49.85219Z","caller":"traceutil/trace.go:171","msg":"trace[1534535030] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8375; }","duration":"212.712872ms","start":"2026-05-22T23:44:49.639463Z","end":"2026-05-22T23:44:49.852176Z","steps":["trace[1534535030] 'agreement among raft nodes before linearized reading' (duration: 212.510257ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:01.117014Z","caller":"traceutil/trace.go:171","msg":"trace[256857056] transaction","detail":"{read_only:false; response_revision:8433; number_of_response:1; }","duration":"105.629312ms","start":"2026-05-22T23:45:01.011347Z","end":"2026-05-22T23:45:01.116977Z","steps":["trace[256857056] 'process raft request' (duration: 105.407026ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:03.038793Z","caller":"traceutil/trace.go:171","msg":"trace[1861078889] transaction","detail":"{read_only:false; response_revision:8442; number_of_response:1; }","duration":"174.297667ms","start":"2026-05-22T23:45:02.864468Z","end":"2026-05-22T23:45:03.038765Z","steps":["trace[1861078889] 'process raft request' (duration: 174.118532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:45:03.28896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.438021ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-22T23:45:03.289335Z","caller":"traceutil/trace.go:171","msg":"trace[1067907527] linearizableReadLoop","detail":"{readStateIndex:8742; appliedIndex:8741; }","duration":"168.639394ms","start":"2026-05-22T23:45:03.120669Z","end":"2026-05-22T23:45:03.289308Z","steps":["trace[1067907527] 'read index received' (duration: 28.722701ms)","trace[1067907527] 'applied index is now lower than readState.Index' (duration: 139.914443ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:45:03.289362Z","caller":"traceutil/trace.go:171","msg":"trace[1159146244] transaction","detail":"{read_only:false; response_revision:8443; number_of_response:1; }","duration":"404.459295ms","start":"2026-05-22T23:45:02.884858Z","end":"2026-05-22T23:45:03.289317Z","steps":["trace[1159146244] 'process raft request' (duration: 264.52049ms)","trace[1159146244] 'compare' (duration: 139.263206ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T23:45:03.289609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:45:02.884828Z","time spent":"404.636058ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-22T23:45:03.289945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.258361ms","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-05-22T23:45:03.290033Z","caller":"traceutil/trace.go:171","msg":"trace[1200424674] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8443; }","duration":"169.392255ms","start":"2026-05-22T23:45:03.120618Z","end":"2026-05-22T23:45:03.290011Z","steps":["trace[1200424674] 'agreement among raft nodes before linearized reading' (duration: 168.804628ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:03.461969Z","caller":"traceutil/trace.go:171","msg":"trace[1322221017] transaction","detail":"{read_only:false; response_revision:8444; number_of_response:1; }","duration":"167.730429ms","start":"2026-05-22T23:45:03.294207Z","end":"2026-05-22T23:45:03.461938Z","steps":["trace[1322221017] 'process raft request' (duration: 96.72849ms)","trace[1322221017] 'compare' (duration: 70.876966ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:45:04.135219Z","caller":"traceutil/trace.go:171","msg":"trace[258276754] transaction","detail":"{read_only:false; response_revision:8448; number_of_response:1; }","duration":"167.173673ms","start":"2026-05-22T23:45:03.968015Z","end":"2026-05-22T23:45:04.135189Z","steps":["trace[258276754] 'process raft request' (duration: 166.986288ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:04.139602Z","caller":"traceutil/trace.go:171","msg":"trace[191009808] transaction","detail":"{read_only:false; response_revision:8449; number_of_response:1; }","duration":"143.924193ms","start":"2026-05-22T23:45:03.99565Z","end":"2026-05-22T23:45:04.139574Z","steps":["trace[191009808] 'process raft request' (duration: 143.789929ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:31.049932Z","caller":"traceutil/trace.go:171","msg":"trace[666507359] linearizableReadLoop","detail":"{readStateIndex:8950; appliedIndex:8949; }","duration":"243.781307ms","start":"2026-05-22T23:45:30.806131Z","end":"2026-05-22T23:45:31.049913Z","steps":["trace[666507359] 'read index received' (duration: 243.54182ms)","trace[666507359] 'applied index is now lower than readState.Index' (duration: 238.807µs)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:45:31.050045Z","caller":"traceutil/trace.go:171","msg":"trace[957504103] transaction","detail":"{read_only:false; response_revision:8644; number_of_response:1; }","duration":"249.377199ms","start":"2026-05-22T23:45:30.800656Z","end":"2026-05-22T23:45:31.050033Z","steps":["trace[957504103] 'process raft request' (duration: 249.125912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:45:31.050288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.141476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-22T23:45:31.050317Z","caller":"traceutil/trace.go:171","msg":"trace[1485265812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8644; }","duration":"244.222538ms","start":"2026-05-22T23:45:30.806087Z","end":"2026-05-22T23:45:31.05031Z","steps":["trace[1485265812] 'agreement among raft nodes before linearized reading' (duration: 244.098475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:45:31.050595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.235064ms","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-22T23:45:31.050621Z","caller":"traceutil/trace.go:171","msg":"trace[2084040612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8644; }","duration":"242.280046ms","start":"2026-05-22T23:45:30.808336Z","end":"2026-05-22T23:45:31.050616Z","steps":["trace[2084040612] 'agreement among raft nodes before linearized reading' (duration: 242.211214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:45:31.050642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.511148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rbn8b\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-22T23:45:31.050707Z","caller":"traceutil/trace.go:171","msg":"trace[842439249] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rbn8b; range_end:; response_count:1; response_revision:8644; }","duration":"208.614151ms","start":"2026-05-22T23:45:30.842077Z","end":"2026-05-22T23:45:31.050691Z","steps":["trace[842439249] 'agreement among raft nodes before linearized reading' (duration: 208.435656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:45:31.051094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.407543ms","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-22T23:45:31.05113Z","caller":"traceutil/trace.go:171","msg":"trace[738543694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8644; }","duration":"244.469375ms","start":"2026-05-22T23:45:30.806651Z","end":"2026-05-22T23:45:31.05112Z","steps":["trace[738543694] 'agreement among raft nodes before linearized reading' (duration: 243.783267ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:34.4955Z","caller":"traceutil/trace.go:171","msg":"trace[425957911] transaction","detail":"{read_only:false; response_revision:8666; number_of_response:1; }","duration":"128.311318ms","start":"2026-05-22T23:45:34.367159Z","end":"2026-05-22T23:45:34.495471Z","steps":["trace[425957911] 'process raft request' (duration: 128.089672ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:44.199567Z","caller":"traceutil/trace.go:171","msg":"trace[634865910] transaction","detail":"{read_only:false; response_revision:8757; number_of_response:1; }","duration":"131.674959ms","start":"2026-05-22T23:45:44.067864Z","end":"2026-05-22T23:45:44.199539Z","steps":["trace[634865910] 'process raft request' (duration: 131.463983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:45:44.401887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.424469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-22T23:45:44.402059Z","caller":"traceutil/trace.go:171","msg":"trace[1017905038] transaction","detail":"{read_only:false; response_revision:8758; number_of_response:1; }","duration":"219.734792ms","start":"2026-05-22T23:45:44.182297Z","end":"2026-05-22T23:45:44.402032Z","steps":["trace[1017905038] 'process raft request' (duration: 115.041337ms)","trace[1017905038] 'compare' (duration: 104.204282ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:45:44.419318Z","caller":"traceutil/trace.go:171","msg":"trace[336729741] transaction","detail":"{read_only:false; response_revision:8759; number_of_response:1; }","duration":"216.705311ms","start":"2026-05-22T23:45:44.202571Z","end":"2026-05-22T23:45:44.419277Z","steps":["trace[336729741] 'process raft request' (duration: 216.508155ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:45.556772Z","caller":"traceutil/trace.go:171","msg":"trace[2069285886] transaction","detail":"{read_only:false; response_revision:8770; number_of_response:1; }","duration":"119.719154ms","start":"2026-05-22T23:45:45.437017Z","end":"2026-05-22T23:45:45.556737Z","steps":["trace[2069285886] 'process raft request' (duration: 67.076634ms)","trace[2069285886] 'compare' (duration: 52.447614ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T23:45:45.828485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.828232ms","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-05-22T23:45:45.82855Z","caller":"traceutil/trace.go:171","msg":"trace[2040254682] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8770; }","duration":"135.906554ms","start":"2026-05-22T23:45:45.69263Z","end":"2026-05-22T23:45:45.828537Z","steps":["trace[2040254682] 'range keys from in-memory index tree' (duration: 135.682858ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:51.924687Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023b0.wal"} {"level":"info","ts":"2026-05-22T23:47:19.688704Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a847c2e9c7010db6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-22T23:47:19.695019Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-22T23:47:19.695187Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-22T23:49:15.063992Z","caller":"traceutil/trace.go:171","msg":"trace[1212072074] linearizableReadLoop","detail":"{readStateIndex:10906; appliedIndex:10905; }","duration":"102.834125ms","start":"2026-05-22T23:49:14.961131Z","end":"2026-05-22T23:49:15.063965Z","steps":["trace[1212072074] 'read index received' (duration: 102.67295ms)","trace[1212072074] 'applied index is now lower than readState.Index' (duration: 160.085µs)"],"step_count":2} {"level":"warn","ts":"2026-05-22T23:49:15.064213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.069051ms","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-05-22T23:49:15.064254Z","caller":"traceutil/trace.go:171","msg":"trace[1841908656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10539; }","duration":"103.165404ms","start":"2026-05-22T23:49:14.961075Z","end":"2026-05-22T23:49:15.06424Z","steps":["trace[1841908656] 'agreement among raft nodes before linearized reading' (duration: 102.993989ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:49:15.06439Z","caller":"traceutil/trace.go:171","msg":"trace[394746906] transaction","detail":"{read_only:false; response_revision:10539; number_of_response:1; }","duration":"125.594903ms","start":"2026-05-22T23:49:14.938764Z","end":"2026-05-22T23:49:15.064359Z","steps":["trace[394746906] 'process raft request' (duration: 125.055729ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:49:29.529533Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8099} {"level":"info","ts":"2026-05-22T23:49:29.591504Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8099,"took":"59.610021ms","hash":3937869016,"current-db-size-bytes":57995264,"current-db-size":"58 MB","current-db-size-in-use-bytes":29028352,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-22T23:49:29.591563Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3937869016,"revision":8099,"compact-revision":6013} {"level":"info","ts":"2026-05-22T23:50:36.867855Z","caller":"traceutil/trace.go:171","msg":"trace[567679870] transaction","detail":"{read_only:false; response_revision:11319; number_of_response:1; }","duration":"227.492603ms","start":"2026-05-22T23:50:36.640336Z","end":"2026-05-22T23:50:36.867829Z","steps":["trace[567679870] 'process raft request' (duration: 224.194712ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:50:36.870305Z","caller":"traceutil/trace.go:171","msg":"trace[491157317] transaction","detail":"{read_only:false; response_revision:11320; number_of_response:1; }","duration":"229.346601ms","start":"2026-05-22T23:50:36.640935Z","end":"2026-05-22T23:50:36.870282Z","steps":["trace[491157317] 'process raft request' (duration: 229.246569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:50:38.34004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.937895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T23:50:38.340208Z","caller":"traceutil/trace.go:171","msg":"trace[558428476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11324; }","duration":"118.117309ms","start":"2026-05-22T23:50:38.222071Z","end":"2026-05-22T23:50:38.340189Z","steps":["trace[558428476] 'range keys from in-memory index tree' (duration: 117.865482ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:54:29.536663Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10650} {"level":"info","ts":"2026-05-22T23:54:29.627687Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10650,"took":"87.178296ms","hash":3223714654,"current-db-size-bytes":57995264,"current-db-size":"58 MB","current-db-size-in-use-bytes":27136000,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-22T23:54:29.627769Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3223714654,"revision":10650,"compact-revision":8099} {"level":"info","ts":"2026-05-22T23:56:38.693602Z","caller":"traceutil/trace.go:171","msg":"trace[1170790996] transaction","detail":"{read_only:false; response_revision:13767; number_of_response:1; }","duration":"112.381753ms","start":"2026-05-22T23:56:38.581192Z","end":"2026-05-22T23:56:38.693574Z","steps":["trace[1170790996] 'process raft request' (duration: 112.199528ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:56:41.792985Z","caller":"traceutil/trace.go:171","msg":"trace[1440157196] transaction","detail":"{read_only:false; response_revision:13783; number_of_response:1; }","duration":"116.018981ms","start":"2026-05-22T23:56:41.676942Z","end":"2026-05-22T23:56:41.792961Z","steps":["trace[1440157196] 'process raft request' (duration: 115.854357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:57:01.25435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.439998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-22T23:57:01.25443Z","caller":"traceutil/trace.go:171","msg":"trace[1091030252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13883; }","duration":"416.577122ms","start":"2026-05-22T23:57:00.837832Z","end":"2026-05-22T23:57:01.254409Z","steps":["trace[1091030252] 'range keys from in-memory index tree' (duration: 416.290765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:57:01.254462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:57:00.837809Z","time spent":"416.646583ms","remote":"127.0.0.1:51070","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-22T23:57:01.254598Z","caller":"traceutil/trace.go:171","msg":"trace[1114954248] linearizableReadLoop","detail":"{readStateIndex:14362; appliedIndex:14361; }","duration":"365.755167ms","start":"2026-05-22T23:57:00.888814Z","end":"2026-05-22T23:57:01.254569Z","steps":["trace[1114954248] 'read index received' (duration: 363.473945ms)","trace[1114954248] 'applied index is now lower than readState.Index' (duration: 2.279922ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:57:01.254723Z","caller":"traceutil/trace.go:171","msg":"trace[442600353] transaction","detail":"{read_only:false; response_revision:13884; number_of_response:1; }","duration":"371.563605ms","start":"2026-05-22T23:57:00.883149Z","end":"2026-05-22T23:57:01.254712Z","steps":["trace[442600353] 'process raft request' (duration: 369.1735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:57:01.25477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.721786ms","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-22T23:57:01.254801Z","caller":"traceutil/trace.go:171","msg":"trace[430477403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13883; }","duration":"416.793877ms","start":"2026-05-22T23:57:00.838Z","end":"2026-05-22T23:57:01.254794Z","steps":["trace[430477403] 'range keys from in-memory index tree' (duration: 416.619623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:57:01.254824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:57:00.837975Z","time spent":"416.844709ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-22T23:57:01.254836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:57:00.883131Z","time spent":"371.628538ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-22T23:57:01.254912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.459753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7911"} {"level":"info","ts":"2026-05-22T23:57:01.254977Z","caller":"traceutil/trace.go:171","msg":"trace[564996638] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:13884; }","duration":"320.568536ms","start":"2026-05-22T23:57:00.934394Z","end":"2026-05-22T23:57:01.254963Z","steps":["trace[564996638] 'agreement among raft nodes before linearized reading' (duration: 320.421492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:57:01.25501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:57:00.934378Z","time spent":"320.625737ms","remote":"127.0.0.1:48794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7934,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-05-22T23:57:01.254925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.105396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-22T23:57:01.255398Z","caller":"traceutil/trace.go:171","msg":"trace[1176757229] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:13884; }","duration":"366.573959ms","start":"2026-05-22T23:57:00.888761Z","end":"2026-05-22T23:57:01.255335Z","steps":["trace[1176757229] 'agreement among raft nodes before linearized reading' (duration: 366.099196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:57:01.255447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:57:00.888741Z","time spent":"366.691303ms","remote":"127.0.0.1:46204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":6,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"warn","ts":"2026-05-22T23:57:16.321033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.193925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-22T23:57:16.321168Z","caller":"traceutil/trace.go:171","msg":"trace[698872600] linearizableReadLoop","detail":"{readStateIndex:14461; appliedIndex:14460; }","duration":"101.287689ms","start":"2026-05-22T23:57:16.219861Z","end":"2026-05-22T23:57:16.321149Z","steps":["trace[698872600] 'read index received' (duration: 33.27µs)","trace[698872600] 'applied index is now lower than readState.Index' (duration: 101.252859ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T23:57:16.321252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.389593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T23:57:16.321285Z","caller":"traceutil/trace.go:171","msg":"trace[1818898092] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13979; }","duration":"101.445724ms","start":"2026-05-22T23:57:16.21983Z","end":"2026-05-22T23:57:16.321275Z","steps":["trace[1818898092] 'agreement among raft nodes before linearized reading' (duration: 101.368952ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:57:16.321247Z","caller":"traceutil/trace.go:171","msg":"trace[1993741314] transaction","detail":"{read_only:false; response_revision:13979; number_of_response:1; }","duration":"202.288312ms","start":"2026-05-22T23:57:16.118904Z","end":"2026-05-22T23:57:16.321193Z","steps":["trace[1993741314] 'process raft request' (duration: 30.83379ms)","trace[1993741314] 'compare' (duration: 171.02236ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:57:17.780389Z","caller":"traceutil/trace.go:171","msg":"trace[1331928395] transaction","detail":"{read_only:false; response_revision:13986; number_of_response:1; }","duration":"419.577044ms","start":"2026-05-22T23:57:17.360782Z","end":"2026-05-22T23:57:17.780359Z","steps":["trace[1331928395] 'process raft request' (duration: 352.811134ms)","trace[1331928395] 'compare' (duration: 66.624626ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T23:57:17.780589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:57:17.360766Z","time spent":"419.705808ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-22T23:57:17.802786Z","caller":"traceutil/trace.go:171","msg":"trace[285620412] linearizableReadLoop","detail":"{readStateIndex:14469; appliedIndex:14467; }","duration":"208.010208ms","start":"2026-05-22T23:57:17.59475Z","end":"2026-05-22T23:57:17.80276Z","steps":["trace[285620412] 'read index received' (duration: 118.857929ms)","trace[285620412] 'applied index is now lower than readState.Index' (duration: 89.151289ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T23:57:17.802956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.200043ms","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-05-22T23:57:17.802933Z","caller":"traceutil/trace.go:171","msg":"trace[1296488189] transaction","detail":"{read_only:false; response_revision:13987; number_of_response:1; }","duration":"441.82181ms","start":"2026-05-22T23:57:17.361063Z","end":"2026-05-22T23:57:17.802885Z","steps":["trace[1296488189] 'process raft request' (duration: 441.603425ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:57:17.802994Z","caller":"traceutil/trace.go:171","msg":"trace[1489820661] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13987; }","duration":"208.281875ms","start":"2026-05-22T23:57:17.594698Z","end":"2026-05-22T23:57:17.80298Z","steps":["trace[1489820661] 'agreement among raft nodes before linearized reading' (duration: 208.144321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:57:17.803098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:57:17.361042Z","time spent":"441.971594ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-22T23:58:34.463307Z","caller":"traceutil/trace.go:171","msg":"trace[1285273629] linearizableReadLoop","detail":"{readStateIndex:15597; appliedIndex:15596; }","duration":"123.442803ms","start":"2026-05-22T23:58:34.339838Z","end":"2026-05-22T23:58:34.463281Z","steps":["trace[1285273629] 'read index received' (duration: 123.235898ms)","trace[1285273629] 'applied index is now lower than readState.Index' (duration: 205.995µs)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:58:34.463368Z","caller":"traceutil/trace.go:171","msg":"trace[1628993766] transaction","detail":"{read_only:false; response_revision:15079; number_of_response:1; }","duration":"132.304345ms","start":"2026-05-22T23:58:34.33103Z","end":"2026-05-22T23:58:34.463335Z","steps":["trace[1628993766] 'process raft request' (duration: 132.064598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:58:34.463556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.69415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-zt4nh\" ","response":"range_response_count:1 size:9790"} {"level":"info","ts":"2026-05-22T23:58:34.463617Z","caller":"traceutil/trace.go:171","msg":"trace[413350789] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-zt4nh; range_end:; response_count:1; response_revision:15079; }","duration":"123.776032ms","start":"2026-05-22T23:58:34.339832Z","end":"2026-05-22T23:58:34.463608Z","steps":["trace[413350789] 'agreement among raft nodes before linearized reading' (duration: 123.590397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:58:34.463667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.441355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-22T23:58:34.463771Z","caller":"traceutil/trace.go:171","msg":"trace[906577896] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:15079; }","duration":"119.587029ms","start":"2026-05-22T23:58:34.344164Z","end":"2026-05-22T23:58:34.463751Z","steps":["trace[906577896] 'agreement among raft nodes before linearized reading' (duration: 119.404614ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:59:08.546064Z","caller":"traceutil/trace.go:171","msg":"trace[751870552] transaction","detail":"{read_only:false; response_revision:15254; number_of_response:1; }","duration":"143.49471ms","start":"2026-05-22T23:59:08.402548Z","end":"2026-05-22T23:59:08.546042Z","steps":["trace[751870552] 'process raft request' (duration: 143.353657ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:59:08.546638Z","caller":"traceutil/trace.go:171","msg":"trace[1458410508] linearizableReadLoop","detail":"{readStateIndex:15780; appliedIndex:15778; }","duration":"140.988983ms","start":"2026-05-22T23:59:08.405633Z","end":"2026-05-22T23:59:08.546622Z","steps":["trace[1458410508] 'read index received' (duration: 140.215131ms)","trace[1458410508] 'applied index is now lower than readState.Index' (duration: 773.122µs)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:59:08.546822Z","caller":"traceutil/trace.go:171","msg":"trace[426789629] transaction","detail":"{read_only:false; response_revision:15255; number_of_response:1; }","duration":"141.884137ms","start":"2026-05-22T23:59:08.4049Z","end":"2026-05-22T23:59:08.546784Z","steps":["trace[426789629] 'process raft request' (duration: 141.637731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:59:08.546864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.212419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-22T23:59:08.546977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.203691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1849"} {"level":"info","ts":"2026-05-22T23:59:08.547025Z","caller":"traceutil/trace.go:171","msg":"trace[1037913147] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:15255; }","duration":"129.273662ms","start":"2026-05-22T23:59:08.417738Z","end":"2026-05-22T23:59:08.547012Z","steps":["trace[1037913147] 'agreement among raft nodes before linearized reading' (duration: 129.136669ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:59:08.546989Z","caller":"traceutil/trace.go:171","msg":"trace[1348951336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15255; }","duration":"141.360713ms","start":"2026-05-22T23:59:08.405612Z","end":"2026-05-22T23:59:08.546973Z","steps":["trace[1348951336] 'agreement among raft nodes before linearized reading' (duration: 141.131007ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:59:29.542915Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12820} {"level":"info","ts":"2026-05-22T23:59:29.618224Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12820,"took":"72.34428ms","hash":1824050895,"current-db-size-bytes":57995264,"current-db-size":"58 MB","current-db-size-in-use-bytes":30457856,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-22T23:59:29.618294Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1824050895,"revision":12820,"compact-revision":10650} {"level":"info","ts":"2026-05-23T00:04:29.549415Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15362} {"level":"info","ts":"2026-05-23T00:04:29.646099Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15362,"took":"94.040875ms","hash":3877457180,"current-db-size-bytes":57995264,"current-db-size":"58 MB","current-db-size-in-use-bytes":32235520,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-23T00:04:29.64619Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3877457180,"revision":15362,"compact-revision":12820} {"level":"info","ts":"2026-05-23T00:05:03.739802Z","caller":"traceutil/trace.go:171","msg":"trace[750823393] transaction","detail":"{read_only:false; response_revision:18010; number_of_response:1; }","duration":"102.542324ms","start":"2026-05-23T00:05:03.63723Z","end":"2026-05-23T00:05:03.739772Z","steps":["trace[750823393] 'process raft request' (duration: 102.344168ms)"],"step_count":1} {"level":"info","ts":"2026-05-23T00:09:08.482569Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a847c2e9c7010db6","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-23T00:09:08.488996Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-23T00:09:08.489117Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-23T00:09:29.556229Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17668} {"level":"info","ts":"2026-05-23T00:09:29.649843Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17668,"took":"90.442427ms","hash":410191223,"current-db-size-bytes":57995264,"current-db-size":"58 MB","current-db-size-in-use-bytes":28581888,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-23T00:09:29.649915Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":410191223,"revision":17668,"compact-revision":15362}