{"level":"warn","ts":"2026-10-14T15:48:01.492147Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T15:48:01.492247Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.166: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://162.253.55.166:2380","--initial-cluster=instance=https://162.253.55.166:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.166:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.166: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-10-14T15:48:01.492331Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T15:48:01.492348Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.166:2380"]} {"level":"info","ts":"2026-10-14T15:48:01.492381Z","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-10-14T15:48:01.49331Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.166:2379"]} {"level":"info","ts":"2026-10-14T15:48:01.493425Z","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://162.253.55.166:2380"],"listen-peer-urls":["https://162.253.55.166:2380"],"advertise-client-urls":["https://162.253.55.166:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.166:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.166: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-10-14T15:48:01.500419Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.723251ms"} {"level":"info","ts":"2026-10-14T15:48:01.510589Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b7481851e1bcdf3c","cluster-id":"bb367d182eb83f54"} {"level":"info","ts":"2026-10-14T15:48:01.510675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T15:48:01.510702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became follower at term 0"} {"level":"info","ts":"2026-10-14T15:48:01.510717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b7481851e1bcdf3c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T15:48:01.510732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became follower at term 1"} {"level":"info","ts":"2026-10-14T15:48:01.510769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c switched to configuration voters=(13206832647222648636)"} {"level":"warn","ts":"2026-10-14T15:48:01.515904Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T15:48:01.518092Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T15:48:01.519763Z","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-10-14T15:48:01.522637Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b7481851e1bcdf3c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T15:48:01.52287Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b7481851e1bcdf3c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T15:48:01.522944Z","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-10-14T15:48:01.523065Z","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-10-14T15:48:01.523102Z","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-10-14T15:48:01.524435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c switched to configuration voters=(13206832647222648636)"} {"level":"info","ts":"2026-10-14T15:48:01.524648Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bb367d182eb83f54","local-member-id":"b7481851e1bcdf3c","added-peer-id":"b7481851e1bcdf3c","added-peer-peer-urls":["https://162.253.55.166:2380"]} {"level":"info","ts":"2026-10-14T15:48:01.527696Z","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-10-14T15:48:01.527784Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.166:2380"} {"level":"info","ts":"2026-10-14T15:48:01.527832Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.166:2380"} {"level":"info","ts":"2026-10-14T15:48:01.528026Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b7481851e1bcdf3c","initial-advertise-peer-urls":["https://162.253.55.166:2380"],"listen-peer-urls":["https://162.253.55.166:2380"],"advertise-client-urls":["https://162.253.55.166:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.166:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T15:48:01.528073Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T15:48:01.811703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T15:48:01.811762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T15:48:01.811798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c received MsgPreVoteResp from b7481851e1bcdf3c at term 1"} {"level":"info","ts":"2026-10-14T15:48:01.811816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became candidate at term 2"} {"level":"info","ts":"2026-10-14T15:48:01.811834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c received MsgVoteResp from b7481851e1bcdf3c at term 2"} {"level":"info","ts":"2026-10-14T15:48:01.811851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became leader at term 2"} {"level":"info","ts":"2026-10-14T15:48:01.811869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b7481851e1bcdf3c elected leader b7481851e1bcdf3c at term 2"} {"level":"info","ts":"2026-10-14T15:48:01.815632Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b7481851e1bcdf3c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.166:2379]}","request-path":"/0/members/b7481851e1bcdf3c/attributes","cluster-id":"bb367d182eb83f54","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T15:48:01.8162Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T15:48:01.816837Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:48:01.817075Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T15:48:01.817707Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T15:48:01.817743Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T15:48:01.818135Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bb367d182eb83f54","local-member-id":"b7481851e1bcdf3c","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:48:01.818257Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:48:01.818303Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:48:01.819104Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T15:48:01.819365Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.166:2379"} {"level":"info","ts":"2026-10-14T15:48:29.846065Z","caller":"traceutil/trace.go:171","msg":"trace[142265830] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"181.659553ms","start":"2026-10-14T15:48:29.664378Z","end":"2026-10-14T15:48:29.846037Z","steps":["trace[142265830] 'process raft request' (duration: 181.54575ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:48:33.986209Z","caller":"traceutil/trace.go:171","msg":"trace[71671868] linearizableReadLoop","detail":"{readStateIndex:455; appliedIndex:454; }","duration":"110.699869ms","start":"2026-10-14T15:48:33.875486Z","end":"2026-10-14T15:48:33.986186Z","steps":["trace[71671868] 'read index received' (duration: 110.476323ms)","trace[71671868] 'applied index is now lower than readState.Index' (duration: 222.596µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:48:33.986289Z","caller":"traceutil/trace.go:171","msg":"trace[1583007389] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"121.756895ms","start":"2026-10-14T15:48:33.864516Z","end":"2026-10-14T15:48:33.986273Z","steps":["trace[1583007389] 'process raft request' (duration: 121.520939ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:48:33.986379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.871473ms","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-10-14T15:48:33.986436Z","caller":"traceutil/trace.go:171","msg":"trace[1094698472] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:438; }","duration":"111.029178ms","start":"2026-10-14T15:48:33.875395Z","end":"2026-10-14T15:48:33.986424Z","steps":["trace[1094698472] 'agreement among raft nodes before linearized reading' (duration: 110.889184ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:48:47.895729Z","caller":"traceutil/trace.go:171","msg":"trace[1033440466] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"122.99868ms","start":"2026-10-14T15:48:47.772698Z","end":"2026-10-14T15:48:47.895697Z","steps":["trace[1033440466] 'process raft request' (duration: 122.933329ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:48:47.895763Z","caller":"traceutil/trace.go:171","msg":"trace[644084365] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"147.051002ms","start":"2026-10-14T15:48:47.748673Z","end":"2026-10-14T15:48:47.895724Z","steps":["trace[644084365] 'process raft request' (duration: 146.712023ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:48:49.324724Z","caller":"traceutil/trace.go:171","msg":"trace[479876050] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"159.218397ms","start":"2026-10-14T15:48:49.165492Z","end":"2026-10-14T15:48:49.324711Z","steps":["trace[479876050] 'process raft request' (duration: 155.30099ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:48:49.324981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.878097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:65214"} {"level":"info","ts":"2026-10-14T15:48:49.324642Z","caller":"traceutil/trace.go:171","msg":"trace[782065821] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"151.543247ms","start":"2026-10-14T15:48:49.173075Z","end":"2026-10-14T15:48:49.324619Z","steps":["trace[782065821] 'read index received' (duration: 147.65159ms)","trace[782065821] 'applied index is now lower than readState.Index' (duration: 3.890657ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:48:49.32506Z","caller":"traceutil/trace.go:171","msg":"trace[1322985597] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:617; }","duration":"151.991079ms","start":"2026-10-14T15:48:49.173049Z","end":"2026-10-14T15:48:49.32504Z","steps":["trace[1322985597] 'agreement among raft nodes before linearized reading' (duration: 151.757242ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:48:51.079354Z","caller":"traceutil/trace.go:171","msg":"trace[281463177] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"107.684008ms","start":"2026-10-14T15:48:50.97161Z","end":"2026-10-14T15:48:51.079294Z","steps":["trace[281463177] 'process raft request' (duration: 107.455322ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:48:57.846672Z","caller":"traceutil/trace.go:171","msg":"trace[2009871066] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"114.407863ms","start":"2026-10-14T15:48:57.732239Z","end":"2026-10-14T15:48:57.846647Z","steps":["trace[2009871066] 'read index received' (duration: 114.283959ms)","trace[2009871066] 'applied index is now lower than readState.Index' (duration: 123.214µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:48:57.846869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.620529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cjhnn\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-14T15:48:57.846822Z","caller":"traceutil/trace.go:171","msg":"trace[265792181] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"241.265031ms","start":"2026-10-14T15:48:57.605498Z","end":"2026-10-14T15:48:57.846764Z","steps":["trace[265792181] 'process raft request' (duration: 241.044314ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:48:57.846912Z","caller":"traceutil/trace.go:171","msg":"trace[286670643] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cjhnn; range_end:; response_count:1; response_revision:680; }","duration":"114.707552ms","start":"2026-10-14T15:48:57.732193Z","end":"2026-10-14T15:48:57.8469Z","steps":["trace[286670643] 'agreement among raft nodes before linearized reading' (duration: 114.540896ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:48:58.266165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.571158ms","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-10-14T15:48:58.266274Z","caller":"traceutil/trace.go:171","msg":"trace[489226488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:681; }","duration":"138.731123ms","start":"2026-10-14T15:48:58.127516Z","end":"2026-10-14T15:48:58.266247Z","steps":["trace[489226488] 'range keys from in-memory index tree' (duration: 138.352323ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:48:58.976064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.735369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:48:58.976279Z","caller":"traceutil/trace.go:171","msg":"trace[1019756762] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:708; }","duration":"263.733729ms","start":"2026-10-14T15:48:58.712526Z","end":"2026-10-14T15:48:58.97626Z","steps":["trace[1019756762] 'read index received' (duration: 14.89379ms)","trace[1019756762] 'applied index is now lower than readState.Index' (duration: 248.838819ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:48:58.976356Z","caller":"traceutil/trace.go:171","msg":"trace[1358157050] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"662.13581ms","start":"2026-10-14T15:48:58.314144Z","end":"2026-10-14T15:48:58.976279Z","steps":["trace[1358157050] 'process raft request' (duration: 348.490516ms)","trace[1358157050] 'compare' (duration: 312.576544ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:48:58.976546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:48:58.314119Z","time spent":"662.330636ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:48:58.97655Z","caller":"traceutil/trace.go:171","msg":"trace[505998192] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"491.011324ms","start":"2026-10-14T15:48:58.485452Z","end":"2026-10-14T15:48:58.976463Z","steps":["trace[505998192] 'process raft request' (duration: 490.727576ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:48:58.976474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.938875ms","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-10-14T15:48:58.976705Z","caller":"traceutil/trace.go:171","msg":"trace[1725431197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:685; }","duration":"264.186901ms","start":"2026-10-14T15:48:58.712498Z","end":"2026-10-14T15:48:58.976685Z","steps":["trace[1725431197] 'agreement among raft nodes before linearized reading' (duration: 263.852252ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:48:58.976739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:48:58.485422Z","time spent":"491.23107ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:49:15.966099Z","caller":"traceutil/trace.go:171","msg":"trace[1290629601] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"123.254167ms","start":"2026-10-14T15:49:15.842822Z","end":"2026-10-14T15:49:15.966076Z","steps":["trace[1290629601] 'read index received' (duration: 123.109443ms)","trace[1290629601] 'applied index is now lower than readState.Index' (duration: 143.584µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:49:15.966377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.535956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4s9jr\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-10-14T15:49:15.966442Z","caller":"traceutil/trace.go:171","msg":"trace[2078070957] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4s9jr; range_end:; response_count:1; response_revision:799; }","duration":"123.65177ms","start":"2026-10-14T15:49:15.84277Z","end":"2026-10-14T15:49:15.966421Z","steps":["trace[2078070957] 'agreement among raft nodes before linearized reading' (duration: 123.424423ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:15.966303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:49:15.605338Z","time spent":"360.960719ms","remote":"127.0.0.1:38296","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-14T15:49:35.871109Z","caller":"traceutil/trace.go:171","msg":"trace[1113533900] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"108.748037ms","start":"2026-10-14T15:49:35.76229Z","end":"2026-10-14T15:49:35.871038Z","steps":["trace[1113533900] 'process raft request' (duration: 108.423398ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:48.579661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.466923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:49:48.579792Z","caller":"traceutil/trace.go:171","msg":"trace[1897514828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1213; }","duration":"254.662568ms","start":"2026-10-14T15:49:48.325106Z","end":"2026-10-14T15:49:48.579769Z","steps":["trace[1897514828] 'range keys from in-memory index tree' (duration: 254.255897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:50:35.079022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.657265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-10-14T15:50:35.079117Z","caller":"traceutil/trace.go:171","msg":"trace[1434745749] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1863; }","duration":"332.818689ms","start":"2026-10-14T15:50:34.746279Z","end":"2026-10-14T15:50:35.079098Z","steps":["trace[1434745749] 'range keys from in-memory index tree' (duration: 332.452699ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:50:35.079133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.057271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-14T15:50:35.07916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:50:34.746257Z","time spent":"332.891501ms","remote":"127.0.0.1:38376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-10-14T15:50:35.079165Z","caller":"traceutil/trace.go:171","msg":"trace[1179453466] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1863; }","duration":"201.133373ms","start":"2026-10-14T15:50:34.878023Z","end":"2026-10-14T15:50:35.079156Z","steps":["trace[1179453466] 'range keys from in-memory index tree' (duration: 200.891866ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:50:35.079371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.693444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8db7d484-62d3-40b7-88f4-d789923b9c34\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-10-14T15:50:35.079361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.401762ms","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-10-14T15:50:35.079394Z","caller":"traceutil/trace.go:171","msg":"trace[304459520] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8db7d484-62d3-40b7-88f4-d789923b9c34; range_end:; response_count:1; response_revision:1863; }","duration":"238.774487ms","start":"2026-10-14T15:50:34.840612Z","end":"2026-10-14T15:50:35.079387Z","steps":["trace[304459520] 'range keys from in-memory index tree' (duration: 238.512609ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:50:35.07942Z","caller":"traceutil/trace.go:171","msg":"trace[33106145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1863; }","duration":"200.498885ms","start":"2026-10-14T15:50:34.878905Z","end":"2026-10-14T15:50:35.079403Z","steps":["trace[33106145] 'range keys from in-memory index tree' (duration: 200.237668ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:50:35.079542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.212179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-10-14T15:50:35.079669Z","caller":"traceutil/trace.go:171","msg":"trace[679246744] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1863; }","duration":"168.384353ms","start":"2026-10-14T15:50:34.911255Z","end":"2026-10-14T15:50:35.079639Z","steps":["trace[679246744] 'range keys from in-memory index tree' (duration: 167.988323ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:50:35.250201Z","caller":"traceutil/trace.go:171","msg":"trace[1543737725] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"166.695586ms","start":"2026-10-14T15:50:35.083484Z","end":"2026-10-14T15:50:35.250179Z","steps":["trace[1543737725] 'process raft request' (duration: 166.583363ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:50:35.333248Z","caller":"traceutil/trace.go:171","msg":"trace[2059477374] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"249.659364ms","start":"2026-10-14T15:50:35.083555Z","end":"2026-10-14T15:50:35.333215Z","steps":["trace[2059477374] 'process raft request' (duration: 198.977903ms)","trace[2059477374] 'compare' (duration: 50.469746ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:50:35.33337Z","caller":"traceutil/trace.go:171","msg":"trace[1665694956] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"247.28702ms","start":"2026-10-14T15:50:35.086062Z","end":"2026-10-14T15:50:35.333349Z","steps":["trace[1665694956] 'process raft request' (duration: 247.068244ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:50:35.333516Z","caller":"traceutil/trace.go:171","msg":"trace[1383907053] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"246.514239ms","start":"2026-10-14T15:50:35.086975Z","end":"2026-10-14T15:50:35.333489Z","steps":["trace[1383907053] 'process raft request' (duration: 246.227991ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:50:37.660146Z","caller":"traceutil/trace.go:171","msg":"trace[474035434] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"107.491831ms","start":"2026-10-14T15:50:37.552608Z","end":"2026-10-14T15:50:37.6601Z","steps":["trace[474035434] 'process raft request' (duration: 96.317034ms)","trace[474035434] 'compare' (duration: 10.89396ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:50:43.700108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.365673ms","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-10-14T15:50:43.70023Z","caller":"traceutil/trace.go:171","msg":"trace[1017631454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1969; }","duration":"105.544459ms","start":"2026-10-14T15:50:43.594658Z","end":"2026-10-14T15:50:43.700202Z","steps":["trace[1017631454] 'range keys from in-memory index tree' (duration: 105.174278ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:52:40.998837Z","caller":"traceutil/trace.go:171","msg":"trace[400151213] transaction","detail":"{read_only:false; response_revision:2818; number_of_response:1; }","duration":"226.950289ms","start":"2026-10-14T15:52:40.771852Z","end":"2026-10-14T15:52:40.998803Z","steps":["trace[400151213] 'process raft request' (duration: 226.735033ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:52:41.12825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.840872ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-14T15:52:41.128396Z","caller":"traceutil/trace.go:171","msg":"trace[1138462] linearizableReadLoop","detail":"{readStateIndex:2929; appliedIndex:2927; }","duration":"216.421061ms","start":"2026-10-14T15:52:40.911955Z","end":"2026-10-14T15:52:41.128376Z","steps":["trace[1138462] 'read index received' (duration: 86.763872ms)","trace[1138462] 'applied index is now lower than readState.Index' (duration: 129.655239ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:52:41.128529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.567174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:52:41.128566Z","caller":"traceutil/trace.go:171","msg":"trace[1656036818] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2818; }","duration":"216.662867ms","start":"2026-10-14T15:52:40.911891Z","end":"2026-10-14T15:52:41.128554Z","steps":["trace[1656036818] 'agreement among raft nodes before linearized reading' (duration: 216.585085ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:52:41.128656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.246151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-10-14T15:52:41.128742Z","caller":"traceutil/trace.go:171","msg":"trace[78790727] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2818; }","duration":"171.407315ms","start":"2026-10-14T15:52:40.957315Z","end":"2026-10-14T15:52:41.128722Z","steps":["trace[78790727] 'agreement among raft nodes before linearized reading' (duration: 171.179479ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:52:41.128984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.516417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-10-14T15:52:41.129095Z","caller":"traceutil/trace.go:171","msg":"trace[37500179] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2818; }","duration":"128.681012ms","start":"2026-10-14T15:52:41.000394Z","end":"2026-10-14T15:52:41.129075Z","steps":["trace[37500179] 'agreement among raft nodes before linearized reading' (duration: 128.367603ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:53:30.812725Z","caller":"traceutil/trace.go:171","msg":"trace[1754731607] linearizableReadLoop","detail":"{readStateIndex:3397; appliedIndex:3395; }","duration":"110.901634ms","start":"2026-10-14T15:53:30.701795Z","end":"2026-10-14T15:53:30.812697Z","steps":["trace[1754731607] 'read index received' (duration: 897.305µs)","trace[1754731607] 'applied index is now lower than readState.Index' (duration: 110.003059ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:53:30.812887Z","caller":"traceutil/trace.go:171","msg":"trace[733347882] transaction","detail":"{read_only:false; response_revision:3264; number_of_response:1; }","duration":"110.341728ms","start":"2026-10-14T15:53:30.702532Z","end":"2026-10-14T15:53:30.812874Z","steps":["trace[733347882] 'process raft request' (duration: 110.094092ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:53:30.812929Z","caller":"traceutil/trace.go:171","msg":"trace[1640210987] transaction","detail":"{read_only:false; response_revision:3263; number_of_response:1; }","duration":"111.812659ms","start":"2026-10-14T15:53:30.701068Z","end":"2026-10-14T15:53:30.812881Z","steps":["trace[1640210987] 'process raft request' (duration: 89.879377ms)","trace[1640210987] 'compare' (duration: 21.51913ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:53:30.813075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.247383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/passwords.secretgen.k14s.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:53:30.813277Z","caller":"traceutil/trace.go:171","msg":"trace[195462995] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/passwords.secretgen.k14s.io; range_end:; response_count:0; response_revision:3264; }","duration":"111.502419ms","start":"2026-10-14T15:53:30.701739Z","end":"2026-10-14T15:53:30.813241Z","steps":["trace[195462995] 'agreement among raft nodes before linearized reading' (duration: 111.233672ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:23.797453Z","caller":"traceutil/trace.go:171","msg":"trace[2089499156] linearizableReadLoop","detail":"{readStateIndex:4830; appliedIndex:4829; }","duration":"137.337179ms","start":"2026-10-14T15:55:23.660093Z","end":"2026-10-14T15:55:23.79743Z","steps":["trace[2089499156] 'read index received' (duration: 108.454227ms)","trace[2089499156] 'applied index is now lower than readState.Index' (duration: 28.881942ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:55:23.797613Z","caller":"traceutil/trace.go:171","msg":"trace[1416866267] transaction","detail":"{read_only:false; response_revision:4628; number_of_response:1; }","duration":"138.14113ms","start":"2026-10-14T15:55:23.659381Z","end":"2026-10-14T15:55:23.797522Z","steps":["trace[1416866267] 'process raft request' (duration: 109.210637ms)","trace[1416866267] 'compare' (duration: 28.686167ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:55:23.797967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.849212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7465"} {"level":"info","ts":"2026-10-14T15:55:23.798095Z","caller":"traceutil/trace.go:171","msg":"trace[1095554119] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4628; }","duration":"137.988847ms","start":"2026-10-14T15:55:23.660063Z","end":"2026-10-14T15:55:23.798052Z","steps":["trace[1095554119] 'agreement among raft nodes before linearized reading' (duration: 137.526854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:28.09411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.363483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T15:55:28.094121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.514863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T15:55:28.094263Z","caller":"traceutil/trace.go:171","msg":"trace[726895532] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:4790; }","duration":"245.549798ms","start":"2026-10-14T15:55:27.848686Z","end":"2026-10-14T15:55:28.094236Z","steps":["trace[726895532] 'count revisions from in-memory index tree' (duration: 245.254471ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:28.094288Z","caller":"traceutil/trace.go:171","msg":"trace[1111815427] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:4790; }","duration":"184.76411ms","start":"2026-10-14T15:55:27.909496Z","end":"2026-10-14T15:55:28.09426Z","steps":["trace[1111815427] 'count revisions from in-memory index tree' (duration: 184.339239ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:28.094529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.526941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5fcf9b09-fb88-491f-b20f-b9d490bef8b0\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-10-14T15:55:28.094544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.952392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5fcf9b09-fb88-491f-b20f-b9d490bef8b0\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-14T15:55:28.094624Z","caller":"traceutil/trace.go:171","msg":"trace[501440755] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5fcf9b09-fb88-491f-b20f-b9d490bef8b0; range_end:; response_count:1; response_revision:4790; }","duration":"163.074935ms","start":"2026-10-14T15:55:27.931525Z","end":"2026-10-14T15:55:28.0946Z","steps":["trace[501440755] 'range keys from in-memory index tree' (duration: 162.670144ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:28.094613Z","caller":"traceutil/trace.go:171","msg":"trace[1696253759] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5fcf9b09-fb88-491f-b20f-b9d490bef8b0; range_end:; response_count:1; response_revision:4790; }","duration":"228.658286ms","start":"2026-10-14T15:55:27.865932Z","end":"2026-10-14T15:55:28.09459Z","steps":["trace[1696253759] 'range keys from in-memory index tree' (duration: 228.321286ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:35.554955Z","caller":"traceutil/trace.go:171","msg":"trace[1257042059] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"161.179064ms","start":"2026-10-14T15:55:35.393748Z","end":"2026-10-14T15:55:35.554927Z","steps":["trace[1257042059] 'process raft request' (duration: 119.666615ms)","trace[1257042059] 'compare' (duration: 41.053707ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:55:35.734598Z","caller":"traceutil/trace.go:171","msg":"trace[1532928519] linearizableReadLoop","detail":"{readStateIndex:5162; appliedIndex:5161; }","duration":"134.691656ms","start":"2026-10-14T15:55:35.59988Z","end":"2026-10-14T15:55:35.734572Z","steps":["trace[1532928519] 'read index received' (duration: 127.034956ms)","trace[1532928519] 'applied index is now lower than readState.Index' (duration: 7.65511ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:55:35.734639Z","caller":"traceutil/trace.go:171","msg":"trace[176957420] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"148.634549ms","start":"2026-10-14T15:55:35.585977Z","end":"2026-10-14T15:55:35.734611Z","steps":["trace[176957420] 'process raft request' (duration: 141.04814ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:35.734965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.049746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-10-14T15:55:35.735084Z","caller":"traceutil/trace.go:171","msg":"trace[486346364] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4947; }","duration":"135.199309ms","start":"2026-10-14T15:55:35.599853Z","end":"2026-10-14T15:55:35.735053Z","steps":["trace[486346364] 'agreement among raft nodes before linearized reading' (duration: 134.84657ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:35.735525Z","caller":"traceutil/trace.go:171","msg":"trace[1636633111] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"134.026558ms","start":"2026-10-14T15:55:35.60147Z","end":"2026-10-14T15:55:35.735497Z","steps":["trace[1636633111] 'process raft request' (duration: 133.689199ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:35.735648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.077688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.166\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-14T15:55:35.735709Z","caller":"traceutil/trace.go:171","msg":"trace[1314813055] range","detail":"{range_begin:/registry/masterleases/162.253.55.166; range_end:; response_count:1; response_revision:4948; }","duration":"107.15545ms","start":"2026-10-14T15:55:35.628539Z","end":"2026-10-14T15:55:35.735694Z","steps":["trace[1314813055] 'agreement among raft nodes before linearized reading' (duration: 106.814091ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:35.735724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.272329ms","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-10-14T15:55:35.735758Z","caller":"traceutil/trace.go:171","msg":"trace[1793287024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4948; }","duration":"132.331531ms","start":"2026-10-14T15:55:35.603416Z","end":"2026-10-14T15:55:35.735748Z","steps":["trace[1793287024] 'agreement among raft nodes before linearized reading' (duration: 131.883639ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:35.735916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.164393ms","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-10-14T15:55:35.735975Z","caller":"traceutil/trace.go:171","msg":"trace[573278358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4948; }","duration":"125.258827ms","start":"2026-10-14T15:55:35.610701Z","end":"2026-10-14T15:55:35.73596Z","steps":["trace[573278358] 'agreement among raft nodes before linearized reading' (duration: 124.708161ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:35.736877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.093195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2588692"} {"level":"info","ts":"2026-10-14T15:55:35.736979Z","caller":"traceutil/trace.go:171","msg":"trace[823707620] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:4948; }","duration":"132.224988ms","start":"2026-10-14T15:55:35.604729Z","end":"2026-10-14T15:55:35.736954Z","steps":["trace[823707620] 'agreement among raft nodes before linearized reading' (duration: 130.713336ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:45.666266Z","caller":"traceutil/trace.go:171","msg":"trace[759449635] transaction","detail":"{read_only:false; response_revision:5115; number_of_response:1; }","duration":"160.075693ms","start":"2026-10-14T15:55:45.506139Z","end":"2026-10-14T15:55:45.666215Z","steps":["trace[759449635] 'process raft request' (duration: 115.818008ms)","trace[759449635] 'compare' (duration: 43.971268ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:55:45.666294Z","caller":"traceutil/trace.go:171","msg":"trace[1887608086] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"159.828737ms","start":"2026-10-14T15:55:45.506435Z","end":"2026-10-14T15:55:45.666264Z","steps":["trace[1887608086] 'process raft request' (duration: 159.662592ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:45.66654Z","caller":"traceutil/trace.go:171","msg":"trace[920852848] transaction","detail":"{read_only:false; response_revision:5117; number_of_response:1; }","duration":"154.571972ms","start":"2026-10-14T15:55:45.511928Z","end":"2026-10-14T15:55:45.6665Z","steps":["trace[920852848] 'process raft request' (duration: 154.259784ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:45.837504Z","caller":"traceutil/trace.go:171","msg":"trace[2044043290] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"156.584597ms","start":"2026-10-14T15:55:45.680884Z","end":"2026-10-14T15:55:45.837469Z","steps":["trace[2044043290] 'process raft request' (duration: 106.707589ms)","trace[2044043290] 'compare' (duration: 49.538489ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:55:45.837757Z","caller":"traceutil/trace.go:171","msg":"trace[398685301] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"149.842971ms","start":"2026-10-14T15:55:45.687866Z","end":"2026-10-14T15:55:45.837709Z","steps":["trace[398685301] 'process raft request' (duration: 149.525163ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:46.419313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.347294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:55:46.41942Z","caller":"traceutil/trace.go:171","msg":"trace[148874392] linearizableReadLoop","detail":"{readStateIndex:5343; appliedIndex:5341; }","duration":"575.286227ms","start":"2026-10-14T15:55:45.844117Z","end":"2026-10-14T15:55:46.419403Z","steps":["trace[148874392] 'read index received' (duration: 58.779823ms)","trace[148874392] 'applied index is now lower than readState.Index' (duration: 516.504864ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:55:46.419643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.124387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-10-14T15:55:46.419672Z","caller":"traceutil/trace.go:171","msg":"trace[1524613695] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5121; }","duration":"542.153028ms","start":"2026-10-14T15:55:45.877507Z","end":"2026-10-14T15:55:46.41966Z","steps":["trace[1524613695] 'agreement among raft nodes before linearized reading' (duration: 541.994893ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:46.419703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:45.877445Z","time spent":"542.25116ms","remote":"127.0.0.1:38376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":669,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-14T15:55:46.419935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.287731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:55:46.420022Z","caller":"traceutil/trace.go:171","msg":"trace[852775676] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5121; }","duration":"452.443626ms","start":"2026-10-14T15:55:45.967558Z","end":"2026-10-14T15:55:46.420002Z","steps":["trace[852775676] 'agreement among raft nodes before linearized reading' (duration: 452.278881ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:46.419972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.742959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-10-14T15:55:46.419944Z","caller":"traceutil/trace.go:171","msg":"trace[1475449856] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"576.13068ms","start":"2026-10-14T15:55:45.843773Z","end":"2026-10-14T15:55:46.419904Z","steps":["trace[1475449856] 'process raft request' (duration: 397.099227ms)","trace[1475449856] 'compare' (duration: 178.015136ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:55:46.420064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.626949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-10-14T15:55:46.420089Z","caller":"traceutil/trace.go:171","msg":"trace[1893980537] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5121; }","duration":"380.920163ms","start":"2026-10-14T15:55:46.039145Z","end":"2026-10-14T15:55:46.420065Z","steps":["trace[1893980537] 'agreement among raft nodes before linearized reading' (duration: 380.682647ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:46.420084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.26633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-10-14T15:55:46.42014Z","caller":"traceutil/trace.go:171","msg":"trace[1716143187] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5121; }","duration":"222.775053ms","start":"2026-10-14T15:55:46.197347Z","end":"2026-10-14T15:55:46.420122Z","steps":["trace[1716143187] 'agreement among raft nodes before linearized reading' (duration: 222.533746ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:46.420149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:46.039118Z","time spent":"381.019236ms","remote":"127.0.0.1:38438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1805,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"warn","ts":"2026-10-14T15:55:46.420072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:45.967527Z","time spent":"452.533838ms","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-10-14T15:55:46.420211Z","caller":"traceutil/trace.go:171","msg":"trace[1666329987] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5121; }","duration":"542.421585ms","start":"2026-10-14T15:55:45.877732Z","end":"2026-10-14T15:55:46.420153Z","steps":["trace[1666329987] 'agreement among raft nodes before linearized reading' (duration: 542.132077ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:46.420269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:45.877703Z","time spent":"542.554599ms","remote":"127.0.0.1:38376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":684,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-10-14T15:55:46.420394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.993455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-c47d949f5-wlftl\" ","response":"range_response_count:1 size:7813"} {"level":"warn","ts":"2026-10-14T15:55:46.420414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:45.843737Z","time spent":"576.531421ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:55:46.420517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.382398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-14T15:55:46.420569Z","caller":"traceutil/trace.go:171","msg":"trace[589484794] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-c47d949f5-wlftl; range_end:; response_count:1; response_revision:5121; }","duration":"381.14711ms","start":"2026-10-14T15:55:46.039295Z","end":"2026-10-14T15:55:46.420442Z","steps":["trace[589484794] 'agreement among raft nodes before linearized reading' (duration: 380.526443ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:46.420786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:46.039278Z","time spent":"381.49734ms","remote":"127.0.0.1:38466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":7837,"request content":"key:\"/registry/pods/openstack/memcached-memcached-c47d949f5-wlftl\" "} {"level":"info","ts":"2026-10-14T15:55:46.420562Z","caller":"traceutil/trace.go:171","msg":"trace[1947501867] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:5121; }","duration":"576.495901ms","start":"2026-10-14T15:55:45.844056Z","end":"2026-10-14T15:55:46.420552Z","steps":["trace[1947501867] 'agreement among raft nodes before linearized reading' (duration: 575.435521ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:46.420894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:45.844042Z","time spent":"576.819419ms","remote":"127.0.0.1:38296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-10-14T15:55:53.101672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.978002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-10-14T15:55:53.10178Z","caller":"traceutil/trace.go:171","msg":"trace[25450131] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5273; }","duration":"120.199499ms","start":"2026-10-14T15:55:52.981552Z","end":"2026-10-14T15:55:53.101752Z","steps":["trace[25450131] 'range keys from in-memory index tree' (duration: 119.608172ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:53.284573Z","caller":"traceutil/trace.go:171","msg":"trace[2106440283] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"156.847304ms","start":"2026-10-14T15:55:53.127699Z","end":"2026-10-14T15:55:53.284546Z","steps":["trace[2106440283] 'process raft request' (duration: 156.663429ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:53.368063Z","caller":"traceutil/trace.go:171","msg":"trace[287245365] linearizableReadLoop","detail":"{readStateIndex:5502; appliedIndex:5501; }","duration":"164.506175ms","start":"2026-10-14T15:55:53.203528Z","end":"2026-10-14T15:55:53.368034Z","steps":["trace[287245365] 'read index received' (duration: 80.993333ms)","trace[287245365] 'applied index is now lower than readState.Index' (duration: 83.511582ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:55:53.368332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.782862ms","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-10-14T15:55:53.368363Z","caller":"traceutil/trace.go:171","msg":"trace[191120735] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5275; }","duration":"164.881775ms","start":"2026-10-14T15:55:53.203472Z","end":"2026-10-14T15:55:53.368354Z","steps":["trace[191120735] 'agreement among raft nodes before linearized reading' (duration: 164.726911ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:53.369417Z","caller":"traceutil/trace.go:171","msg":"trace[333422622] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"200.567104ms","start":"2026-10-14T15:55:53.168782Z","end":"2026-10-14T15:55:53.369349Z","steps":["trace[333422622] 'process raft request' (duration: 148.639399ms)","trace[333422622] 'compare' (duration: 50.472576ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:56:04.806359Z","caller":"traceutil/trace.go:171","msg":"trace[1616059222] transaction","detail":"{read_only:false; response_revision:5424; number_of_response:1; }","duration":"132.99266ms","start":"2026-10-14T15:56:04.673335Z","end":"2026-10-14T15:56:04.806328Z","steps":["trace[1616059222] 'process raft request' (duration: 132.775854ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:56:04.817183Z","caller":"traceutil/trace.go:171","msg":"trace[102773681] linearizableReadLoop","detail":"{readStateIndex:5657; appliedIndex:5655; }","duration":"125.706829ms","start":"2026-10-14T15:56:04.69143Z","end":"2026-10-14T15:56:04.817137Z","steps":["trace[102773681] 'read index received' (duration: 114.858422ms)","trace[102773681] 'applied index is now lower than readState.Index' (duration: 10.847227ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:56:04.817356Z","caller":"traceutil/trace.go:171","msg":"trace[621246531] transaction","detail":"{read_only:false; response_revision:5425; number_of_response:1; }","duration":"141.706139ms","start":"2026-10-14T15:56:04.675522Z","end":"2026-10-14T15:56:04.817228Z","steps":["trace[621246531] 'process raft request' (duration: 141.484282ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:56:04.817581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.101891ms","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-10-14T15:56:04.817831Z","caller":"traceutil/trace.go:171","msg":"trace[641954830] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5425; }","duration":"126.44245ms","start":"2026-10-14T15:56:04.691367Z","end":"2026-10-14T15:56:04.817809Z","steps":["trace[641954830] 'agreement among raft nodes before linearized reading' (duration: 125.910526ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:56:42.5521Z","caller":"traceutil/trace.go:171","msg":"trace[1114586439] transaction","detail":"{read_only:false; response_revision:5815; number_of_response:1; }","duration":"125.227165ms","start":"2026-10-14T15:56:42.426851Z","end":"2026-10-14T15:56:42.552078Z","steps":["trace[1114586439] 'process raft request' (duration: 125.099302ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:58:01.870134Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2959} {"level":"info","ts":"2026-10-14T15:58:01.957442Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2959,"took":"85.731768ms","hash":776699837,"current-db-size-bytes":60137472,"current-db-size":"60 MB","current-db-size-in-use-bytes":36012032,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-14T15:58:01.957533Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":776699837,"revision":2959,"compact-revision":-1} {"level":"info","ts":"2026-10-14T16:01:56.967686Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022da.wal"} {"level":"info","ts":"2026-10-14T16:02:01.638949Z","caller":"traceutil/trace.go:171","msg":"trace[989412421] transaction","detail":"{read_only:false; response_revision:8578; number_of_response:1; }","duration":"152.290596ms","start":"2026-10-14T16:02:01.486626Z","end":"2026-10-14T16:02:01.638916Z","steps":["trace[989412421] 'process raft request' (duration: 130.025974ms)","trace[989412421] 'compare' (duration: 22.117938ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:02:01.64042Z","caller":"traceutil/trace.go:171","msg":"trace[1997009149] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"153.304982ms","start":"2026-10-14T16:02:01.487073Z","end":"2026-10-14T16:02:01.640378Z","steps":["trace[1997009149] 'process raft request' (duration: 152.88529ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:02:01.640686Z","caller":"traceutil/trace.go:171","msg":"trace[1303823415] transaction","detail":"{read_only:false; response_revision:8580; number_of_response:1; }","duration":"150.31549ms","start":"2026-10-14T16:02:01.490345Z","end":"2026-10-14T16:02:01.640661Z","steps":["trace[1303823415] 'process raft request' (duration: 149.839007ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:02:12.853141Z","caller":"traceutil/trace.go:171","msg":"trace[369248679] transaction","detail":"{read_only:false; response_revision:8653; number_of_response:1; }","duration":"150.65861ms","start":"2026-10-14T16:02:12.702461Z","end":"2026-10-14T16:02:12.853119Z","steps":["trace[369248679] 'process raft request' (duration: 150.515686ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:02:13.365745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.69056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lg4r4\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-10-14T16:02:13.36584Z","caller":"traceutil/trace.go:171","msg":"trace[2119463486] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lg4r4; range_end:; response_count:1; response_revision:8654; }","duration":"206.824334ms","start":"2026-10-14T16:02:13.158992Z","end":"2026-10-14T16:02:13.365816Z","steps":["trace[2119463486] 'range keys from in-memory index tree' (duration: 206.455773ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:02:14.376309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.026018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T16:02:14.376469Z","caller":"traceutil/trace.go:171","msg":"trace[1694630009] linearizableReadLoop","detail":"{readStateIndex:9054; appliedIndex:9053; }","duration":"150.824944ms","start":"2026-10-14T16:02:14.225623Z","end":"2026-10-14T16:02:14.376448Z","steps":["trace[1694630009] 'read index received' (duration: 42.361µs)","trace[1694630009] 'applied index is now lower than readState.Index' (duration: 150.780493ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:02:14.376536Z","caller":"traceutil/trace.go:171","msg":"trace[732875987] transaction","detail":"{read_only:false; response_revision:8662; number_of_response:1; }","duration":"509.231234ms","start":"2026-10-14T16:02:13.867274Z","end":"2026-10-14T16:02:14.376505Z","steps":["trace[732875987] 'process raft request' (duration: 316.903209ms)","trace[732875987] 'compare' (duration: 191.786649ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:02:14.376667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.045241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-14T16:02:14.376694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:02:13.867254Z","time spent":"509.356987ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:02:14.376724Z","caller":"traceutil/trace.go:171","msg":"trace[861214825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8662; }","duration":"151.165943ms","start":"2026-10-14T16:02:14.225543Z","end":"2026-10-14T16:02:14.376709Z","steps":["trace[861214825] 'agreement among raft nodes before linearized reading' (duration: 150.973068ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:02:43.144948Z","caller":"traceutil/trace.go:171","msg":"trace[668267135] linearizableReadLoop","detail":"{readStateIndex:9311; appliedIndex:9310; }","duration":"302.792041ms","start":"2026-10-14T16:02:42.842124Z","end":"2026-10-14T16:02:43.144917Z","steps":["trace[668267135] 'read index received' (duration: 302.492212ms)","trace[668267135] 'applied index is now lower than readState.Index' (duration: 298.548µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:02:43.145008Z","caller":"traceutil/trace.go:171","msg":"trace[1448915371] transaction","detail":"{read_only:false; response_revision:8906; number_of_response:1; }","duration":"410.778228ms","start":"2026-10-14T16:02:42.734193Z","end":"2026-10-14T16:02:43.144971Z","steps":["trace[1448915371] 'process raft request' (duration: 410.520011ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:02:43.145192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:02:42.734171Z","time spent":"410.911532ms","remote":"127.0.0.1:38376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:02:43.145237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.14283ms","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-10-14T16:02:43.145364Z","caller":"traceutil/trace.go:171","msg":"trace[2092619283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8906; }","duration":"303.324285ms","start":"2026-10-14T16:02:42.842025Z","end":"2026-10-14T16:02:43.145349Z","steps":["trace[2092619283] 'agreement among raft nodes before linearized reading' (duration: 303.044828ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:02:43.146296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:02:42.841989Z","time spent":"304.289522ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-14T16:02:43.291582Z","caller":"traceutil/trace.go:171","msg":"trace[2064069715] transaction","detail":"{read_only:false; response_revision:8907; number_of_response:1; }","duration":"140.287835ms","start":"2026-10-14T16:02:43.151256Z","end":"2026-10-14T16:02:43.291544Z","steps":["trace[2064069715] 'process raft request' (duration: 65.923952ms)","trace[2064069715] 'compare' (duration: 74.190718ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:02:43.29263Z","caller":"traceutil/trace.go:171","msg":"trace[817312686] transaction","detail":"{read_only:false; response_revision:8908; number_of_response:1; }","duration":"141.052646ms","start":"2026-10-14T16:02:43.151554Z","end":"2026-10-14T16:02:43.292606Z","steps":["trace[817312686] 'process raft request' (duration: 140.789259ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:02:43.292818Z","caller":"traceutil/trace.go:171","msg":"trace[1368289129] transaction","detail":"{read_only:false; response_revision:8909; number_of_response:1; }","duration":"141.238952ms","start":"2026-10-14T16:02:43.15157Z","end":"2026-10-14T16:02:43.292809Z","steps":["trace[1368289129] 'process raft request' (duration: 140.858782ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:02:57.54761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.32196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T16:02:57.547725Z","caller":"traceutil/trace.go:171","msg":"trace[1428844253] linearizableReadLoop","detail":"{readStateIndex:9458; appliedIndex:9457; }","duration":"222.391726ms","start":"2026-10-14T16:02:57.325316Z","end":"2026-10-14T16:02:57.547708Z","steps":["trace[1428844253] 'read index received' (duration: 43.711µs)","trace[1428844253] 'applied index is now lower than readState.Index' (duration: 222.346604ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:02:57.547826Z","caller":"traceutil/trace.go:171","msg":"trace[88251004] transaction","detail":"{read_only:false; response_revision:9043; number_of_response:1; }","duration":"321.561862ms","start":"2026-10-14T16:02:57.226206Z","end":"2026-10-14T16:02:57.547767Z","steps":["trace[88251004] 'process raft request' (duration: 89.966064ms)","trace[88251004] 'compare' (duration: 231.159256ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:02:57.547922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.069609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-10-14T16:02:57.547888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.5464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T16:02:57.548024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.711566ms","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-10-14T16:02:57.548014Z","caller":"traceutil/trace.go:171","msg":"trace[490084358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9043; }","duration":"222.711914ms","start":"2026-10-14T16:02:57.32527Z","end":"2026-10-14T16:02:57.547982Z","steps":["trace[490084358] 'agreement among raft nodes before linearized reading' (duration: 222.523529ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:02:57.548044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.825149ms","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":"warn","ts":"2026-10-14T16:02:57.548059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:02:57.226173Z","time spent":"321.764928ms","remote":"127.0.0.1:38466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4595,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:02:57.547956Z","caller":"traceutil/trace.go:171","msg":"trace[1642009989] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:9043; }","duration":"165.136081ms","start":"2026-10-14T16:02:57.38281Z","end":"2026-10-14T16:02:57.547946Z","steps":["trace[1642009989] 'agreement among raft nodes before linearized reading' (duration: 164.999687ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:02:57.548087Z","caller":"traceutil/trace.go:171","msg":"trace[2049340686] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9043; }","duration":"161.866161ms","start":"2026-10-14T16:02:57.386211Z","end":"2026-10-14T16:02:57.548077Z","steps":["trace[2049340686] 'agreement among raft nodes before linearized reading' (duration: 161.759908ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:02:57.548081Z","caller":"traceutil/trace.go:171","msg":"trace[955689543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9043; }","duration":"161.772907ms","start":"2026-10-14T16:02:57.386293Z","end":"2026-10-14T16:02:57.548066Z","steps":["trace[955689543] 'agreement among raft nodes before linearized reading' (duration: 161.591832ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:02:57.713737Z","caller":"traceutil/trace.go:171","msg":"trace[2053974036] transaction","detail":"{read_only:false; response_revision:9045; number_of_response:1; }","duration":"158.763736ms","start":"2026-10-14T16:02:57.554927Z","end":"2026-10-14T16:02:57.713691Z","steps":["trace[2053974036] 'process raft request' (duration: 158.369004ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:02:57.713824Z","caller":"traceutil/trace.go:171","msg":"trace[584488286] transaction","detail":"{read_only:false; response_revision:9046; number_of_response:1; }","duration":"157.966824ms","start":"2026-10-14T16:02:57.555811Z","end":"2026-10-14T16:02:57.713778Z","steps":["trace[584488286] 'process raft request' (duration: 157.79061ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:02:57.713863Z","caller":"traceutil/trace.go:171","msg":"trace[510728085] linearizableReadLoop","detail":"{readStateIndex:9461; appliedIndex:9458; }","duration":"156.564405ms","start":"2026-10-14T16:02:57.55724Z","end":"2026-10-14T16:02:57.713805Z","steps":["trace[510728085] 'read index received' (duration: 16.117622ms)","trace[510728085] 'applied index is now lower than readState.Index' (duration: 140.445543ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:02:57.714065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.808892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-l7cp4\" ","response":"range_response_count:1 size:4610"} {"level":"warn","ts":"2026-10-14T16:02:57.714133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.496487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:02:57.714178Z","caller":"traceutil/trace.go:171","msg":"trace[2032674574] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-l7cp4; range_end:; response_count:1; response_revision:9047; }","duration":"156.963277ms","start":"2026-10-14T16:02:57.557197Z","end":"2026-10-14T16:02:57.71416Z","steps":["trace[2032674574] 'agreement among raft nodes before linearized reading' (duration: 156.72936ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:02:57.71418Z","caller":"traceutil/trace.go:171","msg":"trace[1629077824] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:9047; }","duration":"119.61764ms","start":"2026-10-14T16:02:57.594545Z","end":"2026-10-14T16:02:57.714162Z","steps":["trace[1629077824] 'agreement among raft nodes before linearized reading' (duration: 119.521957ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:02:57.714316Z","caller":"traceutil/trace.go:171","msg":"trace[614413485] transaction","detail":"{read_only:false; response_revision:9047; number_of_response:1; }","duration":"155.96629ms","start":"2026-10-14T16:02:57.558335Z","end":"2026-10-14T16:02:57.714301Z","steps":["trace[614413485] 'process raft request' (duration: 155.347693ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:02:58.47201Z","caller":"traceutil/trace.go:171","msg":"trace[623275690] linearizableReadLoop","detail":"{readStateIndex:9468; appliedIndex:9467; }","duration":"226.5546ms","start":"2026-10-14T16:02:58.245424Z","end":"2026-10-14T16:02:58.471979Z","steps":["trace[623275690] 'read index received' (duration: 226.254262ms)","trace[623275690] 'applied index is now lower than readState.Index' (duration: 298.978µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:02:58.472272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.819058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T16:02:58.472326Z","caller":"traceutil/trace.go:171","msg":"trace[734321261] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:9053; }","duration":"226.948601ms","start":"2026-10-14T16:02:58.245361Z","end":"2026-10-14T16:02:58.472309Z","steps":["trace[734321261] 'agreement among raft nodes before linearized reading' (duration: 226.781447ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:02:58.472476Z","caller":"traceutil/trace.go:171","msg":"trace[1791872001] transaction","detail":"{read_only:false; response_revision:9053; number_of_response:1; }","duration":"264.157444ms","start":"2026-10-14T16:02:58.208284Z","end":"2026-10-14T16:02:58.472442Z","steps":["trace[1791872001] 'process raft request' (duration: 263.490106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:02:58.71667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.79198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-10-14T16:02:58.716827Z","caller":"traceutil/trace.go:171","msg":"trace[34683326] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9053; }","duration":"205.970534ms","start":"2026-10-14T16:02:58.510826Z","end":"2026-10-14T16:02:58.716797Z","steps":["trace[34683326] 'range keys from in-memory index tree' (duration: 205.630355ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:03:01.877123Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6461} {"level":"info","ts":"2026-10-14T16:03:01.992453Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6461,"took":"112.867054ms","hash":3339804351,"current-db-size-bytes":60137472,"current-db-size":"60 MB","current-db-size-in-use-bytes":25546752,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-14T16:03:01.992536Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3339804351,"revision":6461,"compact-revision":2959} {"level":"info","ts":"2026-10-14T16:03:49.433084Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7481851e1bcdf3c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T16:03:49.439464Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-14T16:03:49.439593Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-14T16:04:14.841319Z","caller":"traceutil/trace.go:171","msg":"trace[2130855124] transaction","detail":"{read_only:false; response_revision:9844; number_of_response:1; }","duration":"110.398607ms","start":"2026-10-14T16:04:14.730883Z","end":"2026-10-14T16:04:14.841282Z","steps":["trace[2130855124] 'process raft request' (duration: 76.969038ms)","trace[2130855124] 'compare' (duration: 33.270874ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:04:15.139918Z","caller":"traceutil/trace.go:171","msg":"trace[436102836] transaction","detail":"{read_only:false; response_revision:9845; number_of_response:1; }","duration":"123.071864ms","start":"2026-10-14T16:04:15.016815Z","end":"2026-10-14T16:04:15.139887Z","steps":["trace[436102836] 'process raft request' (duration: 105.181903ms)","trace[436102836] 'compare' (duration: 17.763088ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:04:15.366524Z","caller":"traceutil/trace.go:171","msg":"trace[1649541558] linearizableReadLoop","detail":"{readStateIndex:10298; appliedIndex:10297; }","duration":"171.743752ms","start":"2026-10-14T16:04:15.194733Z","end":"2026-10-14T16:04:15.366477Z","steps":["trace[1649541558] 'read index received' (duration: 109.84116ms)","trace[1649541558] 'applied index is now lower than readState.Index' (duration: 61.900832ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:04:15.366708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.950208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:04:15.366795Z","caller":"traceutil/trace.go:171","msg":"trace[1048307309] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:9846; }","duration":"172.100892ms","start":"2026-10-14T16:04:15.194676Z","end":"2026-10-14T16:04:15.366776Z","steps":["trace[1048307309] 'agreement among raft nodes before linearized reading' (duration: 171.938248ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:04:15.366579Z","caller":"traceutil/trace.go:171","msg":"trace[1400422882] transaction","detail":"{read_only:false; response_revision:9846; number_of_response:1; }","duration":"208.564875ms","start":"2026-10-14T16:04:15.157939Z","end":"2026-10-14T16:04:15.366503Z","steps":["trace[1400422882] 'process raft request' (duration: 146.714744ms)","trace[1400422882] 'compare' (duration: 61.520412ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:07:43.482701Z","caller":"traceutil/trace.go:171","msg":"trace[875828919] linearizableReadLoop","detail":"{readStateIndex:12488; appliedIndex:12487; }","duration":"109.061688ms","start":"2026-10-14T16:07:43.373614Z","end":"2026-10-14T16:07:43.482675Z","steps":["trace[875828919] 'read index received' (duration: 23.278478ms)","trace[875828919] 'applied index is now lower than readState.Index' (duration: 85.78226ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:07:43.482827Z","caller":"traceutil/trace.go:171","msg":"trace[1847269413] transaction","detail":"{read_only:false; response_revision:11946; number_of_response:1; }","duration":"114.884997ms","start":"2026-10-14T16:07:43.367904Z","end":"2026-10-14T16:07:43.482789Z","steps":["trace[1847269413] 'process raft request' (duration: 29.040936ms)","trace[1847269413] 'compare' (duration: 85.607925ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:07:43.482901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.286586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-14T16:07:43.482939Z","caller":"traceutil/trace.go:171","msg":"trace[1245154032] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:11946; }","duration":"109.380628ms","start":"2026-10-14T16:07:43.373541Z","end":"2026-10-14T16:07:43.482922Z","steps":["trace[1245154032] 'agreement among raft nodes before linearized reading' (duration: 109.242423ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:07:58.20222Z","caller":"traceutil/trace.go:171","msg":"trace[1813926491] transaction","detail":"{read_only:false; response_revision:12049; number_of_response:1; }","duration":"137.807746ms","start":"2026-10-14T16:07:58.064379Z","end":"2026-10-14T16:07:58.202187Z","steps":["trace[1813926491] 'process raft request' (duration: 137.618221ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:07:59.54376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.548059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T16:07:59.543905Z","caller":"traceutil/trace.go:171","msg":"trace[1583121539] transaction","detail":"{read_only:false; response_revision:12054; number_of_response:1; }","duration":"139.693048ms","start":"2026-10-14T16:07:59.404183Z","end":"2026-10-14T16:07:59.543876Z","steps":["trace[1583121539] 'process raft request' (duration: 32.940643ms)","trace[1583121539] 'compare' (duration: 106.348974ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:08:01.886184Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9081} {"level":"info","ts":"2026-10-14T16:08:01.972637Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9081,"took":"84.232238ms","hash":599225775,"current-db-size-bytes":60137472,"current-db-size":"60 MB","current-db-size-in-use-bytes":27410432,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-14T16:08:01.972708Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":599225775,"revision":9081,"compact-revision":6461} {"level":"info","ts":"2026-10-14T16:13:01.893427Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12078} {"level":"info","ts":"2026-10-14T16:13:02.009396Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12078,"took":"113.62158ms","hash":4209067343,"current-db-size-bytes":60137472,"current-db-size":"60 MB","current-db-size-in-use-bytes":25579520,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-14T16:13:02.009508Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4209067343,"revision":12078,"compact-revision":9081} {"level":"info","ts":"2026-10-14T16:13:57.103076Z","caller":"traceutil/trace.go:171","msg":"trace[505203297] transaction","detail":"{read_only:false; response_revision:14971; number_of_response:1; }","duration":"103.247024ms","start":"2026-10-14T16:13:56.999804Z","end":"2026-10-14T16:13:57.103051Z","steps":["trace[505203297] 'process raft request' (duration: 103.026468ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:14:23.599617Z","caller":"traceutil/trace.go:171","msg":"trace[1938703715] transaction","detail":"{read_only:false; response_revision:15151; number_of_response:1; }","duration":"145.766831ms","start":"2026-10-14T16:14:23.453827Z","end":"2026-10-14T16:14:23.599593Z","steps":["trace[1938703715] 'process raft request' (duration: 145.533244ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:14:24.099705Z","caller":"traceutil/trace.go:171","msg":"trace[808330985] transaction","detail":"{read_only:false; response_revision:15157; number_of_response:1; }","duration":"105.551477ms","start":"2026-10-14T16:14:23.994123Z","end":"2026-10-14T16:14:24.099675Z","steps":["trace[808330985] 'process raft request' (duration: 102.540745ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:14:24.103848Z","caller":"traceutil/trace.go:171","msg":"trace[770231504] transaction","detail":"{read_only:false; response_revision:15158; number_of_response:1; }","duration":"109.62462ms","start":"2026-10-14T16:14:23.994198Z","end":"2026-10-14T16:14:24.103822Z","steps":["trace[770231504] 'process raft request' (duration: 109.490176ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:14:42.777995Z","caller":"traceutil/trace.go:171","msg":"trace[347368758] linearizableReadLoop","detail":"{readStateIndex:16019; appliedIndex:16018; }","duration":"268.196312ms","start":"2026-10-14T16:14:42.509753Z","end":"2026-10-14T16:14:42.777949Z","steps":["trace[347368758] 'read index received' (duration: 267.933245ms)","trace[347368758] 'applied index is now lower than readState.Index' (duration: 261.797µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:14:42.778165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.395168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:14:42.778133Z","caller":"traceutil/trace.go:171","msg":"trace[476044667] transaction","detail":"{read_only:false; response_revision:15296; number_of_response:1; }","duration":"425.456739ms","start":"2026-10-14T16:14:42.352647Z","end":"2026-10-14T16:14:42.778103Z","steps":["trace[476044667] 'process raft request' (duration: 425.11114ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:14:42.778207Z","caller":"traceutil/trace.go:171","msg":"trace[1152112078] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:15296; }","duration":"268.502401ms","start":"2026-10-14T16:14:42.509692Z","end":"2026-10-14T16:14:42.778195Z","steps":["trace[1152112078] 'agreement among raft nodes before linearized reading' (duration: 268.408778ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:14:42.778306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:14:42.352624Z","time spent":"425.596663ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:14:43.335885Z","caller":"traceutil/trace.go:171","msg":"trace[539145291] linearizableReadLoop","detail":"{readStateIndex:16020; appliedIndex:16019; }","duration":"291.262515ms","start":"2026-10-14T16:14:43.044593Z","end":"2026-10-14T16:14:43.335856Z","steps":["trace[539145291] 'read index received' (duration: 290.981797ms)","trace[539145291] 'applied index is now lower than readState.Index' (duration: 279.888µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:14:43.335987Z","caller":"traceutil/trace.go:171","msg":"trace[804933409] transaction","detail":"{read_only:false; response_revision:15297; number_of_response:1; }","duration":"550.145302ms","start":"2026-10-14T16:14:42.785801Z","end":"2026-10-14T16:14:43.335946Z","steps":["trace[804933409] 'process raft request' (duration: 549.894016ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:14:43.336164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.547234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-14T16:14:43.336186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:14:42.785783Z","time spent":"550.303786ms","remote":"127.0.0.1:38376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:14:43.336208Z","caller":"traceutil/trace.go:171","msg":"trace[1646083058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15297; }","duration":"291.644076ms","start":"2026-10-14T16:14:43.044554Z","end":"2026-10-14T16:14:43.336198Z","steps":["trace[1646083058] 'agreement among raft nodes before linearized reading' (duration: 291.447831ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:16:16.467153Z","caller":"traceutil/trace.go:171","msg":"trace[1953182037] linearizableReadLoop","detail":"{readStateIndex:17362; appliedIndex:17361; }","duration":"142.372898ms","start":"2026-10-14T16:16:16.324738Z","end":"2026-10-14T16:16:16.467111Z","steps":["trace[1953182037] 'read index received' (duration: 142.043498ms)","trace[1953182037] 'applied index is now lower than readState.Index' (duration: 327.47µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:16:16.467191Z","caller":"traceutil/trace.go:171","msg":"trace[1514050034] transaction","detail":"{read_only:false; response_revision:16587; number_of_response:1; }","duration":"158.607864ms","start":"2026-10-14T16:16:16.308535Z","end":"2026-10-14T16:16:16.467143Z","steps":["trace[1514050034] 'process raft request' (duration: 158.311835ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:16:16.467393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.629465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:16:16.46749Z","caller":"traceutil/trace.go:171","msg":"trace[1750664809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16587; }","duration":"142.775218ms","start":"2026-10-14T16:16:16.32469Z","end":"2026-10-14T16:16:16.467465Z","steps":["trace[1750664809] 'agreement among raft nodes before linearized reading' (duration: 142.556842ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:16:17.133919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.331671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:16:17.134157Z","caller":"traceutil/trace.go:171","msg":"trace[2067550783] transaction","detail":"{read_only:false; response_revision:16588; number_of_response:1; }","duration":"599.156905ms","start":"2026-10-14T16:16:16.534896Z","end":"2026-10-14T16:16:17.134053Z","steps":["trace[2067550783] 'process raft request' (duration: 180.542705ms)","trace[2067550783] 'compare' (duration: 418.072845ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:16:17.134318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:16:16.534847Z","time spent":"599.421142ms","remote":"127.0.0.1:38376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:16:17.188486Z","caller":"traceutil/trace.go:171","msg":"trace[1454558590] linearizableReadLoop","detail":"{readStateIndex:17364; appliedIndex:17362; }","duration":"599.762962ms","start":"2026-10-14T16:16:16.588689Z","end":"2026-10-14T16:16:17.188452Z","steps":["trace[1454558590] 'read index received' (duration: 126.690947ms)","trace[1454558590] 'applied index is now lower than readState.Index' (duration: 473.070775ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:16:17.188629Z","caller":"traceutil/trace.go:171","msg":"trace[1405603054] transaction","detail":"{read_only:false; response_revision:16589; number_of_response:1; }","duration":"653.65172ms","start":"2026-10-14T16:16:16.534919Z","end":"2026-10-14T16:16:17.188571Z","steps":["trace[1405603054] 'process raft request' (duration: 653.349142ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:16:17.188663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.963747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T16:16:17.188782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:16:16.534895Z","time spent":"653.789574ms","remote":"127.0.0.1:38708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:16:17.188837Z","caller":"traceutil/trace.go:171","msg":"trace[1030555836] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:16589; }","duration":"600.08243ms","start":"2026-10-14T16:16:16.588609Z","end":"2026-10-14T16:16:17.188691Z","steps":["trace[1030555836] 'agreement among raft nodes before linearized reading' (duration: 599.992388ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:16:17.188937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.560628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-10-14T16:16:17.189022Z","caller":"traceutil/trace.go:171","msg":"trace[554176077] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:16589; }","duration":"598.704633ms","start":"2026-10-14T16:16:16.590289Z","end":"2026-10-14T16:16:17.188994Z","steps":["trace[554176077] 'agreement among raft nodes before linearized reading' (duration: 598.389675ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:16:17.189078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:16:16.590258Z","time spent":"598.804955ms","remote":"127.0.0.1:38438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1890,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" "} {"level":"warn","ts":"2026-10-14T16:16:17.189093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.261651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-10-14T16:16:17.189076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.403257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-10-14T16:16:17.189086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.502502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-14T16:16:17.189143Z","caller":"traceutil/trace.go:171","msg":"trace[841229032] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16589; }","duration":"543.377904ms","start":"2026-10-14T16:16:16.645749Z","end":"2026-10-14T16:16:17.189127Z","steps":["trace[841229032] 'agreement among raft nodes before linearized reading' (duration: 543.22356ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:16:17.18918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.195511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T16:16:17.189198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:16:16.645704Z","time spent":"543.482436ms","remote":"127.0.0.1:38512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5547,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-10-14T16:16:17.189232Z","caller":"traceutil/trace.go:171","msg":"trace[625690747] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16589; }","duration":"111.303944ms","start":"2026-10-14T16:16:17.077906Z","end":"2026-10-14T16:16:17.18921Z","steps":["trace[625690747] 'agreement among raft nodes before linearized reading' (duration: 111.234192ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:16:17.189232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.505132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"warn","ts":"2026-10-14T16:16:17.189238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.47939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:16:17.18929Z","caller":"traceutil/trace.go:171","msg":"trace[703071504] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:16589; }","duration":"314.610205ms","start":"2026-10-14T16:16:16.874662Z","end":"2026-10-14T16:16:17.189272Z","steps":["trace[703071504] 'agreement among raft nodes before linearized reading' (duration: 314.501252ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:16:17.189281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.405456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:16:17.189309Z","caller":"traceutil/trace.go:171","msg":"trace[1195166335] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16589; }","duration":"111.599883ms","start":"2026-10-14T16:16:17.077687Z","end":"2026-10-14T16:16:17.189287Z","steps":["trace[1195166335] 'agreement among raft nodes before linearized reading' (duration: 111.49727ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:16:17.189079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.759596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-9gk4q\" ","response":"range_response_count:1 size:9785"} {"level":"warn","ts":"2026-10-14T16:16:17.189347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:16:16.874628Z","time spent":"314.709548ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":552,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-10-14T16:16:17.18935Z","caller":"traceutil/trace.go:171","msg":"trace[2097551638] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16589; }","duration":"111.538871ms","start":"2026-10-14T16:16:17.077796Z","end":"2026-10-14T16:16:17.189335Z","steps":["trace[2097551638] 'agreement among raft nodes before linearized reading' (duration: 111.405767ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:16:17.18922Z","caller":"traceutil/trace.go:171","msg":"trace[1973178671] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16589; }","duration":"310.725268ms","start":"2026-10-14T16:16:16.878457Z","end":"2026-10-14T16:16:17.189182Z","steps":["trace[1973178671] 'agreement among raft nodes before linearized reading' (duration: 310.352338ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:16:17.189182Z","caller":"traceutil/trace.go:171","msg":"trace[83769241] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16589; }","duration":"107.546731ms","start":"2026-10-14T16:16:17.081613Z","end":"2026-10-14T16:16:17.18916Z","steps":["trace[83769241] 'agreement among raft nodes before linearized reading' (duration: 107.344525ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:16:17.188949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:16:16.588577Z","time spent":"600.351118ms","remote":"127.0.0.1:38870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":30,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"warn","ts":"2026-10-14T16:16:17.189086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.195521ms","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-10-14T16:16:17.189362Z","caller":"traceutil/trace.go:171","msg":"trace[941825503] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-9gk4q; range_end:; response_count:1; response_revision:16589; }","duration":"175.149677ms","start":"2026-10-14T16:16:17.014202Z","end":"2026-10-14T16:16:17.189352Z","steps":["trace[941825503] 'agreement among raft nodes before linearized reading' (duration: 174.51697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:16:17.189492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.524842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-14T16:16:17.189522Z","caller":"traceutil/trace.go:171","msg":"trace[918880878] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16589; }","duration":"319.745756ms","start":"2026-10-14T16:16:16.869757Z","end":"2026-10-14T16:16:17.189503Z","steps":["trace[918880878] 'agreement among raft nodes before linearized reading' (duration: 318.990046ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:16:17.189538Z","caller":"traceutil/trace.go:171","msg":"trace[1863660021] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16589; }","duration":"545.664286ms","start":"2026-10-14T16:16:16.643859Z","end":"2026-10-14T16:16:17.189523Z","steps":["trace[1863660021] 'agreement among raft nodes before linearized reading' (duration: 545.527982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:16:17.189131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.435416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-14T16:16:17.189615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:16:16.643822Z","time spent":"545.779959ms","remote":"127.0.0.1:38440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":692,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-10-14T16:16:17.189356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.703373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:16:17.189665Z","caller":"traceutil/trace.go:171","msg":"trace[1831751225] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16589; }","duration":"544.040872ms","start":"2026-10-14T16:16:16.645609Z","end":"2026-10-14T16:16:17.18965Z","steps":["trace[1831751225] 'agreement among raft nodes before linearized reading' (duration: 543.697073ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:16:17.189743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:16:16.645595Z","time spent":"544.135514ms","remote":"127.0.0.1:38512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-10-14T16:16:17.189407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:16:16.878412Z","time spent":"310.982355ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T16:16:17.189613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:16:16.869708Z","time spent":"319.88833ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-14T16:16:17.189619Z","caller":"traceutil/trace.go:171","msg":"trace[1840080199] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16589; }","duration":"429.978741ms","start":"2026-10-14T16:16:16.759628Z","end":"2026-10-14T16:16:17.189607Z","steps":["trace[1840080199] 'agreement among raft nodes before linearized reading' (duration: 429.368424ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:16:17.189353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.691468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lg4r4\" ","response":"range_response_count:1 size:6523"} {"level":"warn","ts":"2026-10-14T16:16:17.189861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:16:16.759597Z","time spent":"430.247549ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-14T16:16:17.189903Z","caller":"traceutil/trace.go:171","msg":"trace[1189046781] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lg4r4; range_end:; response_count:1; response_revision:16589; }","duration":"213.288625ms","start":"2026-10-14T16:16:16.976605Z","end":"2026-10-14T16:16:17.189894Z","steps":["trace[1189046781] 'agreement among raft nodes before linearized reading' (duration: 212.651456ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:18:01.900987Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14512} {"level":"info","ts":"2026-10-14T16:18:02.020653Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14512,"took":"114.461452ms","hash":3229969207,"current-db-size-bytes":60137472,"current-db-size":"60 MB","current-db-size-in-use-bytes":29310976,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-14T16:18:02.020722Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3229969207,"revision":14512,"compact-revision":12078} {"level":"info","ts":"2026-10-14T16:21:08.237034Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7481851e1bcdf3c","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T16:21:08.244361Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-14T16:21:08.244486Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-14T16:22:04.495812Z","caller":"traceutil/trace.go:171","msg":"trace[997466195] transaction","detail":"{read_only:false; response_revision:19550; number_of_response:1; }","duration":"109.781753ms","start":"2026-10-14T16:22:04.385951Z","end":"2026-10-14T16:22:04.495732Z","steps":["trace[997466195] 'process raft request' (duration: 65.129951ms)","trace[997466195] 'compare' (duration: 44.359284ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:22:09.442915Z","caller":"traceutil/trace.go:171","msg":"trace[880957143] linearizableReadLoop","detail":"{readStateIndex:20576; appliedIndex:20575; }","duration":"117.537335ms","start":"2026-10-14T16:22:09.325341Z","end":"2026-10-14T16:22:09.442878Z","steps":["trace[880957143] 'read index received' (duration: 117.160825ms)","trace[880957143] 'applied index is now lower than readState.Index' (duration: 374.63µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:22:09.442977Z","caller":"traceutil/trace.go:171","msg":"trace[612049804] transaction","detail":"{read_only:false; response_revision:19646; number_of_response:1; }","duration":"127.629383ms","start":"2026-10-14T16:22:09.315304Z","end":"2026-10-14T16:22:09.442933Z","steps":["trace[612049804] 'process raft request' (duration: 127.324734ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:22:09.44319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.808412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:22:09.443235Z","caller":"traceutil/trace.go:171","msg":"trace[335151409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19646; }","duration":"117.945187ms","start":"2026-10-14T16:22:09.325278Z","end":"2026-10-14T16:22:09.443223Z","steps":["trace[335151409] 'agreement among raft nodes before linearized reading' (duration: 117.829963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:22:09.818352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.472452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:22:09.818582Z","caller":"traceutil/trace.go:171","msg":"trace[526022403] linearizableReadLoop","detail":"{readStateIndex:20578; appliedIndex:20576; }","duration":"332.443725ms","start":"2026-10-14T16:22:09.486116Z","end":"2026-10-14T16:22:09.818559Z","steps":["trace[526022403] 'read index received' (duration: 111.709986ms)","trace[526022403] 'applied index is now lower than readState.Index' (duration: 220.732419ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:22:09.818671Z","caller":"traceutil/trace.go:171","msg":"trace[522839243] transaction","detail":"{read_only:false; response_revision:19647; number_of_response:1; }","duration":"367.139125ms","start":"2026-10-14T16:22:09.451509Z","end":"2026-10-14T16:22:09.818648Z","steps":["trace[522839243] 'process raft request' (duration: 146.252451ms)","trace[522839243] 'compare' (duration: 220.123983ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:22:09.818716Z","caller":"traceutil/trace.go:171","msg":"trace[325036123] transaction","detail":"{read_only:false; response_revision:19648; number_of_response:1; }","duration":"367.165695ms","start":"2026-10-14T16:22:09.451537Z","end":"2026-10-14T16:22:09.818702Z","steps":["trace[325036123] 'process raft request' (duration: 366.932159ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:22:09.81877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:22:09.451466Z","time spent":"367.257868ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:22:09.818832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:22:09.451511Z","time spent":"367.238636ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:22:09.819074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.96178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-14T16:22:09.819129Z","caller":"traceutil/trace.go:171","msg":"trace[1253384173] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:19648; }","duration":"333.069902ms","start":"2026-10-14T16:22:09.486044Z","end":"2026-10-14T16:22:09.819114Z","steps":["trace[1253384173] 'agreement among raft nodes before linearized reading' (duration: 332.894527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:22:09.819172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:22:09.486012Z","time spent":"333.149054ms","remote":"127.0.0.1:38376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":381,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-10-14T16:22:09.819487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.161171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-10-14T16:22:09.81953Z","caller":"traceutil/trace.go:171","msg":"trace[544145267] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:19648; }","duration":"270.245923ms","start":"2026-10-14T16:22:09.549271Z","end":"2026-10-14T16:22:09.819517Z","steps":["trace[544145267] 'agreement among raft nodes before linearized reading' (duration: 270.11991ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:22:09.819684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.268182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2df3a381-f43c-478f-b12f-2aeb89bcc26f\" ","response":"range_response_count:1 size:3950"} {"level":"info","ts":"2026-10-14T16:22:09.819808Z","caller":"traceutil/trace.go:171","msg":"trace[1496785012] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2df3a381-f43c-478f-b12f-2aeb89bcc26f; range_end:; response_count:1; response_revision:19648; }","duration":"123.438217ms","start":"2026-10-14T16:22:09.696327Z","end":"2026-10-14T16:22:09.819766Z","steps":["trace[1496785012] 'agreement among raft nodes before linearized reading' (duration: 123.152719ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:22:46.239436Z","caller":"traceutil/trace.go:171","msg":"trace[1648284991] transaction","detail":"{read_only:false; response_revision:20161; number_of_response:1; }","duration":"141.895042ms","start":"2026-10-14T16:22:46.097505Z","end":"2026-10-14T16:22:46.2394Z","steps":["trace[1648284991] 'process raft request' (duration: 123.396336ms)","trace[1648284991] 'compare' (duration: 18.137206ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:22:46.239534Z","caller":"traceutil/trace.go:171","msg":"trace[162613059] transaction","detail":"{read_only:false; response_revision:20162; number_of_response:1; }","duration":"134.688614ms","start":"2026-10-14T16:22:46.104827Z","end":"2026-10-14T16:22:46.239516Z","steps":["trace[162613059] 'process raft request' (duration: 134.341785ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:23:01.976126Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17402} {"level":"warn","ts":"2026-10-14T16:23:02.307632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.624534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:23:02.30828Z","caller":"traceutil/trace.go:171","msg":"trace[1648543842] transaction","detail":"{read_only:false; response_revision:20299; number_of_response:1; }","duration":"155.541715ms","start":"2026-10-14T16:23:02.152688Z","end":"2026-10-14T16:23:02.30823Z","steps":["trace[1648543842] 'compare' (duration: 39.729236ms)","trace[1648543842] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:460; } (duration: 105.638061ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:23:02.308452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.956725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-14T16:23:02.308499Z","caller":"traceutil/trace.go:171","msg":"trace[562568372] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20299; }","duration":"130.038458ms","start":"2026-10-14T16:23:02.178445Z","end":"2026-10-14T16:23:02.308483Z","steps":["trace[562568372] 'agreement among raft nodes before linearized reading' (duration: 129.876433ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:23:02.308263Z","caller":"traceutil/trace.go:171","msg":"trace[1149923251] linearizableReadLoop","detail":"{readStateIndex:21259; appliedIndex:21258; }","duration":"129.698128ms","start":"2026-10-14T16:23:02.178494Z","end":"2026-10-14T16:23:02.308192Z","steps":["trace[1149923251] 'read index received' (duration: 49.831µs)","trace[1149923251] 'applied index is now lower than readState.Index' (duration: 129.645997ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:23:02.355991Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17402,"took":"376.14886ms","hash":1677035256,"current-db-size-bytes":60137472,"current-db-size":"60 MB","current-db-size-in-use-bytes":33509376,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-14T16:23:02.356061Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1677035256,"revision":17402,"compact-revision":14512} {"level":"info","ts":"2026-10-14T16:23:11.335986Z","caller":"traceutil/trace.go:171","msg":"trace[1168234574] transaction","detail":"{read_only:false; response_revision:20410; number_of_response:1; }","duration":"102.648609ms","start":"2026-10-14T16:23:11.233297Z","end":"2026-10-14T16:23:11.335945Z","steps":["trace[1168234574] 'process raft request' (duration: 98.205437ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:28:01.982065Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20298} {"level":"info","ts":"2026-10-14T16:28:02.128312Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20298,"took":"142.848393ms","hash":3287245981,"current-db-size-bytes":60137472,"current-db-size":"60 MB","current-db-size-in-use-bytes":32649216,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-14T16:28:02.128402Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3287245981,"revision":20298,"compact-revision":17402} {"level":"info","ts":"2026-10-14T16:29:37.02669Z","caller":"traceutil/trace.go:171","msg":"trace[99872959] transaction","detail":"{read_only:false; response_revision:23913; number_of_response:1; }","duration":"153.502705ms","start":"2026-10-14T16:29:36.873133Z","end":"2026-10-14T16:29:37.026636Z","steps":["trace[99872959] 'process raft request' (duration: 153.3011ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:29:37.032258Z","caller":"traceutil/trace.go:171","msg":"trace[1334914523] linearizableReadLoop","detail":"{readStateIndex:25046; appliedIndex:25044; }","duration":"153.931457ms","start":"2026-10-14T16:29:36.87828Z","end":"2026-10-14T16:29:37.032211Z","steps":["trace[1334914523] 'read index received' (duration: 148.240381ms)","trace[1334914523] 'applied index is now lower than readState.Index' (duration: 5.689406ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:29:37.032589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.279057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-10-14T16:29:37.032636Z","caller":"traceutil/trace.go:171","msg":"trace[575112700] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:23914; }","duration":"154.384359ms","start":"2026-10-14T16:29:36.878237Z","end":"2026-10-14T16:29:37.032622Z","steps":["trace[575112700] 'agreement among raft nodes before linearized reading' (duration: 154.134972ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:29:37.032707Z","caller":"traceutil/trace.go:171","msg":"trace[1662248516] transaction","detail":"{read_only:false; response_revision:23914; number_of_response:1; }","duration":"157.870105ms","start":"2026-10-14T16:29:36.874752Z","end":"2026-10-14T16:29:37.032621Z","steps":["trace[1662248516] 'process raft request' (duration: 157.239908ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:29:42.636521Z","caller":"traceutil/trace.go:171","msg":"trace[1198649124] linearizableReadLoop","detail":"{readStateIndex:25083; appliedIndex:25082; }","duration":"154.156263ms","start":"2026-10-14T16:29:42.482313Z","end":"2026-10-14T16:29:42.636469Z","steps":["trace[1198649124] 'read index received' (duration: 112.944864ms)","trace[1198649124] 'applied index is now lower than readState.Index' (duration: 41.209239ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:29:42.636793Z","caller":"traceutil/trace.go:171","msg":"trace[265306719] transaction","detail":"{read_only:false; response_revision:23949; number_of_response:1; }","duration":"201.309335ms","start":"2026-10-14T16:29:42.435436Z","end":"2026-10-14T16:29:42.636745Z","steps":["trace[265306719] 'process raft request' (duration: 159.838699ms)","trace[265306719] 'compare' (duration: 40.950242ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:29:42.636999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.628896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:29:42.637095Z","caller":"traceutil/trace.go:171","msg":"trace[1780942884] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:23949; }","duration":"154.7913ms","start":"2026-10-14T16:29:42.482286Z","end":"2026-10-14T16:29:42.637077Z","steps":["trace[1780942884] 'agreement among raft nodes before linearized reading' (duration: 154.460711ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:29:58.293126Z","caller":"traceutil/trace.go:171","msg":"trace[1971071634] transaction","detail":"{read_only:false; response_revision:24050; number_of_response:1; }","duration":"122.162357ms","start":"2026-10-14T16:29:58.17092Z","end":"2026-10-14T16:29:58.293082Z","steps":["trace[1971071634] 'process raft request' (duration: 121.909449ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:30:03.64844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.57407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-10-14T16:30:03.648558Z","caller":"traceutil/trace.go:171","msg":"trace[1322345923] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:24117; }","duration":"194.742924ms","start":"2026-10-14T16:30:03.453789Z","end":"2026-10-14T16:30:03.648532Z","steps":["trace[1322345923] 'range keys from in-memory index tree' (duration: 194.295622ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:30:03.648761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.947719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130325"} {"level":"info","ts":"2026-10-14T16:30:03.648854Z","caller":"traceutil/trace.go:171","msg":"trace[308082206] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:24117; }","duration":"161.093263ms","start":"2026-10-14T16:30:03.487741Z","end":"2026-10-14T16:30:03.648834Z","steps":["trace[308082206] 'range keys from in-memory index tree' (duration: 160.571218ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:30:04.453122Z","caller":"traceutil/trace.go:171","msg":"trace[1636745840] linearizableReadLoop","detail":"{readStateIndex:25261; appliedIndex:25260; }","duration":"113.350556ms","start":"2026-10-14T16:30:04.33972Z","end":"2026-10-14T16:30:04.45307Z","steps":["trace[1636745840] 'read index received' (duration: 112.76185ms)","trace[1636745840] 'applied index is now lower than readState.Index' (duration: 586.276µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:30:04.453222Z","caller":"traceutil/trace.go:171","msg":"trace[2076485935] transaction","detail":"{read_only:false; response_revision:24118; number_of_response:1; }","duration":"123.840734ms","start":"2026-10-14T16:30:04.329346Z","end":"2026-10-14T16:30:04.453187Z","steps":["trace[2076485935] 'process raft request' (duration: 123.216226ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:30:04.453685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.84614ms","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-10-14T16:30:04.453749Z","caller":"traceutil/trace.go:171","msg":"trace[176037796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24118; }","duration":"114.106237ms","start":"2026-10-14T16:30:04.339624Z","end":"2026-10-14T16:30:04.45373Z","steps":["trace[176037796] 'agreement among raft nodes before linearized reading' (duration: 113.701916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:30:04.856818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.992377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:30:04.857092Z","caller":"traceutil/trace.go:171","msg":"trace[1641530593] transaction","detail":"{read_only:false; response_revision:24119; number_of_response:1; }","duration":"398.418466ms","start":"2026-10-14T16:30:04.458619Z","end":"2026-10-14T16:30:04.857037Z","steps":["trace[1641530593] 'process raft request' (duration: 170.06828ms)","trace[1641530593] 'compare' (duration: 227.40358ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:30:04.857707Z","caller":"traceutil/trace.go:171","msg":"trace[1059210260] linearizableReadLoop","detail":"{readStateIndex:25262; appliedIndex:25261; }","duration":"396.931265ms","start":"2026-10-14T16:30:04.460009Z","end":"2026-10-14T16:30:04.85694Z","steps":["trace[1059210260] 'read index received' (duration: 168.556148ms)","trace[1059210260] 'applied index is now lower than readState.Index' (duration: 228.372827ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:30:04.857958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:30:04.458583Z","time spent":"398.639773ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:30:04.858003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.972083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11498"} {"level":"info","ts":"2026-10-14T16:30:04.858086Z","caller":"traceutil/trace.go:171","msg":"trace[851360546] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24119; }","duration":"398.089086ms","start":"2026-10-14T16:30:04.459955Z","end":"2026-10-14T16:30:04.858044Z","steps":["trace[851360546] 'agreement among raft nodes before linearized reading' (duration: 397.832479ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:30:04.858125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:30:04.459939Z","time spent":"398.176749ms","remote":"127.0.0.1:38466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11522,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-14T16:30:05.30985Z","caller":"traceutil/trace.go:171","msg":"trace[1995503883] linearizableReadLoop","detail":"{readStateIndex:25264; appliedIndex:25263; }","duration":"375.879468ms","start":"2026-10-14T16:30:04.933938Z","end":"2026-10-14T16:30:05.309817Z","steps":["trace[1995503883] 'read index received' (duration: 356.368953ms)","trace[1995503883] 'applied index is now lower than readState.Index' (duration: 19.508905ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:30:05.310167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.222247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-10-14T16:30:05.310213Z","caller":"traceutil/trace.go:171","msg":"trace[1135526111] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24121; }","duration":"376.281529ms","start":"2026-10-14T16:30:04.933916Z","end":"2026-10-14T16:30:05.310197Z","steps":["trace[1135526111] 'agreement among raft nodes before linearized reading' (duration: 376.089104ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:30:05.310247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:30:04.933778Z","time spent":"376.457113ms","remote":"127.0.0.1:38376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":671,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-14T16:30:05.310283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.30742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-10-14T16:30:05.310421Z","caller":"traceutil/trace.go:171","msg":"trace[1465249906] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:24121; }","duration":"341.483356ms","start":"2026-10-14T16:30:04.968904Z","end":"2026-10-14T16:30:05.310387Z","steps":["trace[1465249906] 'agreement among raft nodes before linearized reading' (duration: 341.124025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:30:05.310485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:30:04.968893Z","time spent":"341.576438ms","remote":"127.0.0.1:38376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":686,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-10-14T16:30:05.310729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.658207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:30:05.31078Z","caller":"traceutil/trace.go:171","msg":"trace[1961630965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24121; }","duration":"281.742069ms","start":"2026-10-14T16:30:05.029024Z","end":"2026-10-14T16:30:05.310766Z","steps":["trace[1961630965] 'agreement among raft nodes before linearized reading' (duration: 281.659467ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:30:05.311167Z","caller":"traceutil/trace.go:171","msg":"trace[104034944] transaction","detail":"{read_only:false; response_revision:24121; number_of_response:1; }","duration":"388.953976ms","start":"2026-10-14T16:30:04.920895Z","end":"2026-10-14T16:30:05.309849Z","steps":["trace[104034944] 'process raft request' (duration: 369.520384ms)","trace[104034944] 'compare' (duration: 18.751054ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:30:05.311614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:30:04.920835Z","time spent":"390.688765ms","remote":"127.0.0.1:38708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:30:05.544439Z","caller":"traceutil/trace.go:171","msg":"trace[782450329] linearizableReadLoop","detail":"{readStateIndex:25265; appliedIndex:25264; }","duration":"227.237176ms","start":"2026-10-14T16:30:05.317174Z","end":"2026-10-14T16:30:05.544411Z","steps":["trace[782450329] 'read index received' (duration: 225.552839ms)","trace[782450329] 'applied index is now lower than readState.Index' (duration: 1.683067ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:30:05.544662Z","caller":"traceutil/trace.go:171","msg":"trace[2110066521] transaction","detail":"{read_only:false; response_revision:24122; number_of_response:1; }","duration":"227.463722ms","start":"2026-10-14T16:30:05.317102Z","end":"2026-10-14T16:30:05.544565Z","steps":["trace[2110066521] 'process raft request' (duration: 225.608121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:30:05.544752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.544444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-14T16:30:05.54497Z","caller":"traceutil/trace.go:171","msg":"trace[1639901512] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24122; }","duration":"227.794051ms","start":"2026-10-14T16:30:05.317143Z","end":"2026-10-14T16:30:05.544937Z","steps":["trace[1639901512] 'agreement among raft nodes before linearized reading' (duration: 227.39513ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:30:05.54886Z","caller":"traceutil/trace.go:171","msg":"trace[1764445260] transaction","detail":"{read_only:false; response_revision:24124; number_of_response:1; }","duration":"231.365389ms","start":"2026-10-14T16:30:05.317457Z","end":"2026-10-14T16:30:05.548823Z","steps":["trace[1764445260] 'process raft request' (duration: 231.234245ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:30:05.549037Z","caller":"traceutil/trace.go:171","msg":"trace[1494955311] transaction","detail":"{read_only:false; response_revision:24123; number_of_response:1; }","duration":"231.622856ms","start":"2026-10-14T16:30:05.317378Z","end":"2026-10-14T16:30:05.549Z","steps":["trace[1494955311] 'process raft request' (duration: 231.07458ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:30:05.549215Z","caller":"traceutil/trace.go:171","msg":"trace[193786834] transaction","detail":"{read_only:false; number_of_response:0; response_revision:24124; }","duration":"228.965723ms","start":"2026-10-14T16:30:05.320237Z","end":"2026-10-14T16:30:05.549203Z","steps":["trace[193786834] 'process raft request' (duration: 228.512261ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:30:05.549442Z","caller":"traceutil/trace.go:171","msg":"trace[497087347] transaction","detail":"{read_only:false; response_revision:24125; number_of_response:1; }","duration":"227.378859ms","start":"2026-10-14T16:30:05.322045Z","end":"2026-10-14T16:30:05.549424Z","steps":["trace[497087347] 'process raft request' (duration: 227.072071ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:30:05.549477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.013362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:30:05.549536Z","caller":"traceutil/trace.go:171","msg":"trace[1936317454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24125; }","duration":"226.123726ms","start":"2026-10-14T16:30:05.323394Z","end":"2026-10-14T16:30:05.549518Z","steps":["trace[1936317454] 'agreement among raft nodes before linearized reading' (duration: 225.987842ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:30:05.549611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.197584ms","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-10-14T16:30:05.549788Z","caller":"traceutil/trace.go:171","msg":"trace[1374541420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24125; }","duration":"208.399109ms","start":"2026-10-14T16:30:05.341356Z","end":"2026-10-14T16:30:05.549756Z","steps":["trace[1374541420] 'agreement among raft nodes before linearized reading' (duration: 208.05081ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:31:40.787515Z","caller":"traceutil/trace.go:171","msg":"trace[688602501] transaction","detail":"{read_only:false; response_revision:25093; number_of_response:1; }","duration":"119.917676ms","start":"2026-10-14T16:31:40.667568Z","end":"2026-10-14T16:31:40.787486Z","steps":["trace[688602501] 'process raft request' (duration: 119.68551ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:33:01.992654Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23100} {"level":"info","ts":"2026-10-14T16:33:02.121108Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23100,"took":"125.036571ms","hash":3976240693,"current-db-size-bytes":60137472,"current-db-size":"60 MB","current-db-size-in-use-bytes":32882688,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-14T16:33:02.121208Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3976240693,"revision":23100,"compact-revision":20298} {"level":"info","ts":"2026-10-14T16:33:52.539888Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006cf2.wal"} {"level":"info","ts":"2026-10-14T16:34:03.128148Z","caller":"traceutil/trace.go:171","msg":"trace[1012529273] transaction","detail":"{read_only:false; response_revision:26924; number_of_response:1; }","duration":"168.050313ms","start":"2026-10-14T16:34:02.96006Z","end":"2026-10-14T16:34:03.12811Z","steps":["trace[1012529273] 'process raft request' (duration: 167.881308ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:03.485141Z","caller":"traceutil/trace.go:171","msg":"trace[218750051] linearizableReadLoop","detail":"{readStateIndex:28192; appliedIndex:28189; }","duration":"473.687879ms","start":"2026-10-14T16:34:03.011417Z","end":"2026-10-14T16:34:03.485105Z","steps":["trace[218750051] 'read index received' (duration: 116.619239ms)","trace[218750051] 'applied index is now lower than readState.Index' (duration: 357.06708ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:34:03.485312Z","caller":"traceutil/trace.go:171","msg":"trace[1443637876] transaction","detail":"{read_only:false; response_revision:26926; number_of_response:1; }","duration":"514.5098ms","start":"2026-10-14T16:34:02.970789Z","end":"2026-10-14T16:34:03.485299Z","steps":["trace[1443637876] 'process raft request' (duration: 514.13136ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:03.485696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:02.97077Z","time spent":"514.573043ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:34:03.485856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.971679ms","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-10-14T16:34:03.485939Z","caller":"traceutil/trace.go:171","msg":"trace[296607974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26926; }","duration":"458.122782ms","start":"2026-10-14T16:34:03.027798Z","end":"2026-10-14T16:34:03.485921Z","steps":["trace[296607974] 'agreement among raft nodes before linearized reading' (duration: 457.917967ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:03.485981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:03.027756Z","time spent":"458.214695ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-14T16:34:03.486954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.145377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11498"} {"level":"warn","ts":"2026-10-14T16:34:03.487007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.824682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-10-14T16:34:03.487048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.6987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:527"} {"level":"warn","ts":"2026-10-14T16:34:03.487078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.662504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-14T16:34:03.487069Z","caller":"traceutil/trace.go:171","msg":"trace[350576776] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26926; }","duration":"269.329343ms","start":"2026-10-14T16:34:03.217716Z","end":"2026-10-14T16:34:03.487045Z","steps":["trace[350576776] 'agreement among raft nodes before linearized reading' (duration: 268.952952ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:03.487129Z","caller":"traceutil/trace.go:171","msg":"trace[127732699] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26926; }","duration":"475.751547ms","start":"2026-10-14T16:34:03.01136Z","end":"2026-10-14T16:34:03.487111Z","steps":["trace[127732699] 'agreement among raft nodes before linearized reading' (duration: 474.80391ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:03.487134Z","caller":"traceutil/trace.go:171","msg":"trace[1743959167] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:26926; }","duration":"446.826593ms","start":"2026-10-14T16:34:03.040279Z","end":"2026-10-14T16:34:03.487106Z","steps":["trace[1743959167] 'agreement among raft nodes before linearized reading' (duration: 446.546825ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:03.487106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.246361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:623"} {"level":"warn","ts":"2026-10-14T16:34:03.487171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:03.011337Z","time spent":"475.825239ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T16:34:03.487188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:03.040266Z","time spent":"446.910336ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":551,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-10-14T16:34:03.487204Z","caller":"traceutil/trace.go:171","msg":"trace[1431785034] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:26926; }","duration":"269.380824ms","start":"2026-10-14T16:34:03.217798Z","end":"2026-10-14T16:34:03.487178Z","steps":["trace[1431785034] 'agreement among raft nodes before linearized reading' (duration: 269.064945ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:03.487323Z","caller":"traceutil/trace.go:171","msg":"trace[452351205] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26926; }","duration":"353.919595ms","start":"2026-10-14T16:34:03.133165Z","end":"2026-10-14T16:34:03.487084Z","steps":["trace[452351205] 'agreement among raft nodes before linearized reading' (duration: 353.671257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:03.487414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.59649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-14T16:34:03.487416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:03.133064Z","time spent":"354.335275ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-14T16:34:03.487448Z","caller":"traceutil/trace.go:171","msg":"trace[909975352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26926; }","duration":"453.685301ms","start":"2026-10-14T16:34:03.033752Z","end":"2026-10-14T16:34:03.487438Z","steps":["trace[909975352] 'agreement among raft nodes before linearized reading' (duration: 453.583729ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:03.487134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.705824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T16:34:03.487687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.469346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-14T16:34:03.487701Z","caller":"traceutil/trace.go:171","msg":"trace[400332271] transaction","detail":"{read_only:false; response_revision:26925; number_of_response:1; }","duration":"524.198937ms","start":"2026-10-14T16:34:02.9612Z","end":"2026-10-14T16:34:03.485399Z","steps":["trace[400332271] 'process raft request' (duration: 446.129814ms)","trace[400332271] 'compare' (duration: 77.076546ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:34:03.487737Z","caller":"traceutil/trace.go:171","msg":"trace[1964906744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26926; }","duration":"164.298351ms","start":"2026-10-14T16:34:03.323335Z","end":"2026-10-14T16:34:03.487633Z","steps":["trace[1964906744] 'agreement among raft nodes before linearized reading' (duration: 163.627362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:03.487485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:03.033713Z","time spent":"453.765214ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-14T16:34:03.48785Z","caller":"traceutil/trace.go:171","msg":"trace[24225150] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:26926; }","duration":"191.676752ms","start":"2026-10-14T16:34:03.296102Z","end":"2026-10-14T16:34:03.487779Z","steps":["trace[24225150] 'agreement among raft nodes before linearized reading' (duration: 190.727755ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:03.488058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:02.96117Z","time spent":"526.628974ms","remote":"127.0.0.1:38376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:35:31.138069Z","caller":"traceutil/trace.go:171","msg":"trace[412081297] transaction","detail":"{read_only:false; response_revision:28032; number_of_response:1; }","duration":"111.259233ms","start":"2026-10-14T16:35:31.026774Z","end":"2026-10-14T16:35:31.138033Z","steps":["trace[412081297] 'process raft request' (duration: 110.905984ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:36:31.891334Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7481851e1bcdf3c","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T16:36:31.897698Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-14T16:36:31.897808Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"warn","ts":"2026-10-14T16:37:27.213939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.693386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:37:27.214494Z","caller":"traceutil/trace.go:171","msg":"trace[1963516149] transaction","detail":"{read_only:false; response_revision:29249; number_of_response:1; }","duration":"327.437145ms","start":"2026-10-14T16:37:26.887014Z","end":"2026-10-14T16:37:27.214451Z","steps":["trace[1963516149] 'process raft request' (duration: 71.04425ms)","trace[1963516149] 'compare' (duration: 255.425049ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:37:27.214614Z","caller":"traceutil/trace.go:171","msg":"trace[158285445] linearizableReadLoop","detail":"{readStateIndex:30605; appliedIndex:30604; }","duration":"322.188552ms","start":"2026-10-14T16:37:26.89239Z","end":"2026-10-14T16:37:27.214578Z","steps":["trace[158285445] 'read index received' (duration: 65.632222ms)","trace[158285445] 'applied index is now lower than readState.Index' (duration: 256.55434ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:37:27.214721Z","caller":"traceutil/trace.go:171","msg":"trace[889283833] transaction","detail":"{read_only:false; response_revision:29250; number_of_response:1; }","duration":"244.955502ms","start":"2026-10-14T16:37:26.969629Z","end":"2026-10-14T16:37:27.214585Z","steps":["trace[889283833] 'process raft request' (duration: 244.814778ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:37:27.214745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:37:26.886972Z","time spent":"327.661001ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:37:27.215134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.734557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-10-14T16:37:27.215192Z","caller":"traceutil/trace.go:171","msg":"trace[1037538718] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:29250; }","duration":"322.83259ms","start":"2026-10-14T16:37:26.892344Z","end":"2026-10-14T16:37:27.215177Z","steps":["trace[1037538718] 'agreement among raft nodes before linearized reading' (duration: 322.379917ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:37:27.215236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:37:26.892325Z","time spent":"322.901372ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":552,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-10-14T16:37:27.608002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.381732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:37:27.608217Z","caller":"traceutil/trace.go:171","msg":"trace[1966379161] transaction","detail":"{read_only:false; response_revision:29252; number_of_response:1; }","duration":"387.111493ms","start":"2026-10-14T16:37:27.221087Z","end":"2026-10-14T16:37:27.608199Z","steps":["trace[1966379161] 'process raft request' (duration: 387.015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:37:27.608293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:37:27.221048Z","time spent":"387.212136ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:37:27.608433Z","caller":"traceutil/trace.go:171","msg":"trace[2019063987] transaction","detail":"{read_only:false; response_revision:29251; number_of_response:1; }","duration":"562.602509ms","start":"2026-10-14T16:37:27.045796Z","end":"2026-10-14T16:37:27.608398Z","steps":["trace[2019063987] 'process raft request' (duration: 331.723904ms)","trace[2019063987] 'compare' (duration: 230.180416ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:37:27.608739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.85302ms","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-10-14T16:37:27.608945Z","caller":"traceutil/trace.go:171","msg":"trace[962154151] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:29252; }","duration":"620.029024ms","start":"2026-10-14T16:37:26.988818Z","end":"2026-10-14T16:37:27.608847Z","steps":["trace[962154151] 'agreement among raft nodes before linearized reading' (duration: 619.763657ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:37:27.609108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:37:26.988807Z","time spent":"620.284431ms","remote":"127.0.0.1:60954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"info","ts":"2026-10-14T16:37:27.608471Z","caller":"traceutil/trace.go:171","msg":"trace[1366682397] linearizableReadLoop","detail":"{readStateIndex:30607; appliedIndex:30606; }","duration":"393.764476ms","start":"2026-10-14T16:37:27.214694Z","end":"2026-10-14T16:37:27.608459Z","steps":["trace[1366682397] 'read index received' (duration: 162.808049ms)","trace[1366682397] 'applied index is now lower than readState.Index' (duration: 230.955147ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:37:27.608628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:37:27.045773Z","time spent":"562.745323ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:37:27.609033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.660687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-14T16:37:27.610053Z","caller":"traceutil/trace.go:171","msg":"trace[2006403201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:29252; }","duration":"666.737366ms","start":"2026-10-14T16:37:26.943278Z","end":"2026-10-14T16:37:27.610016Z","steps":["trace[2006403201] 'agreement among raft nodes before linearized reading' (duration: 665.217574ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:37:27.610083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.023469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:37:27.610171Z","caller":"traceutil/trace.go:171","msg":"trace[946401357] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:29252; }","duration":"380.179593ms","start":"2026-10-14T16:37:27.229969Z","end":"2026-10-14T16:37:27.610149Z","steps":["trace[946401357] 'agreement among raft nodes before linearized reading' (duration: 380.008957ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:37:27.610175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:37:26.943261Z","time spent":"666.8779ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-14T16:37:27.610201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.802599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T16:37:27.610235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:37:27.229957Z","time spent":"380.263056ms","remote":"127.0.0.1:47996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"info","ts":"2026-10-14T16:37:27.610278Z","caller":"traceutil/trace.go:171","msg":"trace[195582646] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:29252; }","duration":"138.927252ms","start":"2026-10-14T16:37:27.471326Z","end":"2026-10-14T16:37:27.610253Z","steps":["trace[195582646] 'agreement among raft nodes before linearized reading' (duration: 138.785369ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:37:27.611414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.929557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-14T16:37:27.611477Z","caller":"traceutil/trace.go:171","msg":"trace[302510479] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29252; }","duration":"653.04369ms","start":"2026-10-14T16:37:26.958416Z","end":"2026-10-14T16:37:27.611459Z","steps":["trace[302510479] 'agreement among raft nodes before linearized reading' (duration: 650.256454ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:37:27.611516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:37:26.95839Z","time spent":"653.115912ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-14T16:37:28.035615Z","caller":"traceutil/trace.go:171","msg":"trace[1064943228] transaction","detail":"{read_only:false; response_revision:29254; number_of_response:1; }","duration":"418.436062ms","start":"2026-10-14T16:37:27.617147Z","end":"2026-10-14T16:37:28.035583Z","steps":["trace[1064943228] 'process raft request' (duration: 417.901148ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:37:28.035631Z","caller":"traceutil/trace.go:171","msg":"trace[1365418954] transaction","detail":"{read_only:false; response_revision:29253; number_of_response:1; }","duration":"419.45333ms","start":"2026-10-14T16:37:27.616112Z","end":"2026-10-14T16:37:28.035565Z","steps":["trace[1365418954] 'process raft request' (duration: 392.914622ms)","trace[1365418954] 'compare' (duration: 25.606473ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:37:28.035804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:37:27.617121Z","time spent":"418.582247ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:37:28.035914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:37:27.616088Z","time spent":"419.673776ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:37:28.036079Z","caller":"traceutil/trace.go:171","msg":"trace[249195787] transaction","detail":"{read_only:false; response_revision:29255; number_of_response:1; }","duration":"418.891156ms","start":"2026-10-14T16:37:27.617161Z","end":"2026-10-14T16:37:28.036052Z","steps":["trace[249195787] 'process raft request' (duration: 417.938059ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:37:28.036232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:37:27.617119Z","time spent":"419.027ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:37:28.036258Z","caller":"traceutil/trace.go:171","msg":"trace[1104840563] transaction","detail":"{read_only:false; response_revision:29256; number_of_response:1; }","duration":"419.003818ms","start":"2026-10-14T16:37:27.617241Z","end":"2026-10-14T16:37:28.036245Z","steps":["trace[1104840563] 'process raft request' (duration: 418.71635ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:37:28.036325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:37:27.617231Z","time spent":"419.05773ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:37:28.036767Z","caller":"traceutil/trace.go:171","msg":"trace[795672048] transaction","detail":"{read_only:false; response_revision:29257; number_of_response:1; }","duration":"411.315627ms","start":"2026-10-14T16:37:27.625432Z","end":"2026-10-14T16:37:28.036748Z","steps":["trace[795672048] 'process raft request' (duration: 410.764142ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:37:28.036952Z","caller":"traceutil/trace.go:171","msg":"trace[1716533914] linearizableReadLoop","detail":"{readStateIndex:30614; appliedIndex:30608; }","duration":"313.939895ms","start":"2026-10-14T16:37:27.722996Z","end":"2026-10-14T16:37:28.036936Z","steps":["trace[1716533914] 'read index received' (duration: 286.05886ms)","trace[1716533914] 'applied index is now lower than readState.Index' (duration: 27.879655ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:37:28.037057Z","caller":"traceutil/trace.go:171","msg":"trace[1589880386] transaction","detail":"{read_only:false; response_revision:29258; number_of_response:1; }","duration":"346.572071ms","start":"2026-10-14T16:37:27.690475Z","end":"2026-10-14T16:37:28.037047Z","steps":["trace[1589880386] 'process raft request' (duration: 346.189891ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:37:28.037131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:37:27.690429Z","time spent":"346.649593ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:37:28.037241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.143523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:847"} {"level":"info","ts":"2026-10-14T16:37:28.037301Z","caller":"traceutil/trace.go:171","msg":"trace[82383546] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:29258; }","duration":"313.254486ms","start":"2026-10-14T16:37:27.724031Z","end":"2026-10-14T16:37:28.037285Z","steps":["trace[82383546] 'agreement among raft nodes before linearized reading' (duration: 313.071771ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:37:28.03734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:37:27.723987Z","time spent":"313.344669ms","remote":"127.0.0.1:38470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":871,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" "} {"level":"warn","ts":"2026-10-14T16:37:28.037521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.53266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:180"} {"level":"info","ts":"2026-10-14T16:37:28.037617Z","caller":"traceutil/trace.go:171","msg":"trace[478863420] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:29258; }","duration":"314.680125ms","start":"2026-10-14T16:37:27.722925Z","end":"2026-10-14T16:37:28.037605Z","steps":["trace[478863420] 'agreement among raft nodes before linearized reading' (duration: 314.528711ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:37:28.037649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:37:27.722897Z","time spent":"314.744077ms","remote":"127.0.0.1:38470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":204,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"warn","ts":"2026-10-14T16:37:28.036865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:37:27.625415Z","time spent":"411.41052ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:37:28.395981Z","caller":"traceutil/trace.go:171","msg":"trace[1782814365] linearizableReadLoop","detail":"{readStateIndex:30615; appliedIndex:30614; }","duration":"223.700319ms","start":"2026-10-14T16:37:28.172256Z","end":"2026-10-14T16:37:28.395957Z","steps":["trace[1782814365] 'read index received' (duration: 223.416511ms)","trace[1782814365] 'applied index is now lower than readState.Index' (duration: 282.748µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:37:28.396202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.921995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:37:28.39622Z","caller":"traceutil/trace.go:171","msg":"trace[1243958770] transaction","detail":"{read_only:false; response_revision:29259; number_of_response:1; }","duration":"283.12862ms","start":"2026-10-14T16:37:28.113045Z","end":"2026-10-14T16:37:28.396173Z","steps":["trace[1243958770] 'process raft request' (duration: 282.74524ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:37:28.396262Z","caller":"traceutil/trace.go:171","msg":"trace[282357090] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0; response_count:0; response_revision:29259; }","duration":"224.047649ms","start":"2026-10-14T16:37:28.172194Z","end":"2026-10-14T16:37:28.396241Z","steps":["trace[282357090] 'agreement among raft nodes before linearized reading' (duration: 223.907085ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:37:28.396416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.525162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11826"} {"level":"info","ts":"2026-10-14T16:37:28.39655Z","caller":"traceutil/trace.go:171","msg":"trace[992895620] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:29259; }","duration":"102.695918ms","start":"2026-10-14T16:37:28.293804Z","end":"2026-10-14T16:37:28.3965Z","steps":["trace[992895620] 'agreement among raft nodes before linearized reading' (duration: 102.343458ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:38:02.004685Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25786} {"level":"info","ts":"2026-10-14T16:38:02.147096Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25786,"took":"136.363152ms","hash":1193099769,"current-db-size-bytes":60137472,"current-db-size":"60 MB","current-db-size-in-use-bytes":44240896,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-10-14T16:38:02.147302Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1193099769,"revision":25786,"compact-revision":23100} {"level":"info","ts":"2026-10-14T16:38:32.568221Z","caller":"traceutil/trace.go:171","msg":"trace[725272777] linearizableReadLoop","detail":"{readStateIndex:31299; appliedIndex:31298; }","duration":"130.984773ms","start":"2026-10-14T16:38:32.437209Z","end":"2026-10-14T16:38:32.568193Z","steps":["trace[725272777] 'read index received' (duration: 130.823049ms)","trace[725272777] 'applied index is now lower than readState.Index' (duration: 160.234µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:38:32.568491Z","caller":"traceutil/trace.go:171","msg":"trace[2074080332] transaction","detail":"{read_only:false; response_revision:29915; number_of_response:1; }","duration":"152.972247ms","start":"2026-10-14T16:38:32.415506Z","end":"2026-10-14T16:38:32.568478Z","steps":["trace[2074080332] 'process raft request' (duration: 152.521215ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:38:32.568878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.650633ms","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-10-14T16:38:32.568942Z","caller":"traceutil/trace.go:171","msg":"trace[54294027] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29915; }","duration":"131.758776ms","start":"2026-10-14T16:38:32.437168Z","end":"2026-10-14T16:38:32.568927Z","steps":["trace[54294027] 'agreement among raft nodes before linearized reading' (duration: 131.562641ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:38:32.568941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.167288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-14T16:38:32.568979Z","caller":"traceutil/trace.go:171","msg":"trace[779316864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:29915; }","duration":"116.23305ms","start":"2026-10-14T16:38:32.452734Z","end":"2026-10-14T16:38:32.568967Z","steps":["trace[779316864] 'agreement among raft nodes before linearized reading' (duration: 116.120297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:38:32.568954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.536414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-14T16:38:32.569094Z","caller":"traceutil/trace.go:171","msg":"trace[1759615012] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29915; }","duration":"118.710388ms","start":"2026-10-14T16:38:32.450348Z","end":"2026-10-14T16:38:32.569059Z","steps":["trace[1759615012] 'agreement among raft nodes before linearized reading' (duration: 118.340118ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:38:32.712592Z","caller":"traceutil/trace.go:171","msg":"trace[1861767095] transaction","detail":"{read_only:false; response_revision:29916; number_of_response:1; }","duration":"139.588621ms","start":"2026-10-14T16:38:32.572969Z","end":"2026-10-14T16:38:32.712558Z","steps":["trace[1861767095] 'process raft request' (duration: 126.606574ms)","trace[1861767095] 'compare' (duration: 12.808072ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:38:32.739937Z","caller":"traceutil/trace.go:171","msg":"trace[1210110020] linearizableReadLoop","detail":"{readStateIndex:31302; appliedIndex:31299; }","duration":"129.064371ms","start":"2026-10-14T16:38:32.610852Z","end":"2026-10-14T16:38:32.739916Z","steps":["trace[1210110020] 'read index received' (duration: 88.626131ms)","trace[1210110020] 'applied index is now lower than readState.Index' (duration: 40.43724ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:38:32.740129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.270127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11498"} {"level":"info","ts":"2026-10-14T16:38:32.740316Z","caller":"traceutil/trace.go:171","msg":"trace[481062282] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:29919; }","duration":"129.512854ms","start":"2026-10-14T16:38:32.610783Z","end":"2026-10-14T16:38:32.740296Z","steps":["trace[481062282] 'agreement among raft nodes before linearized reading' (duration: 129.209125ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:38:32.740945Z","caller":"traceutil/trace.go:171","msg":"trace[1101102197] transaction","detail":"{read_only:false; response_revision:29917; number_of_response:1; }","duration":"166.632692ms","start":"2026-10-14T16:38:32.574272Z","end":"2026-10-14T16:38:32.740905Z","steps":["trace[1101102197] 'process raft request' (duration: 165.467111ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:38:32.741033Z","caller":"traceutil/trace.go:171","msg":"trace[1418531248] transaction","detail":"{read_only:false; response_revision:29918; number_of_response:1; }","duration":"166.55497ms","start":"2026-10-14T16:38:32.574463Z","end":"2026-10-14T16:38:32.741019Z","steps":["trace[1418531248] 'process raft request' (duration: 165.391809ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:38:32.923973Z","caller":"traceutil/trace.go:171","msg":"trace[912771808] transaction","detail":"{read_only:false; response_revision:29920; number_of_response:1; }","duration":"192.686857ms","start":"2026-10-14T16:38:32.731257Z","end":"2026-10-14T16:38:32.923944Z","steps":["trace[912771808] 'process raft request' (duration: 175.651639ms)","trace[912771808] 'compare' (duration: 16.830952ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:38:32.923988Z","caller":"traceutil/trace.go:171","msg":"trace[1038588000] linearizableReadLoop","detail":"{readStateIndex:31304; appliedIndex:31303; }","duration":"176.708479ms","start":"2026-10-14T16:38:32.747253Z","end":"2026-10-14T16:38:32.923962Z","steps":["trace[1038588000] 'read index received' (duration: 159.667782ms)","trace[1038588000] 'applied index is now lower than readState.Index' (duration: 17.038367ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:38:32.924014Z","caller":"traceutil/trace.go:171","msg":"trace[1582791514] transaction","detail":"{read_only:false; response_revision:29921; number_of_response:1; }","duration":"101.65615ms","start":"2026-10-14T16:38:32.822325Z","end":"2026-10-14T16:38:32.923981Z","steps":["trace[1582791514] 'process raft request' (duration: 101.578988ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:38:32.92423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.965686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11498"} {"level":"info","ts":"2026-10-14T16:38:32.924271Z","caller":"traceutil/trace.go:171","msg":"trace[1980670619] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:29921; }","duration":"177.062648ms","start":"2026-10-14T16:38:32.747197Z","end":"2026-10-14T16:38:32.92426Z","steps":["trace[1980670619] 'agreement among raft nodes before linearized reading' (duration: 176.855173ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:38:33.206055Z","caller":"traceutil/trace.go:171","msg":"trace[849181669] linearizableReadLoop","detail":"{readStateIndex:31306; appliedIndex:31305; }","duration":"280.448186ms","start":"2026-10-14T16:38:32.925589Z","end":"2026-10-14T16:38:33.206037Z","steps":["trace[849181669] 'read index received' (duration: 280.311022ms)","trace[849181669] 'applied index is now lower than readState.Index' (duration: 135.994µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:38:33.206122Z","caller":"traceutil/trace.go:171","msg":"trace[198244837] transaction","detail":"{read_only:false; response_revision:29922; number_of_response:1; }","duration":"282.218545ms","start":"2026-10-14T16:38:32.923876Z","end":"2026-10-14T16:38:33.206094Z","steps":["trace[198244837] 'process raft request' (duration: 282.03747ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:38:33.206201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.090281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:38:33.206237Z","caller":"traceutil/trace.go:171","msg":"trace[2106688614] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:29922; }","duration":"243.149752ms","start":"2026-10-14T16:38:32.963076Z","end":"2026-10-14T16:38:33.206225Z","steps":["trace[2106688614] 'agreement among raft nodes before linearized reading' (duration: 243.07209ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:38:33.206235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.642391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16879"} {"level":"info","ts":"2026-10-14T16:38:33.206267Z","caller":"traceutil/trace.go:171","msg":"trace[52228359] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:29922; }","duration":"280.727103ms","start":"2026-10-14T16:38:32.925532Z","end":"2026-10-14T16:38:33.206259Z","steps":["trace[52228359] 'agreement among raft nodes before linearized reading' (duration: 280.574839ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:38:33.206496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.87948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-14T16:38:33.206658Z","caller":"traceutil/trace.go:171","msg":"trace[210435582] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:29922; }","duration":"151.090046ms","start":"2026-10-14T16:38:33.055495Z","end":"2026-10-14T16:38:33.206585Z","steps":["trace[210435582] 'agreement among raft nodes before linearized reading' (duration: 150.765717ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:38:33.53312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:38:33.214246Z","time spent":"318.86549ms","remote":"127.0.0.1:35978","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-14T16:38:33.533512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.718813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:38:33.533706Z","caller":"traceutil/trace.go:171","msg":"trace[650035930] transaction","detail":"{read_only:false; response_revision:29923; number_of_response:1; }","duration":"321.800411ms","start":"2026-10-14T16:38:33.211877Z","end":"2026-10-14T16:38:33.533678Z","steps":["trace[650035930] 'process raft request' (duration: 157.80127ms)","trace[650035930] 'compare' (duration: 163.564029ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:38:33.533798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:38:33.211854Z","time spent":"321.900643ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:38:33.561682Z","caller":"traceutil/trace.go:171","msg":"trace[1030328428] transaction","detail":"{read_only:false; response_revision:29924; number_of_response:1; }","duration":"292.544867ms","start":"2026-10-14T16:38:33.269111Z","end":"2026-10-14T16:38:33.561656Z","steps":["trace[1030328428] 'process raft request' (duration: 292.29777ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:38:33.562104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.921359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:38:33.562214Z","caller":"traceutil/trace.go:171","msg":"trace[1998770880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29924; }","duration":"238.083643ms","start":"2026-10-14T16:38:33.3241Z","end":"2026-10-14T16:38:33.562184Z","steps":["trace[1998770880] 'agreement among raft nodes before linearized reading' (duration: 237.859017ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:38:33.56227Z","caller":"traceutil/trace.go:171","msg":"trace[158031102] linearizableReadLoop","detail":"{readStateIndex:31308; appliedIndex:31306; }","duration":"237.455376ms","start":"2026-10-14T16:38:33.324163Z","end":"2026-10-14T16:38:33.561619Z","steps":["trace[158031102] 'read index received' (duration: 45.525759ms)","trace[158031102] 'applied index is now lower than readState.Index' (duration: 191.928477ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:38:54.18359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.707906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:38:54.183991Z","caller":"traceutil/trace.go:171","msg":"trace[1332681279] transaction","detail":"{read_only:false; response_revision:30101; number_of_response:1; }","duration":"282.898833ms","start":"2026-10-14T16:38:53.901045Z","end":"2026-10-14T16:38:54.183944Z","steps":["trace[1332681279] 'process raft request' (duration: 53.383116ms)","trace[1332681279] 'compare' (duration: 228.354456ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:38:54.350832Z","caller":"traceutil/trace.go:171","msg":"trace[1332233308] transaction","detail":"{read_only:false; response_revision:30102; number_of_response:1; }","duration":"144.907517ms","start":"2026-10-14T16:38:54.205895Z","end":"2026-10-14T16:38:54.350803Z","steps":["trace[1332233308] 'process raft request' (duration: 110.307888ms)","trace[1332233308] 'compare' (duration: 34.470406ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:38:54.62674Z","caller":"traceutil/trace.go:171","msg":"trace[1044581277] linearizableReadLoop","detail":"{readStateIndex:31495; appliedIndex:31494; }","duration":"302.676177ms","start":"2026-10-14T16:38:54.324035Z","end":"2026-10-14T16:38:54.626711Z","steps":["trace[1044581277] 'read index received' (duration: 293.31141ms)","trace[1044581277] 'applied index is now lower than readState.Index' (duration: 9.363297ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:38:54.626918Z","caller":"traceutil/trace.go:171","msg":"trace[361935522] transaction","detail":"{read_only:false; response_revision:30103; number_of_response:1; }","duration":"372.06836ms","start":"2026-10-14T16:38:54.254776Z","end":"2026-10-14T16:38:54.626844Z","steps":["trace[361935522] 'process raft request' (duration: 362.558668ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:38:54.626942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.889932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:38:54.627081Z","caller":"traceutil/trace.go:171","msg":"trace[1964257511] transaction","detail":"{read_only:false; response_revision:30104; number_of_response:1; }","duration":"223.698298ms","start":"2026-10-14T16:38:54.403316Z","end":"2026-10-14T16:38:54.627015Z","steps":["trace[1964257511] 'process raft request' (duration: 223.302657ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:38:54.627135Z","caller":"traceutil/trace.go:171","msg":"trace[412281208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:30103; }","duration":"303.14171ms","start":"2026-10-14T16:38:54.323974Z","end":"2026-10-14T16:38:54.627115Z","steps":["trace[412281208] 'agreement among raft nodes before linearized reading' (duration: 302.873643ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:38:54.627161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:38:54.254736Z","time spent":"372.286597ms","remote":"127.0.0.1:38708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:38:54.62724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:38:54.32395Z","time spent":"303.240052ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T16:42:14.03854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.827626ms","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-10-14T16:42:14.03867Z","caller":"traceutil/trace.go:171","msg":"trace[1018622892] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:32413; }","duration":"131.033232ms","start":"2026-10-14T16:42:13.907612Z","end":"2026-10-14T16:42:14.038645Z","steps":["trace[1018622892] 'range keys from in-memory index tree' (duration: 130.500557ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:43:02.015283Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29635} {"level":"info","ts":"2026-10-14T16:43:02.207678Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29635,"took":"187.817903ms","hash":1728967744,"current-db-size-bytes":60137472,"current-db-size":"60 MB","current-db-size-in-use-bytes":38051840,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-14T16:43:02.207754Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1728967744,"revision":29635,"compact-revision":25786} {"level":"info","ts":"2026-10-14T16:48:02.023611Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32947} {"level":"info","ts":"2026-10-14T16:48:02.179265Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32947,"took":"151.298049ms","hash":1517039213,"current-db-size-bytes":60137472,"current-db-size":"60 MB","current-db-size-in-use-bytes":35172352,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-14T16:48:02.179341Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1517039213,"revision":32947,"compact-revision":29635} {"level":"info","ts":"2026-10-14T16:52:53.145329Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7481851e1bcdf3c","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T16:52:53.153431Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-14T16:52:53.153965Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-14T16:53:02.031951Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35635} {"level":"info","ts":"2026-10-14T16:53:02.172689Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35635,"took":"136.336362ms","hash":1326859860,"current-db-size-bytes":60137472,"current-db-size":"60 MB","current-db-size-in-use-bytes":34320384,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-14T16:53:02.172768Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1326859860,"revision":35635,"compact-revision":32947} {"level":"info","ts":"2026-10-14T16:58:02.043487Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38341} {"level":"info","ts":"2026-10-14T16:58:02.228221Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38341,"took":"180.147893ms","hash":2011951695,"current-db-size-bytes":61181952,"current-db-size":"61 MB","current-db-size-in-use-bytes":34406400,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-14T16:58:02.228405Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2011951695,"revision":38341,"compact-revision":35635} {"level":"info","ts":"2026-10-14T16:59:22.410791Z","caller":"traceutil/trace.go:171","msg":"trace[2030520723] transaction","detail":"{read_only:false; response_revision:41772; number_of_response:1; }","duration":"100.259572ms","start":"2026-10-14T16:59:22.310494Z","end":"2026-10-14T16:59:22.410753Z","steps":["trace[2030520723] 'process raft request' (duration: 100.041946ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:03:02.054674Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41020} {"level":"info","ts":"2026-10-14T17:03:02.248021Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41020,"took":"185.88656ms","hash":397619115,"current-db-size-bytes":61181952,"current-db-size":"61 MB","current-db-size-in-use-bytes":34177024,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-14T17:03:02.248123Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":397619115,"revision":41020,"compact-revision":38341} {"level":"info","ts":"2026-10-14T17:08:02.060404Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":43824} {"level":"info","ts":"2026-10-14T17:08:02.225879Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":43824,"took":"160.395971ms","hash":497345932,"current-db-size-bytes":61181952,"current-db-size":"61 MB","current-db-size-in-use-bytes":33226752,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-14T17:08:02.225967Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":497345932,"revision":43824,"compact-revision":41020} {"level":"info","ts":"2026-10-14T17:10:16.275315Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7481851e1bcdf3c","local-member-applied-index":50005,"local-member-snapshot-index":40004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T17:10:16.281783Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":50005} {"level":"info","ts":"2026-10-14T17:10:16.281919Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":45005}