{"level":"warn","ts":"2026-10-16T00:07:57.122819Z","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-16T00:07:57.122958Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.136: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.136:2380","--initial-cluster=instance=https://162.253.55.136:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.136:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.136: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-16T00:07:57.123087Z","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-16T00:07:57.123109Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.136:2380"]} {"level":"info","ts":"2026-10-16T00:07:57.123143Z","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-16T00:07:57.124467Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.136:2379"]} {"level":"info","ts":"2026-10-16T00:07:57.124615Z","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.136:2380"],"listen-peer-urls":["https://162.253.55.136:2380"],"advertise-client-urls":["https://162.253.55.136:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.136:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.136: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-16T00:07:57.12807Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.072816ms"} {"level":"info","ts":"2026-10-16T00:07:57.136048Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"308ce60d3e55f7ff","cluster-id":"dd7f8b36a12acab1"} {"level":"info","ts":"2026-10-16T00:07:57.13616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff switched to configuration voters=()"} {"level":"info","ts":"2026-10-16T00:07:57.136195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff became follower at term 0"} {"level":"info","ts":"2026-10-16T00:07:57.136215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 308ce60d3e55f7ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-16T00:07:57.13623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff became follower at term 1"} {"level":"info","ts":"2026-10-16T00:07:57.136287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff switched to configuration voters=(3498423955114817535)"} {"level":"warn","ts":"2026-10-16T00:07:57.14654Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-16T00:07:57.149506Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-16T00:07:57.151812Z","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-16T00:07:57.156581Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"308ce60d3e55f7ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-16T00:07:57.156804Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"308ce60d3e55f7ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-16T00:07:57.156945Z","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-16T00:07:57.157758Z","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-16T00:07:57.157783Z","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-16T00:07:57.15887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff switched to configuration voters=(3498423955114817535)"} {"level":"info","ts":"2026-10-16T00:07:57.159076Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dd7f8b36a12acab1","local-member-id":"308ce60d3e55f7ff","added-peer-id":"308ce60d3e55f7ff","added-peer-peer-urls":["https://162.253.55.136:2380"]} {"level":"info","ts":"2026-10-16T00:07:57.161613Z","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-16T00:07:57.16188Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.136:2380"} {"level":"info","ts":"2026-10-16T00:07:57.161951Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.136:2380"} {"level":"info","ts":"2026-10-16T00:07:57.162028Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"308ce60d3e55f7ff","initial-advertise-peer-urls":["https://162.253.55.136:2380"],"listen-peer-urls":["https://162.253.55.136:2380"],"advertise-client-urls":["https://162.253.55.136:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.136:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-16T00:07:57.162115Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-16T00:07:57.538895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff is starting a new election at term 1"} {"level":"info","ts":"2026-10-16T00:07:57.538952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff became pre-candidate at term 1"} {"level":"info","ts":"2026-10-16T00:07:57.538983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff received MsgPreVoteResp from 308ce60d3e55f7ff at term 1"} {"level":"info","ts":"2026-10-16T00:07:57.539002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff became candidate at term 2"} {"level":"info","ts":"2026-10-16T00:07:57.539013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff received MsgVoteResp from 308ce60d3e55f7ff at term 2"} {"level":"info","ts":"2026-10-16T00:07:57.539028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff became leader at term 2"} {"level":"info","ts":"2026-10-16T00:07:57.53904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 308ce60d3e55f7ff elected leader 308ce60d3e55f7ff at term 2"} {"level":"info","ts":"2026-10-16T00:07:57.54756Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:07:57.547565Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:07:57.547615Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:07:57.5528Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"308ce60d3e55f7ff","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.136:2379]}","request-path":"/0/members/308ce60d3e55f7ff/attributes","cluster-id":"dd7f8b36a12acab1","publish-timeout":"7s"} {"level":"info","ts":"2026-10-16T00:07:57.552829Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.136:2379"} {"level":"info","ts":"2026-10-16T00:07:57.55369Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-16T00:07:57.553759Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-16T00:07:57.554442Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-16T00:07:57.555316Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dd7f8b36a12acab1","local-member-id":"308ce60d3e55f7ff","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:07:57.555608Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:07:57.555679Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:08:19.76029Z","caller":"traceutil/trace.go:171","msg":"trace[481887724] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"180.447053ms","start":"2026-10-16T00:08:19.579796Z","end":"2026-10-16T00:08:19.760243Z","steps":["trace[481887724] 'process raft request' (duration: 180.258968ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:08:31.098565Z","caller":"traceutil/trace.go:171","msg":"trace[1855109294] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"136.291769ms","start":"2026-10-16T00:08:30.962244Z","end":"2026-10-16T00:08:31.098536Z","steps":["trace[1855109294] 'process raft request' (duration: 122.896594ms)","trace[1855109294] 'compare' (duration: 13.312583ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:08:31.098812Z","caller":"traceutil/trace.go:171","msg":"trace[539311755] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"134.7708ms","start":"2026-10-16T00:08:30.96403Z","end":"2026-10-16T00:08:31.098801Z","steps":["trace[539311755] 'process raft request' (duration: 134.715779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:52.095867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.74135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-k7ckk\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-16T00:08:52.09603Z","caller":"traceutil/trace.go:171","msg":"trace[1737953949] range","detail":"{range_begin:/registry/pods/kube-system/cilium-k7ckk; range_end:; response_count:1; response_revision:657; }","duration":"126.922264ms","start":"2026-10-16T00:08:51.969082Z","end":"2026-10-16T00:08:52.096004Z","steps":["trace[1737953949] 'range keys from in-memory index tree' (duration: 126.580576ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:52.096011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.1659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-16T00:08:52.096115Z","caller":"traceutil/trace.go:171","msg":"trace[1039781173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:657; }","duration":"118.304283ms","start":"2026-10-16T00:08:51.977785Z","end":"2026-10-16T00:08:52.096089Z","steps":["trace[1039781173] 'range keys from in-memory index tree' (duration: 118.065807ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:08:52.469291Z","caller":"traceutil/trace.go:171","msg":"trace[144948701] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"352.182523ms","start":"2026-10-16T00:08:52.11706Z","end":"2026-10-16T00:08:52.469243Z","steps":["trace[144948701] 'read index received' (duration: 344.3026ms)","trace[144948701] 'applied index is now lower than readState.Index' (duration: 7.878423ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:08:52.469367Z","caller":"traceutil/trace.go:171","msg":"trace[383916411] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"356.411397ms","start":"2026-10-16T00:08:52.112908Z","end":"2026-10-16T00:08:52.469319Z","steps":["trace[383916411] 'process raft request' (duration: 348.042642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:52.469454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.375638ms","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-16T00:08:52.469507Z","caller":"traceutil/trace.go:171","msg":"trace[569871016] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:659; }","duration":"352.47232ms","start":"2026-10-16T00:08:52.11702Z","end":"2026-10-16T00:08:52.469492Z","steps":["trace[569871016] 'agreement among raft nodes before linearized reading' (duration: 352.334797ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:52.469541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:08:52.117007Z","time spent":"352.525922ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-16T00:08:52.470164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:08:52.112874Z","time spent":"356.612262ms","remote":"127.0.0.1:54228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:08:52.634732Z","caller":"traceutil/trace.go:171","msg":"trace[1885122511] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"473.223914ms","start":"2026-10-16T00:08:52.161475Z","end":"2026-10-16T00:08:52.634698Z","steps":["trace[1885122511] 'process raft request' (duration: 450.858687ms)","trace[1885122511] 'compare' (duration: 21.650889ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:08:52.634793Z","caller":"traceutil/trace.go:171","msg":"trace[629446016] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"160.68027ms","start":"2026-10-16T00:08:52.474083Z","end":"2026-10-16T00:08:52.634763Z","steps":["trace[629446016] 'process raft request' (duration: 160.331372ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:52.634889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:08:52.161441Z","time spent":"473.368047ms","remote":"127.0.0.1:54158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:08:53.26242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.271258ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:08:53.26254Z","caller":"traceutil/trace.go:171","msg":"trace[1719577443] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"550.411331ms","start":"2026-10-16T00:08:52.712111Z","end":"2026-10-16T00:08:53.262522Z","steps":["trace[1719577443] 'process raft request' (duration: 387.759343ms)","trace[1719577443] 'compare' (duration: 162.108895ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:08:53.262692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:08:52.712084Z","time spent":"550.585356ms","remote":"127.0.0.1:54412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-16T00:08:58.338333Z","caller":"traceutil/trace.go:171","msg":"trace[397525336] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:768; }","duration":"145.078069ms","start":"2026-10-16T00:08:58.193229Z","end":"2026-10-16T00:08:58.338307Z","steps":["trace[397525336] 'read index received' (duration: 143.829918ms)","trace[397525336] 'applied index is now lower than readState.Index' (duration: 1.246831ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:08:58.338406Z","caller":"traceutil/trace.go:171","msg":"trace[1464642242] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"160.590487ms","start":"2026-10-16T00:08:58.177783Z","end":"2026-10-16T00:08:58.338374Z","steps":["trace[1464642242] 'process raft request' (duration: 160.061144ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:58.338665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.357205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-16T00:08:58.338864Z","caller":"traceutil/trace.go:171","msg":"trace[1006971243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"145.611611ms","start":"2026-10-16T00:08:58.193176Z","end":"2026-10-16T00:08:58.338788Z","steps":["trace[1006971243] 'agreement among raft nodes before linearized reading' (duration: 145.235672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:58.339312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.520059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-10-16T00:08:58.339373Z","caller":"traceutil/trace.go:171","msg":"trace[2004564774] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:743; }","duration":"100.741423ms","start":"2026-10-16T00:08:58.238615Z","end":"2026-10-16T00:08:58.339357Z","steps":["trace[2004564774] 'agreement among raft nodes before linearized reading' (duration: 99.831371ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:09.420372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.645828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:09:09.420434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.002563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:09:09.420486Z","caller":"traceutil/trace.go:171","msg":"trace[988731223] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:829; }","duration":"317.805873ms","start":"2026-10-16T00:09:09.102656Z","end":"2026-10-16T00:09:09.420462Z","steps":["trace[988731223] 'range keys from in-memory index tree' (duration: 317.575237ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:09.420528Z","caller":"traceutil/trace.go:171","msg":"trace[1527546380] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:829; }","duration":"317.135016ms","start":"2026-10-16T00:09:09.103372Z","end":"2026-10-16T00:09:09.420507Z","steps":["trace[1527546380] 'range keys from in-memory index tree' (duration: 316.948591ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:09.420509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.903381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:09:09.420546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.308271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:09:09.420586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.911466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:09:09.420619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.878314ms","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:5"} {"level":"info","ts":"2026-10-16T00:09:09.420613Z","caller":"traceutil/trace.go:171","msg":"trace[928941349] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:829; }","duration":"353.054715ms","start":"2026-10-16T00:09:09.067539Z","end":"2026-10-16T00:09:09.420594Z","steps":["trace[928941349] 'count revisions from in-memory index tree' (duration: 352.827679ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:09.420629Z","caller":"traceutil/trace.go:171","msg":"trace[1954236604] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:829; }","duration":"335.443874ms","start":"2026-10-16T00:09:09.085167Z","end":"2026-10-16T00:09:09.420611Z","steps":["trace[1954236604] 'range keys from in-memory index tree' (duration: 335.203508ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:09.420651Z","caller":"traceutil/trace.go:171","msg":"trace[740955633] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:829; }","duration":"317.927556ms","start":"2026-10-16T00:09:09.102713Z","end":"2026-10-16T00:09:09.42064Z","steps":["trace[740955633] 'count revisions from in-memory index tree' (duration: 317.825743ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:09.420654Z","caller":"traceutil/trace.go:171","msg":"trace[819055126] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:829; }","duration":"354.028069ms","start":"2026-10-16T00:09:09.06661Z","end":"2026-10-16T00:09:09.420638Z","steps":["trace[819055126] 'range keys from in-memory index tree' (duration: 353.824004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:09.420678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:09.102701Z","time spent":"317.969137ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-10-16T00:09:09.420677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:09.067523Z","time spent":"353.137306ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-16T00:09:09.4207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:09.06659Z","time spent":"354.09423ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-10-16T00:09:09.420575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:09.10264Z","time spent":"317.917465ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-10-16T00:09:09.420742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.771023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7lnts\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-10-16T00:09:09.42082Z","caller":"traceutil/trace.go:171","msg":"trace[320694065] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7lnts; range_end:; response_count:1; response_revision:829; }","duration":"351.867786ms","start":"2026-10-16T00:09:09.06893Z","end":"2026-10-16T00:09:09.420798Z","steps":["trace[320694065] 'range keys from in-memory index tree' (duration: 351.534697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:09.420788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.169162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:09:09.420866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:09.068917Z","time spent":"351.938737ms","remote":"127.0.0.1:54228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7lnts\" "} {"level":"warn","ts":"2026-10-16T00:09:09.420831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.2707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:09:09.420887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.248833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:09:09.420681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:09.085155Z","time spent":"335.511906ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"info","ts":"2026-10-16T00:09:09.420977Z","caller":"traceutil/trace.go:171","msg":"trace[136290306] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:829; }","duration":"336.393577ms","start":"2026-10-16T00:09:09.084552Z","end":"2026-10-16T00:09:09.420945Z","steps":["trace[136290306] 'count revisions from in-memory index tree' (duration: 335.973807ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:09.420578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:09.103363Z","time spent":"317.202638ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-10-16T00:09:09.421072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:09.084504Z","time spent":"336.552601ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-10-16T00:09:09.420962Z","caller":"traceutil/trace.go:171","msg":"trace[693694986] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:829; }","duration":"353.408314ms","start":"2026-10-16T00:09:09.067511Z","end":"2026-10-16T00:09:09.420919Z","steps":["trace[693694986] 'range keys from in-memory index tree' (duration: 353.184288ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:09.421156Z","caller":"traceutil/trace.go:171","msg":"trace[814549244] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:829; }","duration":"336.420388ms","start":"2026-10-16T00:09:09.084552Z","end":"2026-10-16T00:09:09.420972Z","steps":["trace[814549244] 'range keys from in-memory index tree' (duration: 336.077839ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:09.421179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:09.067465Z","time spent":"353.695211ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-10-16T00:09:09.421362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:09.084537Z","time spent":"336.793307ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-10-16T00:09:39.10458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.850014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-16T00:09:39.104672Z","caller":"traceutil/trace.go:171","msg":"trace[199844605] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1227; }","duration":"241.988788ms","start":"2026-10-16T00:09:38.862664Z","end":"2026-10-16T00:09:39.104653Z","steps":["trace[199844605] 'range keys from in-memory index tree' (duration: 241.667221ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:39.104694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.503776ms","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-16T00:09:39.104833Z","caller":"traceutil/trace.go:171","msg":"trace[1185865672] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1227; }","duration":"178.711541ms","start":"2026-10-16T00:09:38.926101Z","end":"2026-10-16T00:09:39.104812Z","steps":["trace[1185865672] 'range keys from in-memory index tree' (duration: 178.188578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:10:28.707508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.704848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c4cf74b4-36bc-4403-8c65-7b0c5604c5ae\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-16T00:10:28.707642Z","caller":"traceutil/trace.go:171","msg":"trace[973715251] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c4cf74b4-36bc-4403-8c65-7b0c5604c5ae; range_end:; response_count:1; response_revision:1906; }","duration":"109.888492ms","start":"2026-10-16T00:10:28.597727Z","end":"2026-10-16T00:10:28.707615Z","steps":["trace[973715251] 'range keys from in-memory index tree' (duration: 109.472712ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:10:29.033699Z","caller":"traceutil/trace.go:171","msg":"trace[879821894] linearizableReadLoop","detail":"{readStateIndex:1981; appliedIndex:1980; }","duration":"279.724783ms","start":"2026-10-16T00:10:28.753946Z","end":"2026-10-16T00:10:29.033671Z","steps":["trace[879821894] 'read index received' (duration: 279.62278ms)","trace[879821894] 'applied index is now lower than readState.Index' (duration: 100.713µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:10:29.033838Z","caller":"traceutil/trace.go:171","msg":"trace[231384671] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"294.434323ms","start":"2026-10-16T00:10:28.739372Z","end":"2026-10-16T00:10:29.033806Z","steps":["trace[231384671] 'process raft request' (duration: 294.146306ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:10:29.033873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.893387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:10:29.033935Z","caller":"traceutil/trace.go:171","msg":"trace[1130762362] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1907; }","duration":"280.00573ms","start":"2026-10-16T00:10:28.753914Z","end":"2026-10-16T00:10:29.03392Z","steps":["trace[1130762362] 'agreement among raft nodes before linearized reading' (duration: 279.888437ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:10:29.053781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.607563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-16T00:10:29.05388Z","caller":"traceutil/trace.go:171","msg":"trace[990602255] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1908; }","duration":"144.755676ms","start":"2026-10-16T00:10:28.909098Z","end":"2026-10-16T00:10:29.053854Z","steps":["trace[990602255] 'agreement among raft nodes before linearized reading' (duration: 144.462089ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:10:41.674448Z","caller":"traceutil/trace.go:171","msg":"trace[2017433453] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"138.895853ms","start":"2026-10-16T00:10:41.535526Z","end":"2026-10-16T00:10:41.674422Z","steps":["trace[2017433453] 'process raft request' (duration: 138.723999ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:10:41.712784Z","caller":"traceutil/trace.go:171","msg":"trace[450769147] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"173.980672ms","start":"2026-10-16T00:10:41.538783Z","end":"2026-10-16T00:10:41.712764Z","steps":["trace[450769147] 'process raft request' (duration: 173.89031ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:24.615563Z","caller":"traceutil/trace.go:171","msg":"trace[843544941] linearizableReadLoop","detail":"{readStateIndex:2343; appliedIndex:2342; }","duration":"165.690567ms","start":"2026-10-16T00:11:24.449842Z","end":"2026-10-16T00:11:24.615532Z","steps":["trace[843544941] 'read index received' (duration: 165.540843ms)","trace[843544941] 'applied index is now lower than readState.Index' (duration: 148.664µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:11:24.61569Z","caller":"traceutil/trace.go:171","msg":"trace[2082490841] transaction","detail":"{read_only:false; response_revision:2256; number_of_response:1; }","duration":"180.745144ms","start":"2026-10-16T00:11:24.434918Z","end":"2026-10-16T00:11:24.615663Z","steps":["trace[2082490841] 'process raft request' (duration: 180.409116ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:24.615871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.017774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-16T00:11:24.615931Z","caller":"traceutil/trace.go:171","msg":"trace[776403500] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2256; }","duration":"166.134067ms","start":"2026-10-16T00:11:24.44978Z","end":"2026-10-16T00:11:24.615914Z","steps":["trace[776403500] 'agreement among raft nodes before linearized reading' (duration: 165.889201ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:25.038462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.224832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:11:25.038761Z","caller":"traceutil/trace.go:171","msg":"trace[601205280] transaction","detail":"{read_only:false; response_revision:2257; number_of_response:1; }","duration":"404.95714ms","start":"2026-10-16T00:11:24.633764Z","end":"2026-10-16T00:11:25.038722Z","steps":["trace[601205280] 'process raft request' (duration: 240.343899ms)","trace[601205280] 'compare' (duration: 163.208928ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:11:25.038788Z","caller":"traceutil/trace.go:171","msg":"trace[637902746] linearizableReadLoop","detail":"{readStateIndex:2344; appliedIndex:2343; }","duration":"379.7066ms","start":"2026-10-16T00:11:24.658976Z","end":"2026-10-16T00:11:25.038682Z","steps":["trace[637902746] 'read index received' (duration: 215.096449ms)","trace[637902746] 'applied index is now lower than readState.Index' (duration: 164.608031ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:11:25.038944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:24.633725Z","time spent":"405.140445ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:11:25.03926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.249613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-16T00:11:25.039395Z","caller":"traceutil/trace.go:171","msg":"trace[1071696814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2257; }","duration":"380.450218ms","start":"2026-10-16T00:11:24.658908Z","end":"2026-10-16T00:11:25.039359Z","steps":["trace[1071696814] 'agreement among raft nodes before linearized reading' (duration: 379.994967ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:25.03945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:24.658889Z","time spent":"380.55167ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-16T00:11:25.043016Z","caller":"traceutil/trace.go:171","msg":"trace[434398311] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"249.014676ms","start":"2026-10-16T00:11:24.793965Z","end":"2026-10-16T00:11:25.04298Z","steps":["trace[434398311] 'process raft request' (duration: 248.77259ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:12:09.050343Z","caller":"traceutil/trace.go:171","msg":"trace[1658942869] transaction","detail":"{read_only:false; response_revision:2668; number_of_response:1; }","duration":"208.963184ms","start":"2026-10-16T00:12:08.841357Z","end":"2026-10-16T00:12:09.05032Z","steps":["trace[1658942869] 'process raft request' (duration: 208.80914ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:17.876801Z","caller":"traceutil/trace.go:171","msg":"trace[169133789] linearizableReadLoop","detail":"{readStateIndex:5320; appliedIndex:5318; }","duration":"142.189848ms","start":"2026-10-16T00:17:17.734588Z","end":"2026-10-16T00:17:17.876778Z","steps":["trace[169133789] 'read index received' (duration: 19.602761ms)","trace[169133789] 'applied index is now lower than readState.Index' (duration: 122.585927ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:17:17.876944Z","caller":"traceutil/trace.go:171","msg":"trace[1552820537] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5122; }","duration":"149.0449ms","start":"2026-10-16T00:17:17.727851Z","end":"2026-10-16T00:17:17.876896Z","steps":["trace[1552820537] 'process raft request' (duration: 108.967626ms)","trace[1552820537] 'compare' (duration: 39.767536ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:17:17.877014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.414164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4849"} {"level":"info","ts":"2026-10-16T00:17:17.87705Z","caller":"traceutil/trace.go:171","msg":"trace[953303714] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5122; }","duration":"142.481085ms","start":"2026-10-16T00:17:17.734559Z","end":"2026-10-16T00:17:17.87704Z","steps":["trace[953303714] 'agreement among raft nodes before linearized reading' (duration: 142.349752ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:17:17.877123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.580527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-16T00:17:17.877211Z","caller":"traceutil/trace.go:171","msg":"trace[565550813] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5122; }","duration":"125.705141ms","start":"2026-10-16T00:17:17.751484Z","end":"2026-10-16T00:17:17.87719Z","steps":["trace[565550813] 'agreement among raft nodes before linearized reading' (duration: 125.458335ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:17:17.877392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.846484ms","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-16T00:17:17.877469Z","caller":"traceutil/trace.go:171","msg":"trace[571100578] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5122; }","duration":"125.936826ms","start":"2026-10-16T00:17:17.751517Z","end":"2026-10-16T00:17:17.877454Z","steps":["trace[571100578] 'agreement among raft nodes before linearized reading' (duration: 125.796593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:17:17.877682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.692636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:531288"} {"level":"info","ts":"2026-10-16T00:17:17.877766Z","caller":"traceutil/trace.go:171","msg":"trace[115899225] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:5122; }","duration":"107.809999ms","start":"2026-10-16T00:17:17.769931Z","end":"2026-10-16T00:17:17.877741Z","steps":["trace[115899225] 'agreement among raft nodes before linearized reading' (duration: 107.386319ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:17:23.427004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.230057ms","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-16T00:17:23.427132Z","caller":"traceutil/trace.go:171","msg":"trace[508767957] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5283; }","duration":"250.409491ms","start":"2026-10-16T00:17:23.176694Z","end":"2026-10-16T00:17:23.427103Z","steps":["trace[508767957] 'range keys from in-memory index tree' (duration: 249.99361ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:17:23.427237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.290243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8f78f118-9589-4c5e-844f-def86f8ba318\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-10-16T00:17:23.427276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.036205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-16T00:17:23.427341Z","caller":"traceutil/trace.go:171","msg":"trace[93818942] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8f78f118-9589-4c5e-844f-def86f8ba318; range_end:; response_count:1; response_revision:5283; }","duration":"283.496068ms","start":"2026-10-16T00:17:23.143819Z","end":"2026-10-16T00:17:23.427315Z","steps":["trace[93818942] 'range keys from in-memory index tree' (duration: 283.13378ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:23.427353Z","caller":"traceutil/trace.go:171","msg":"trace[615273501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5283; }","duration":"272.121887ms","start":"2026-10-16T00:17:23.155214Z","end":"2026-10-16T00:17:23.427336Z","steps":["trace[615273501] 'range keys from in-memory index tree' (duration: 271.841591ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:17:23.427756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.140316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786916"} {"level":"info","ts":"2026-10-16T00:17:23.428057Z","caller":"traceutil/trace.go:171","msg":"trace[1864845137] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5283; }","duration":"311.470304ms","start":"2026-10-16T00:17:23.116571Z","end":"2026-10-16T00:17:23.428041Z","steps":["trace[1864845137] 'range keys from in-memory index tree' (duration: 310.701486ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:17:23.428101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:17:23.116554Z","time spent":"311.533925ms","remote":"127.0.0.1:54158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":25,"response size":786939,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"info","ts":"2026-10-16T00:17:23.914223Z","caller":"traceutil/trace.go:171","msg":"trace[931557216] transaction","detail":"{read_only:false; response_revision:5288; number_of_response:1; }","duration":"186.567691ms","start":"2026-10-16T00:17:23.727622Z","end":"2026-10-16T00:17:23.914189Z","steps":["trace[931557216] 'process raft request' (duration: 186.366926ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:36.548294Z","caller":"traceutil/trace.go:171","msg":"trace[1787764362] transaction","detail":"{read_only:false; response_revision:5528; number_of_response:1; }","duration":"135.581847ms","start":"2026-10-16T00:17:36.412684Z","end":"2026-10-16T00:17:36.548266Z","steps":["trace[1787764362] 'process raft request' (duration: 135.533976ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:36.548914Z","caller":"traceutil/trace.go:171","msg":"trace[1508002310] transaction","detail":"{read_only:false; response_revision:5527; number_of_response:1; }","duration":"140.546916ms","start":"2026-10-16T00:17:36.408332Z","end":"2026-10-16T00:17:36.548879Z","steps":["trace[1508002310] 'process raft request' (duration: 75.314273ms)","trace[1508002310] 'compare' (duration: 64.357982ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:17:37.07164Z","caller":"traceutil/trace.go:171","msg":"trace[1779519464] linearizableReadLoop","detail":"{readStateIndex:5740; appliedIndex:5739; }","duration":"270.016267ms","start":"2026-10-16T00:17:36.801599Z","end":"2026-10-16T00:17:37.071616Z","steps":["trace[1779519464] 'read index received' (duration: 269.937905ms)","trace[1779519464] 'applied index is now lower than readState.Index' (duration: 77.342µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:17:37.071698Z","caller":"traceutil/trace.go:171","msg":"trace[1908663634] transaction","detail":"{read_only:false; response_revision:5530; number_of_response:1; }","duration":"273.456028ms","start":"2026-10-16T00:17:36.798201Z","end":"2026-10-16T00:17:37.071657Z","steps":["trace[1908663634] 'process raft request' (duration: 273.278053ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:17:37.071873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.253802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-vt8h9\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-10-16T00:17:37.071916Z","caller":"traceutil/trace.go:171","msg":"trace[591894307] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-vt8h9; range_end:; response_count:1; response_revision:5530; }","duration":"270.357444ms","start":"2026-10-16T00:17:36.801545Z","end":"2026-10-16T00:17:37.071903Z","steps":["trace[591894307] 'agreement among raft nodes before linearized reading' (duration: 270.1775ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:37.181987Z","caller":"traceutil/trace.go:171","msg":"trace[1587100623] linearizableReadLoop","detail":"{readStateIndex:5741; appliedIndex:5740; }","duration":"110.265384ms","start":"2026-10-16T00:17:37.071703Z","end":"2026-10-16T00:17:37.181969Z","steps":["trace[1587100623] 'read index received' (duration: 89.386047ms)","trace[1587100623] 'applied index is now lower than readState.Index' (duration: 20.878167ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:17:37.182065Z","caller":"traceutil/trace.go:171","msg":"trace[1236983873] transaction","detail":"{read_only:false; response_revision:5531; number_of_response:1; }","duration":"213.842709ms","start":"2026-10-16T00:17:36.9682Z","end":"2026-10-16T00:17:37.182043Z","steps":["trace[1236983873] 'process raft request' (duration: 192.87559ms)","trace[1236983873] 'compare' (duration: 20.541649ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:17:37.182093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.728006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T00:17:37.182146Z","caller":"traceutil/trace.go:171","msg":"trace[556803831] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:5531; }","duration":"339.816478ms","start":"2026-10-16T00:17:36.842316Z","end":"2026-10-16T00:17:37.182132Z","steps":["trace[556803831] 'agreement among raft nodes before linearized reading' (duration: 339.727376ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:17:37.18218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:17:36.842302Z","time spent":"339.871449ms","remote":"127.0.0.1:60538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":2,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-10-16T00:17:37.182237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.056466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-16T00:17:37.182308Z","caller":"traceutil/trace.go:171","msg":"trace[868314934] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5531; }","duration":"380.177278ms","start":"2026-10-16T00:17:36.802117Z","end":"2026-10-16T00:17:37.182294Z","steps":["trace[868314934] 'agreement among raft nodes before linearized reading' (duration: 379.936682ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:17:37.182417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:17:36.802097Z","time spent":"380.266241ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-16T00:17:37.189163Z","caller":"traceutil/trace.go:171","msg":"trace[1582652495] transaction","detail":"{read_only:false; response_revision:5533; number_of_response:1; }","duration":"105.596603ms","start":"2026-10-16T00:17:37.08355Z","end":"2026-10-16T00:17:37.189146Z","steps":["trace[1582652495] 'process raft request' (duration: 105.424419ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:37.189273Z","caller":"traceutil/trace.go:171","msg":"trace[837316892] transaction","detail":"{read_only:false; response_revision:5532; number_of_response:1; }","duration":"114.049485ms","start":"2026-10-16T00:17:37.075217Z","end":"2026-10-16T00:17:37.189266Z","steps":["trace[837316892] 'process raft request' (duration: 113.633675ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:46.135002Z","caller":"traceutil/trace.go:171","msg":"trace[1009302524] linearizableReadLoop","detail":"{readStateIndex:5923; appliedIndex:5922; }","duration":"146.583169ms","start":"2026-10-16T00:17:45.988397Z","end":"2026-10-16T00:17:46.13498Z","steps":["trace[1009302524] 'read index received' (duration: 146.521807ms)","trace[1009302524] 'applied index is now lower than readState.Index' (duration: 60.462µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:17:46.135164Z","caller":"traceutil/trace.go:171","msg":"trace[1899711214] transaction","detail":"{read_only:false; response_revision:5709; number_of_response:1; }","duration":"227.180217ms","start":"2026-10-16T00:17:45.90795Z","end":"2026-10-16T00:17:46.13513Z","steps":["trace[1899711214] 'process raft request' (duration: 226.903391ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:17:46.135257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.842056ms","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-16T00:17:46.135292Z","caller":"traceutil/trace.go:171","msg":"trace[214786145] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5709; }","duration":"146.942288ms","start":"2026-10-16T00:17:45.988339Z","end":"2026-10-16T00:17:46.135282Z","steps":["trace[214786145] 'agreement among raft nodes before linearized reading' (duration: 146.771694ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:17:46.396268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.953782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:17:46.396644Z","caller":"traceutil/trace.go:171","msg":"trace[33181029] linearizableReadLoop","detail":"{readStateIndex:5924; appliedIndex:5923; }","duration":"256.812103ms","start":"2026-10-16T00:17:46.139798Z","end":"2026-10-16T00:17:46.39661Z","steps":["trace[33181029] 'read index received' (duration: 29.438001ms)","trace[33181029] 'applied index is now lower than readState.Index' (duration: 227.371372ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:17:46.396692Z","caller":"traceutil/trace.go:171","msg":"trace[251008609] transaction","detail":"{read_only:false; response_revision:5712; number_of_response:1; }","duration":"256.036104ms","start":"2026-10-16T00:17:46.140623Z","end":"2026-10-16T00:17:46.396659Z","steps":["trace[251008609] 'process raft request' (duration: 255.82923ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:46.396765Z","caller":"traceutil/trace.go:171","msg":"trace[1248377499] transaction","detail":"{read_only:false; response_revision:5714; number_of_response:1; }","duration":"255.695707ms","start":"2026-10-16T00:17:46.14105Z","end":"2026-10-16T00:17:46.396745Z","steps":["trace[1248377499] 'process raft request' (duration: 255.587724ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:46.39673Z","caller":"traceutil/trace.go:171","msg":"trace[1510207964] transaction","detail":"{read_only:false; response_revision:5713; number_of_response:1; }","duration":"255.882521ms","start":"2026-10-16T00:17:46.140813Z","end":"2026-10-16T00:17:46.396695Z","steps":["trace[1510207964] 'process raft request' (duration: 255.732678ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:46.396764Z","caller":"traceutil/trace.go:171","msg":"trace[643414789] transaction","detail":"{read_only:false; response_revision:5715; number_of_response:1; }","duration":"255.39599ms","start":"2026-10-16T00:17:46.141354Z","end":"2026-10-16T00:17:46.39675Z","steps":["trace[643414789] 'process raft request' (duration: 255.324838ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:46.3969Z","caller":"traceutil/trace.go:171","msg":"trace[699304180] transaction","detail":"{read_only:false; response_revision:5716; number_of_response:1; }","duration":"254.98037ms","start":"2026-10-16T00:17:46.141904Z","end":"2026-10-16T00:17:46.396884Z","steps":["trace[699304180] 'process raft request' (duration: 254.812136ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:17:46.39694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.11509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-8f78f118-9589-4c5e-844f-def86f8ba318\" ","response":"range_response_count:1 size:1275"} {"level":"info","ts":"2026-10-16T00:17:46.397038Z","caller":"traceutil/trace.go:171","msg":"trace[1222017030] range","detail":"{range_begin:/registry/persistentvolumes/pvc-8f78f118-9589-4c5e-844f-def86f8ba318; range_end:; response_count:1; response_revision:5715; }","duration":"257.241344ms","start":"2026-10-16T00:17:46.139767Z","end":"2026-10-16T00:17:46.397009Z","steps":["trace[1222017030] 'agreement among raft nodes before linearized reading' (duration: 256.963097ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:46.397093Z","caller":"traceutil/trace.go:171","msg":"trace[521801339] transaction","detail":"{read_only:false; response_revision:5717; number_of_response:1; }","duration":"254.570139ms","start":"2026-10-16T00:17:46.142508Z","end":"2026-10-16T00:17:46.397078Z","steps":["trace[521801339] 'process raft request' (duration: 254.331874ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:46.397191Z","caller":"traceutil/trace.go:171","msg":"trace[1770283771] transaction","detail":"{read_only:false; response_revision:5710; number_of_response:1; }","duration":"257.271965ms","start":"2026-10-16T00:17:46.139264Z","end":"2026-10-16T00:17:46.396536Z","steps":["trace[1770283771] 'process raft request' (duration: 29.965984ms)","trace[1770283771] 'compare' (duration: 226.830579ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:17:46.396667Z","caller":"traceutil/trace.go:171","msg":"trace[368796336] transaction","detail":"{read_only:false; response_revision:5711; number_of_response:1; }","duration":"256.797633ms","start":"2026-10-16T00:17:46.139836Z","end":"2026-10-16T00:17:46.396633Z","steps":["trace[368796336] 'process raft request' (duration: 256.567647ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:17:46.584882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.034872ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-16T00:17:46.585078Z","caller":"traceutil/trace.go:171","msg":"trace[1402661098] linearizableReadLoop","detail":"{readStateIndex:5932; appliedIndex:5931; }","duration":"188.342154ms","start":"2026-10-16T00:17:46.396702Z","end":"2026-10-16T00:17:46.585044Z","steps":["trace[1402661098] 'read index received' (duration: 1.011395ms)","trace[1402661098] 'applied index is now lower than readState.Index' (duration: 187.328049ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:17:46.585305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.984642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d458d035-9976-4104-b04f-6ace97882802\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-16T00:17:46.585411Z","caller":"traceutil/trace.go:171","msg":"trace[1400840508] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d458d035-9976-4104-b04f-6ace97882802; range_end:; response_count:1; response_revision:5717; }","duration":"445.127096ms","start":"2026-10-16T00:17:46.140265Z","end":"2026-10-16T00:17:46.585392Z","steps":["trace[1400840508] 'agreement among raft nodes before linearized reading' (duration: 444.85733ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:17:46.585422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.042099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-16T00:17:46.585535Z","caller":"traceutil/trace.go:171","msg":"trace[1673517897] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5717; }","duration":"265.218773ms","start":"2026-10-16T00:17:46.320288Z","end":"2026-10-16T00:17:46.585507Z","steps":["trace[1673517897] 'agreement among raft nodes before linearized reading' (duration: 264.843134ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:17:46.585453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:17:46.14025Z","time spent":"445.193407ms","remote":"127.0.0.1:54228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d458d035-9976-4104-b04f-6ace97882802\" "} {"level":"warn","ts":"2026-10-16T00:17:46.58644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.329287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-10-16T00:17:46.58651Z","caller":"traceutil/trace.go:171","msg":"trace[165909723] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5717; }","duration":"110.443659ms","start":"2026-10-16T00:17:46.476052Z","end":"2026-10-16T00:17:46.586496Z","steps":["trace[165909723] 'agreement among raft nodes before linearized reading' (duration: 109.280902ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:58.05009Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2957} {"level":"warn","ts":"2026-10-16T00:17:58.170904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.247891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-10-16T00:17:58.170987Z","caller":"traceutil/trace.go:171","msg":"trace[92052414] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5847; }","duration":"104.392095ms","start":"2026-10-16T00:17:58.066577Z","end":"2026-10-16T00:17:58.17097Z","steps":["trace[92052414] 'range keys from in-memory index tree' (duration: 104.034997ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:58.289549Z","caller":"traceutil/trace.go:171","msg":"trace[629971259] transaction","detail":"{read_only:false; response_revision:5848; number_of_response:1; }","duration":"106.223429ms","start":"2026-10-16T00:17:58.183288Z","end":"2026-10-16T00:17:58.289511Z","steps":["trace[629971259] 'process raft request' (duration: 71.233225ms)","trace[629971259] 'compare' (duration: 12.030457ms)","trace[629971259] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/pods/openstack/rabbitmq-keystone-server-0; req_size:10667; } (duration: 22.761332ms)"],"step_count":3} {"level":"info","ts":"2026-10-16T00:17:58.33095Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2957,"took":"279.039732ms","hash":1088445927,"current-db-size-bytes":56659968,"current-db-size":"57 MB","current-db-size-in-use-bytes":32096256,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T00:17:58.33102Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1088445927,"revision":2957,"compact-revision":-1} {"level":"info","ts":"2026-10-16T00:18:40.815834Z","caller":"traceutil/trace.go:171","msg":"trace[1249989715] transaction","detail":"{read_only:false; response_revision:6229; number_of_response:1; }","duration":"123.996345ms","start":"2026-10-16T00:18:40.691813Z","end":"2026-10-16T00:18:40.815809Z","steps":["trace[1249989715] 'process raft request' (duration: 123.8032ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:18:40.822956Z","caller":"traceutil/trace.go:171","msg":"trace[913678572] transaction","detail":"{read_only:false; response_revision:6230; number_of_response:1; }","duration":"127.093308ms","start":"2026-10-16T00:18:40.695839Z","end":"2026-10-16T00:18:40.822933Z","steps":["trace[913678572] 'process raft request' (duration: 126.942544ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:18:40.823408Z","caller":"traceutil/trace.go:171","msg":"trace[850498647] linearizableReadLoop","detail":"{readStateIndex:6459; appliedIndex:6458; }","duration":"104.925566ms","start":"2026-10-16T00:18:40.718459Z","end":"2026-10-16T00:18:40.823384Z","steps":["trace[850498647] 'read index received' (duration: 97.439019ms)","trace[850498647] 'applied index is now lower than readState.Index' (duration: 7.485586ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:18:40.82445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.96156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225408"} {"level":"info","ts":"2026-10-16T00:18:40.824506Z","caller":"traceutil/trace.go:171","msg":"trace[335813130] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:6232; }","duration":"106.067182ms","start":"2026-10-16T00:18:40.718425Z","end":"2026-10-16T00:18:40.824493Z","steps":["trace[335813130] 'agreement among raft nodes before linearized reading' (duration: 105.060128ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:22:58.057776Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5847} {"level":"info","ts":"2026-10-16T00:22:58.153037Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5847,"took":"93.284836ms","hash":3748852239,"current-db-size-bytes":56659968,"current-db-size":"57 MB","current-db-size-in-use-bytes":26632192,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-16T00:22:58.153107Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3748852239,"revision":5847,"compact-revision":2957} {"level":"info","ts":"2026-10-16T00:24:01.808022Z","caller":"traceutil/trace.go:171","msg":"trace[1967113038] linearizableReadLoop","detail":"{readStateIndex:8895; appliedIndex:8894; }","duration":"172.056487ms","start":"2026-10-16T00:24:01.635939Z","end":"2026-10-16T00:24:01.807996Z","steps":["trace[1967113038] 'read index received' (duration: 171.79787ms)","trace[1967113038] 'applied index is now lower than readState.Index' (duration: 257.737µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:24:01.808054Z","caller":"traceutil/trace.go:171","msg":"trace[2009872817] transaction","detail":"{read_only:false; response_revision:8588; number_of_response:1; }","duration":"214.559296ms","start":"2026-10-16T00:24:01.593468Z","end":"2026-10-16T00:24:01.808028Z","steps":["trace[2009872817] 'process raft request' (duration: 214.363732ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:24:01.808196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.254871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-16T00:24:01.808231Z","caller":"traceutil/trace.go:171","msg":"trace[21382855] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8588; }","duration":"172.342963ms","start":"2026-10-16T00:24:01.635877Z","end":"2026-10-16T00:24:01.80822Z","steps":["trace[21382855] 'agreement among raft nodes before linearized reading' (duration: 172.22119ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:24:02.042518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.995382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:24:02.042694Z","caller":"traceutil/trace.go:171","msg":"trace[257763225] linearizableReadLoop","detail":"{readStateIndex:8896; appliedIndex:8895; }","duration":"168.864121ms","start":"2026-10-16T00:24:01.873785Z","end":"2026-10-16T00:24:02.042649Z","steps":["trace[257763225] 'read index received' (duration: 34.781µs)","trace[257763225] 'applied index is now lower than readState.Index' (duration: 168.82797ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:24:02.042885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.092396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-10-16T00:24:02.042923Z","caller":"traceutil/trace.go:171","msg":"trace[1906917523] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8589; }","duration":"169.171938ms","start":"2026-10-16T00:24:01.873741Z","end":"2026-10-16T00:24:02.042913Z","steps":["trace[1906917523] 'agreement among raft nodes before linearized reading' (duration: 168.994854ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:02.042905Z","caller":"traceutil/trace.go:171","msg":"trace[1871612737] transaction","detail":"{read_only:false; response_revision:8589; number_of_response:1; }","duration":"229.815378ms","start":"2026-10-16T00:24:01.813057Z","end":"2026-10-16T00:24:02.042873Z","steps":["trace[1871612737] 'process raft request' (duration: 18.354325ms)","trace[1871612737] 'compare' (duration: 210.832958ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:24:13.412093Z","caller":"traceutil/trace.go:171","msg":"trace[751148574] linearizableReadLoop","detail":"{readStateIndex:8960; appliedIndex:8959; }","duration":"112.963103ms","start":"2026-10-16T00:24:13.299095Z","end":"2026-10-16T00:24:13.412058Z","steps":["trace[751148574] 'read index received' (duration: 75.162375ms)","trace[751148574] 'applied index is now lower than readState.Index' (duration: 37.798958ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:24:13.412254Z","caller":"traceutil/trace.go:171","msg":"trace[2100156556] transaction","detail":"{read_only:false; response_revision:8651; number_of_response:1; }","duration":"128.106172ms","start":"2026-10-16T00:24:13.284126Z","end":"2026-10-16T00:24:13.412232Z","steps":["trace[2100156556] 'process raft request' (duration: 90.230252ms)","trace[2100156556] 'compare' (duration: 37.587103ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:24:13.412575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.451344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zlpcv\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-16T00:24:13.412689Z","caller":"traceutil/trace.go:171","msg":"trace[1659134736] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zlpcv; range_end:; response_count:1; response_revision:8651; }","duration":"113.640168ms","start":"2026-10-16T00:24:13.299023Z","end":"2026-10-16T00:24:13.412663Z","steps":["trace[1659134736] 'agreement among raft nodes before linearized reading' (duration: 113.333691ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:14.119368Z","caller":"traceutil/trace.go:171","msg":"trace[1880154584] transaction","detail":"{read_only:false; response_revision:8653; number_of_response:1; }","duration":"240.382359ms","start":"2026-10-16T00:24:13.87896Z","end":"2026-10-16T00:24:14.119343Z","steps":["trace[1880154584] 'process raft request' (duration: 240.244096ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:14.274704Z","caller":"traceutil/trace.go:171","msg":"trace[2107781743] linearizableReadLoop","detail":"{readStateIndex:8965; appliedIndex:8962; }","duration":"116.422064ms","start":"2026-10-16T00:24:14.158253Z","end":"2026-10-16T00:24:14.274675Z","steps":["trace[2107781743] 'read index received' (duration: 49.413644ms)","trace[2107781743] 'applied index is now lower than readState.Index' (duration: 67.00673ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:24:14.274773Z","caller":"traceutil/trace.go:171","msg":"trace[1115964403] transaction","detail":"{read_only:false; response_revision:8656; number_of_response:1; }","duration":"116.650459ms","start":"2026-10-16T00:24:14.15806Z","end":"2026-10-16T00:24:14.27471Z","steps":["trace[1115964403] 'process raft request' (duration: 116.537846ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:14.274766Z","caller":"traceutil/trace.go:171","msg":"trace[1390221162] transaction","detail":"{read_only:false; response_revision:8654; number_of_response:1; }","duration":"150.206337ms","start":"2026-10-16T00:24:14.124494Z","end":"2026-10-16T00:24:14.2747Z","steps":["trace[1390221162] 'process raft request' (duration: 83.158715ms)","trace[1390221162] 'compare' (duration: 66.56748ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:24:14.27473Z","caller":"traceutil/trace.go:171","msg":"trace[309794243] transaction","detail":"{read_only:false; response_revision:8655; number_of_response:1; }","duration":"118.138025ms","start":"2026-10-16T00:24:14.156558Z","end":"2026-10-16T00:24:14.274696Z","steps":["trace[309794243] 'process raft request' (duration: 117.968091ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:24:14.27495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.66177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:24:14.275007Z","caller":"traceutil/trace.go:171","msg":"trace[542007182] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8656; }","duration":"116.778593ms","start":"2026-10-16T00:24:14.158216Z","end":"2026-10-16T00:24:14.274995Z","steps":["trace[542007182] 'agreement among raft nodes before linearized reading' (duration: 116.617899ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:40.643477Z","caller":"traceutil/trace.go:171","msg":"trace[274140235] transaction","detail":"{read_only:false; response_revision:8850; number_of_response:1; }","duration":"108.535128ms","start":"2026-10-16T00:24:40.534901Z","end":"2026-10-16T00:24:40.643436Z","steps":["trace[274140235] 'process raft request' (duration: 108.276982ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:43.59011Z","caller":"traceutil/trace.go:171","msg":"trace[1357982590] linearizableReadLoop","detail":"{readStateIndex:9183; appliedIndex:9182; }","duration":"115.195086ms","start":"2026-10-16T00:24:43.474885Z","end":"2026-10-16T00:24:43.59008Z","steps":["trace[1357982590] 'read index received' (duration: 115.168455ms)","trace[1357982590] 'applied index is now lower than readState.Index' (duration: 25.241µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:24:43.590205Z","caller":"traceutil/trace.go:171","msg":"trace[1522259619] transaction","detail":"{read_only:false; response_revision:8865; number_of_response:1; }","duration":"150.077864ms","start":"2026-10-16T00:24:43.440093Z","end":"2026-10-16T00:24:43.590171Z","steps":["trace[1522259619] 'process raft request' (duration: 149.838828ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:24:43.590296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.37756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T00:24:43.590349Z","caller":"traceutil/trace.go:171","msg":"trace[908030628] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8865; }","duration":"115.491953ms","start":"2026-10-16T00:24:43.474841Z","end":"2026-10-16T00:24:43.590333Z","steps":["trace[908030628] 'agreement among raft nodes before linearized reading' (duration: 115.38201ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:43.790852Z","caller":"traceutil/trace.go:171","msg":"trace[2081492680] linearizableReadLoop","detail":"{readStateIndex:9184; appliedIndex:9183; }","duration":"200.633915ms","start":"2026-10-16T00:24:43.590191Z","end":"2026-10-16T00:24:43.790825Z","steps":["trace[2081492680] 'read index received' (duration: 128.762888ms)","trace[2081492680] 'applied index is now lower than readState.Index' (duration: 71.862117ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:24:43.7909Z","caller":"traceutil/trace.go:171","msg":"trace[861259824] transaction","detail":"{read_only:false; response_revision:8866; number_of_response:1; }","duration":"244.779803ms","start":"2026-10-16T00:24:43.546076Z","end":"2026-10-16T00:24:43.790855Z","steps":["trace[861259824] 'process raft request' (duration: 172.971588ms)","trace[861259824] 'compare' (duration: 71.622861ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:24:43.791118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.610971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:24:43.791174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.057123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-16T00:24:43.791229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.393434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zlpcv\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-16T00:24:43.791254Z","caller":"traceutil/trace.go:171","msg":"trace[1288547148] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:8866; }","duration":"199.801405ms","start":"2026-10-16T00:24:43.591424Z","end":"2026-10-16T00:24:43.791225Z","steps":["trace[1288547148] 'agreement among raft nodes before linearized reading' (duration: 199.58964ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:43.791271Z","caller":"traceutil/trace.go:171","msg":"trace[1014580291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8866; }","duration":"238.159467ms","start":"2026-10-16T00:24:43.553089Z","end":"2026-10-16T00:24:43.791248Z","steps":["trace[1014580291] 'agreement among raft nodes before linearized reading' (duration: 237.86132ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:43.791306Z","caller":"traceutil/trace.go:171","msg":"trace[843960221] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zlpcv; range_end:; response_count:1; response_revision:8866; }","duration":"145.551127ms","start":"2026-10-16T00:24:43.645735Z","end":"2026-10-16T00:24:43.791286Z","steps":["trace[843960221] 'agreement among raft nodes before linearized reading' (duration: 145.28797ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:24:43.791641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.115955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:24:43.791724Z","caller":"traceutil/trace.go:171","msg":"trace[1135652105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8866; }","duration":"238.211768ms","start":"2026-10-16T00:24:43.553497Z","end":"2026-10-16T00:24:43.791709Z","steps":["trace[1135652105] 'agreement among raft nodes before linearized reading' (duration: 237.422769ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:24:43.791837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.654843ms","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-16T00:24:43.791895Z","caller":"traceutil/trace.go:171","msg":"trace[1447338321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8866; }","duration":"210.749986ms","start":"2026-10-16T00:24:43.581128Z","end":"2026-10-16T00:24:43.791878Z","steps":["trace[1447338321] 'agreement among raft nodes before linearized reading' (duration: 209.760122ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:24:52.848376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.343038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:24:52.848462Z","caller":"traceutil/trace.go:171","msg":"trace[1898390841] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:8917; }","duration":"152.474411ms","start":"2026-10-16T00:24:52.695967Z","end":"2026-10-16T00:24:52.848441Z","steps":["trace[1898390841] 'count revisions from in-memory index tree' (duration: 152.247786ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:24:54.301803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.653845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-648df85c55-jhzcc.18dedac353b4bbad\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-10-16T00:24:54.301886Z","caller":"traceutil/trace.go:171","msg":"trace[406397166] range","detail":"{range_begin:/registry/events/openstack/barbican-api-648df85c55-jhzcc.18dedac353b4bbad; range_end:; response_count:1; response_revision:8923; }","duration":"101.747237ms","start":"2026-10-16T00:24:54.200119Z","end":"2026-10-16T00:24:54.301866Z","steps":["trace[406397166] 'range keys from in-memory index tree' (duration: 101.431919ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:24:57.170318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.245111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-vh6b5\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-10-16T00:24:57.170405Z","caller":"traceutil/trace.go:171","msg":"trace[1931010107] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-vh6b5; range_end:; response_count:1; response_revision:8952; }","duration":"129.370403ms","start":"2026-10-16T00:24:57.041014Z","end":"2026-10-16T00:24:57.170385Z","steps":["trace[1931010107] 'range keys from in-memory index tree' (duration: 129.162419ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:25:18.967766Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002516.wal"} {"level":"info","ts":"2026-10-16T00:26:03.889832Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"308ce60d3e55f7ff","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T00:26:03.897377Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-16T00:26:03.897612Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-16T00:26:10.619395Z","caller":"traceutil/trace.go:171","msg":"trace[781975810] transaction","detail":"{read_only:false; response_revision:9711; number_of_response:1; }","duration":"123.171855ms","start":"2026-10-16T00:26:10.496185Z","end":"2026-10-16T00:26:10.619357Z","steps":["trace[781975810] 'process raft request' (duration: 122.93858ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:26:23.551523Z","caller":"traceutil/trace.go:171","msg":"trace[644513131] linearizableReadLoop","detail":"{readStateIndex:10134; appliedIndex:10133; }","duration":"188.491517ms","start":"2026-10-16T00:26:23.363007Z","end":"2026-10-16T00:26:23.551499Z","steps":["trace[644513131] 'read index received' (duration: 188.239361ms)","trace[644513131] 'applied index is now lower than readState.Index' (duration: 251.066µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:26:23.551611Z","caller":"traceutil/trace.go:171","msg":"trace[1631702347] transaction","detail":"{read_only:false; response_revision:9787; number_of_response:1; }","duration":"196.377935ms","start":"2026-10-16T00:26:23.355211Z","end":"2026-10-16T00:26:23.551589Z","steps":["trace[1631702347] 'process raft request' (duration: 196.086218ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:26:23.551957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.832355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T00:26:23.552269Z","caller":"traceutil/trace.go:171","msg":"trace[555423074] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:9787; }","duration":"189.267326ms","start":"2026-10-16T00:26:23.362976Z","end":"2026-10-16T00:26:23.552244Z","steps":["trace[555423074] 'agreement among raft nodes before linearized reading' (duration: 188.659341ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:27:58.070655Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8023} {"level":"info","ts":"2026-10-16T00:27:58.138442Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8023,"took":"65.765646ms","hash":27906809,"current-db-size-bytes":56659968,"current-db-size":"57 MB","current-db-size-in-use-bytes":28340224,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-16T00:27:58.138505Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":27906809,"revision":8023,"compact-revision":5847} {"level":"info","ts":"2026-10-16T00:30:06.404824Z","caller":"traceutil/trace.go:171","msg":"trace[415514033] linearizableReadLoop","detail":"{readStateIndex:12031; appliedIndex:12030; }","duration":"108.886393ms","start":"2026-10-16T00:30:06.295902Z","end":"2026-10-16T00:30:06.404788Z","steps":["trace[415514033] 'read index received' (duration: 87.433278ms)","trace[415514033] 'applied index is now lower than readState.Index' (duration: 21.449365ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:30:06.405081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.156939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-16T00:30:06.405183Z","caller":"traceutil/trace.go:171","msg":"trace[9825784] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11629; }","duration":"109.274932ms","start":"2026-10-16T00:30:06.295884Z","end":"2026-10-16T00:30:06.405159Z","steps":["trace[9825784] 'agreement among raft nodes before linearized reading' (duration: 109.020466ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:30:06.540592Z","caller":"traceutil/trace.go:171","msg":"trace[1907112331] transaction","detail":"{read_only:false; response_revision:11630; number_of_response:1; }","duration":"131.313921ms","start":"2026-10-16T00:30:06.409229Z","end":"2026-10-16T00:30:06.540543Z","steps":["trace[1907112331] 'process raft request' (duration: 131.097326ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:30:06.804144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.328737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-16T00:30:06.804341Z","caller":"traceutil/trace.go:171","msg":"trace[1934480955] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11630; }","duration":"110.598314ms","start":"2026-10-16T00:30:06.693713Z","end":"2026-10-16T00:30:06.804311Z","steps":["trace[1934480955] 'range keys from in-memory index tree' (duration: 110.112632ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:30:06.950149Z","caller":"traceutil/trace.go:171","msg":"trace[212179700] transaction","detail":"{read_only:false; response_revision:11632; number_of_response:1; }","duration":"141.086504ms","start":"2026-10-16T00:30:06.809014Z","end":"2026-10-16T00:30:06.9501Z","steps":["trace[212179700] 'process raft request' (duration: 113.904502ms)","trace[212179700] 'compare' (duration: 26.893725ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:30:06.950174Z","caller":"traceutil/trace.go:171","msg":"trace[2065734797] linearizableReadLoop","detail":"{readStateIndex:12034; appliedIndex:12032; }","duration":"135.358817ms","start":"2026-10-16T00:30:06.814774Z","end":"2026-10-16T00:30:06.950133Z","steps":["trace[2065734797] 'read index received' (duration: 4.154269ms)","trace[2065734797] 'applied index is now lower than readState.Index' (duration: 131.200858ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:30:06.950486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.673346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-10-16T00:30:06.950532Z","caller":"traceutil/trace.go:171","msg":"trace[1613462516] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:11633; }","duration":"135.798358ms","start":"2026-10-16T00:30:06.814722Z","end":"2026-10-16T00:30:06.95052Z","steps":["trace[1613462516] 'agreement among raft nodes before linearized reading' (duration: 135.564443ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:32:58.078032Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10452} {"level":"info","ts":"2026-10-16T00:32:58.16588Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10452,"took":"85.487911ms","hash":1328776624,"current-db-size-bytes":56659968,"current-db-size":"57 MB","current-db-size-in-use-bytes":27869184,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-16T00:32:58.166015Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1328776624,"revision":10452,"compact-revision":8023} {"level":"info","ts":"2026-10-16T00:36:19.736899Z","caller":"traceutil/trace.go:171","msg":"trace[959184687] linearizableReadLoop","detail":"{readStateIndex:14638; appliedIndex:14637; }","duration":"117.149847ms","start":"2026-10-16T00:36:19.619722Z","end":"2026-10-16T00:36:19.736872Z","steps":["trace[959184687] 'read index received' (duration: 116.900411ms)","trace[959184687] 'applied index is now lower than readState.Index' (duration: 248.406µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:36:19.737134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.394533ms","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-16T00:36:19.737177Z","caller":"traceutil/trace.go:171","msg":"trace[381999466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14148; }","duration":"117.460935ms","start":"2026-10-16T00:36:19.619703Z","end":"2026-10-16T00:36:19.737164Z","steps":["trace[381999466] 'agreement among raft nodes before linearized reading' (duration: 117.297141ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:36:19.737281Z","caller":"traceutil/trace.go:171","msg":"trace[1806064503] transaction","detail":"{read_only:false; response_revision:14148; number_of_response:1; }","duration":"121.216375ms","start":"2026-10-16T00:36:19.616027Z","end":"2026-10-16T00:36:19.737243Z","steps":["trace[1806064503] 'process raft request' (duration: 120.687342ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:36:20.088501Z","caller":"traceutil/trace.go:171","msg":"trace[283544165] transaction","detail":"{read_only:false; response_revision:14151; number_of_response:1; }","duration":"115.308303ms","start":"2026-10-16T00:36:19.973142Z","end":"2026-10-16T00:36:20.08845Z","steps":["trace[283544165] 'process raft request' (duration: 114.76792ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:36:37.424573Z","caller":"traceutil/trace.go:171","msg":"trace[291639544] transaction","detail":"{read_only:false; response_revision:14251; number_of_response:1; }","duration":"496.090489ms","start":"2026-10-16T00:36:36.928451Z","end":"2026-10-16T00:36:37.424541Z","steps":["trace[291639544] 'process raft request' (duration: 496.019888ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:36:37.424787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:36:36.928432Z","time spent":"496.258333ms","remote":"127.0.0.1:54228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:36:37.425258Z","caller":"traceutil/trace.go:171","msg":"trace[212617862] transaction","detail":"{read_only:false; response_revision:14250; number_of_response:1; }","duration":"509.375558ms","start":"2026-10-16T00:36:36.915873Z","end":"2026-10-16T00:36:37.425248Z","steps":["trace[212617862] 'process raft request' (duration: 508.408565ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:36:37.425311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:36:36.91581Z","time spent":"509.475781ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:36:37.42644Z","caller":"traceutil/trace.go:171","msg":"trace[156338344] linearizableReadLoop","detail":"{readStateIndex:14747; appliedIndex:14747; }","duration":"416.614898ms","start":"2026-10-16T00:36:37.009814Z","end":"2026-10-16T00:36:37.426429Z","steps":["trace[156338344] 'read index received' (duration: 416.610458ms)","trace[156338344] 'applied index is now lower than readState.Index' (duration: 3.82µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:36:37.426725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.918706ms","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-16T00:36:37.426765Z","caller":"traceutil/trace.go:171","msg":"trace[934421611] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14251; }","duration":"417.009248ms","start":"2026-10-16T00:36:37.009745Z","end":"2026-10-16T00:36:37.426754Z","steps":["trace[934421611] 'agreement among raft nodes before linearized reading' (duration: 416.723891ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:36:37.426879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:36:37.009717Z","time spent":"417.064239ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-16T00:36:37.443254Z","caller":"traceutil/trace.go:171","msg":"trace[812030060] transaction","detail":"{read_only:false; response_revision:14253; number_of_response:1; }","duration":"314.104083ms","start":"2026-10-16T00:36:37.129112Z","end":"2026-10-16T00:36:37.443216Z","steps":["trace[812030060] 'process raft request' (duration: 314.028072ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:36:37.443473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:36:37.129078Z","time spent":"314.285067ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:36:37.443386Z","caller":"traceutil/trace.go:171","msg":"trace[29603816] transaction","detail":"{read_only:false; response_revision:14252; number_of_response:1; }","duration":"317.102195ms","start":"2026-10-16T00:36:37.126268Z","end":"2026-10-16T00:36:37.443371Z","steps":["trace[29603816] 'process raft request' (duration: 316.717466ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:36:37.444833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:36:37.126238Z","time spent":"318.54444ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:36:47.842941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.209876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:36:47.843149Z","caller":"traceutil/trace.go:171","msg":"trace[305545969] linearizableReadLoop","detail":"{readStateIndex:14923; appliedIndex:14922; }","duration":"224.16578ms","start":"2026-10-16T00:36:47.618956Z","end":"2026-10-16T00:36:47.843122Z","steps":["trace[305545969] 'read index received' (duration: 25.548334ms)","trace[305545969] 'applied index is now lower than readState.Index' (duration: 198.615226ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:36:47.843298Z","caller":"traceutil/trace.go:171","msg":"trace[2043051813] transaction","detail":"{read_only:false; response_revision:14424; number_of_response:1; }","duration":"300.606258ms","start":"2026-10-16T00:36:47.542658Z","end":"2026-10-16T00:36:47.843264Z","steps":["trace[2043051813] 'process raft request' (duration: 101.945361ms)","trace[2043051813] 'compare' (duration: 198.017122ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:36:47.843434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:36:47.542619Z","time spent":"300.757262ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:36:47.843726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.558304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zlpcv\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-16T00:36:47.843831Z","caller":"traceutil/trace.go:171","msg":"trace[1126550642] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zlpcv; range_end:; response_count:1; response_revision:14424; }","duration":"104.726667ms","start":"2026-10-16T00:36:47.73908Z","end":"2026-10-16T00:36:47.843807Z","steps":["trace[1126550642] 'agreement among raft nodes before linearized reading' (duration: 104.443791ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:36:47.843475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.501079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-10-16T00:36:47.844104Z","caller":"traceutil/trace.go:171","msg":"trace[1728127849] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:14424; }","duration":"225.189495ms","start":"2026-10-16T00:36:47.618889Z","end":"2026-10-16T00:36:47.844078Z","steps":["trace[1728127849] 'agreement among raft nodes before linearized reading' (duration: 224.399116ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:36:48.13696Z","caller":"traceutil/trace.go:171","msg":"trace[6659872] transaction","detail":"{read_only:false; response_revision:14425; number_of_response:1; }","duration":"287.552894ms","start":"2026-10-16T00:36:47.849343Z","end":"2026-10-16T00:36:48.136896Z","steps":["trace[6659872] 'process raft request' (duration: 265.721589ms)","trace[6659872] 'compare' (duration: 21.539768ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:36:48.13731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.0787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-16T00:36:48.13738Z","caller":"traceutil/trace.go:171","msg":"trace[202282002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14425; }","duration":"264.231953ms","start":"2026-10-16T00:36:47.873132Z","end":"2026-10-16T00:36:48.137364Z","steps":["trace[202282002] 'agreement among raft nodes before linearized reading' (duration: 263.917916ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:36:48.137069Z","caller":"traceutil/trace.go:171","msg":"trace[1139764344] linearizableReadLoop","detail":"{readStateIndex:14924; appliedIndex:14923; }","duration":"263.603319ms","start":"2026-10-16T00:36:47.873183Z","end":"2026-10-16T00:36:48.136786Z","steps":["trace[1139764344] 'read index received' (duration: 241.796694ms)","trace[1139764344] 'applied index is now lower than readState.Index' (duration: 21.805225ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:36:48.1379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.968385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-wpjfc.18dedb772083a0f8\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-10-16T00:36:48.137989Z","caller":"traceutil/trace.go:171","msg":"trace[1758474455] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-wpjfc.18dedb772083a0f8; range_end:; response_count:1; response_revision:14425; }","duration":"172.134579ms","start":"2026-10-16T00:36:47.965829Z","end":"2026-10-16T00:36:48.137963Z","steps":["trace[1758474455] 'agreement among raft nodes before linearized reading' (duration: 171.718989ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:36:48.138029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.534534ms","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-16T00:36:48.138084Z","caller":"traceutil/trace.go:171","msg":"trace[971001543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14425; }","duration":"171.678118ms","start":"2026-10-16T00:36:47.966393Z","end":"2026-10-16T00:36:48.138071Z","steps":["trace[971001543] 'agreement among raft nodes before linearized reading' (duration: 171.520985ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:37:58.182635Z","caller":"traceutil/trace.go:171","msg":"trace[935208358] transaction","detail":"{read_only:false; response_revision:15357; number_of_response:1; }","duration":"103.246786ms","start":"2026-10-16T00:37:58.079356Z","end":"2026-10-16T00:37:58.182602Z","steps":["trace[935208358] 'process raft request' (duration: 103.059802ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:37:58.187694Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12771} {"level":"info","ts":"2026-10-16T00:37:58.268473Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12771,"took":"77.925734ms","hash":3209555962,"current-db-size-bytes":56659968,"current-db-size":"57 MB","current-db-size-in-use-bytes":31191040,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T00:37:58.268587Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3209555962,"revision":12771,"compact-revision":10452} {"level":"info","ts":"2026-10-16T00:38:29.767664Z","caller":"traceutil/trace.go:171","msg":"trace[1452636350] transaction","detail":"{read_only:false; response_revision:15522; number_of_response:1; }","duration":"149.722417ms","start":"2026-10-16T00:38:29.617909Z","end":"2026-10-16T00:38:29.767632Z","steps":["trace[1452636350] 'process raft request' (duration: 149.516722ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:38:29.768296Z","caller":"traceutil/trace.go:171","msg":"trace[686773146] linearizableReadLoop","detail":"{readStateIndex:16056; appliedIndex:16056; }","duration":"145.916154ms","start":"2026-10-16T00:38:29.622366Z","end":"2026-10-16T00:38:29.768282Z","steps":["trace[686773146] 'read index received' (duration: 145.909073ms)","trace[686773146] 'applied index is now lower than readState.Index' (duration: 5.341µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:38:29.768544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.140059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-10-16T00:38:29.769081Z","caller":"traceutil/trace.go:171","msg":"trace[268826012] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:15522; }","duration":"146.678942ms","start":"2026-10-16T00:38:29.622347Z","end":"2026-10-16T00:38:29.769026Z","steps":["trace[268826012] 'agreement among raft nodes before linearized reading' (duration: 146.003465ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:38:35.11498Z","caller":"traceutil/trace.go:171","msg":"trace[178031782] transaction","detail":"{read_only:false; response_revision:15553; number_of_response:1; }","duration":"103.616095ms","start":"2026-10-16T00:38:35.011331Z","end":"2026-10-16T00:38:35.114947Z","steps":["trace[178031782] 'process raft request' (duration: 103.071022ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:38:35.344085Z","caller":"traceutil/trace.go:171","msg":"trace[866550453] transaction","detail":"{read_only:false; response_revision:15554; number_of_response:1; }","duration":"217.500192ms","start":"2026-10-16T00:38:35.126558Z","end":"2026-10-16T00:38:35.344058Z","steps":["trace[866550453] 'process raft request' (duration: 217.344488ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:38:35.917716Z","caller":"traceutil/trace.go:171","msg":"trace[1857193676] transaction","detail":"{read_only:false; response_revision:15555; number_of_response:1; }","duration":"114.607215ms","start":"2026-10-16T00:38:35.803069Z","end":"2026-10-16T00:38:35.917676Z","steps":["trace[1857193676] 'process raft request' (duration: 114.319228ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:38:36.142392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.506367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:38:36.142648Z","caller":"traceutil/trace.go:171","msg":"trace[575835450] transaction","detail":"{read_only:false; response_revision:15557; number_of_response:1; }","duration":"174.24914ms","start":"2026-10-16T00:38:35.96837Z","end":"2026-10-16T00:38:36.142619Z","steps":["trace[575835450] 'process raft request' (duration: 36.390474ms)","trace[575835450] 'compare' (duration: 137.093897ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:42:58.196766Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15357} {"level":"info","ts":"2026-10-16T00:42:58.298418Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15357,"took":"98.259648ms","hash":2021335539,"current-db-size-bytes":56659968,"current-db-size":"57 MB","current-db-size-in-use-bytes":32141312,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T00:42:58.298482Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2021335539,"revision":15357,"compact-revision":12771} {"level":"info","ts":"2026-10-16T00:44:10.774387Z","caller":"traceutil/trace.go:171","msg":"trace[115671011] transaction","detail":"{read_only:false; response_revision:18111; number_of_response:1; }","duration":"112.643373ms","start":"2026-10-16T00:44:10.661716Z","end":"2026-10-16T00:44:10.77436Z","steps":["trace[115671011] 'process raft request' (duration: 112.489319ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:44:10.790293Z","caller":"traceutil/trace.go:171","msg":"trace[274883871] transaction","detail":"{read_only:false; response_revision:18112; number_of_response:1; }","duration":"127.279732ms","start":"2026-10-16T00:44:10.662991Z","end":"2026-10-16T00:44:10.79027Z","steps":["trace[274883871] 'process raft request' (duration: 126.849552ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:44:15.017967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.043852ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:44:15.018235Z","caller":"traceutil/trace.go:171","msg":"trace[619253331] linearizableReadLoop","detail":"{readStateIndex:18769; appliedIndex:18766; }","duration":"230.061472ms","start":"2026-10-16T00:44:14.788132Z","end":"2026-10-16T00:44:15.018193Z","steps":["trace[619253331] 'read index received' (duration: 25.585459ms)","trace[619253331] 'applied index is now lower than readState.Index' (duration: 204.470653ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:44:15.018321Z","caller":"traceutil/trace.go:171","msg":"trace[186187250] transaction","detail":"{read_only:false; response_revision:18150; number_of_response:1; }","duration":"336.076852ms","start":"2026-10-16T00:44:14.682231Z","end":"2026-10-16T00:44:15.018308Z","steps":["trace[186187250] 'process raft request' (duration: 131.63528ms)","trace[186187250] 'compare' (duration: 203.833307ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:44:15.018383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:44:14.68215Z","time spent":"336.197705ms","remote":"127.0.0.1:54206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":931,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:44:15.018539Z","caller":"traceutil/trace.go:171","msg":"trace[910529600] transaction","detail":"{read_only:false; response_revision:18152; number_of_response:1; }","duration":"334.79945ms","start":"2026-10-16T00:44:14.683708Z","end":"2026-10-16T00:44:15.018507Z","steps":["trace[910529600] 'process raft request' (duration: 334.41778ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:44:15.018702Z","caller":"traceutil/trace.go:171","msg":"trace[2044594068] transaction","detail":"{read_only:false; response_revision:18151; number_of_response:1; }","duration":"335.906228ms","start":"2026-10-16T00:44:14.68277Z","end":"2026-10-16T00:44:15.018676Z","steps":["trace[2044594068] 'process raft request' (duration: 335.301333ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:44:15.018744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.626976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-16T00:44:15.018738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:44:14.683694Z","time spent":"334.932563ms","remote":"127.0.0.1:54342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1401,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:44:15.018777Z","caller":"traceutil/trace.go:171","msg":"trace[817520322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18152; }","duration":"230.722919ms","start":"2026-10-16T00:44:14.788044Z","end":"2026-10-16T00:44:15.018767Z","steps":["trace[817520322] 'agreement among raft nodes before linearized reading' (duration: 230.561465ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:44:15.018805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:44:14.682744Z","time spent":"336.001989ms","remote":"127.0.0.1:54206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":877,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:44:15.01899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.250003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-16T00:44:15.019047Z","caller":"traceutil/trace.go:171","msg":"trace[459974986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18152; }","duration":"201.361045ms","start":"2026-10-16T00:44:14.817671Z","end":"2026-10-16T00:44:15.019032Z","steps":["trace[459974986] 'agreement among raft nodes before linearized reading' (duration: 201.168001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:44:15.019081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.432516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T00:44:15.0192Z","caller":"traceutil/trace.go:171","msg":"trace[650700637] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:18152; }","duration":"153.57974ms","start":"2026-10-16T00:44:14.865573Z","end":"2026-10-16T00:44:15.019153Z","steps":["trace[650700637] 'agreement among raft nodes before linearized reading' (duration: 153.297233ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:44:15.019255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.727854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pmrnm\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-10-16T00:44:15.019385Z","caller":"traceutil/trace.go:171","msg":"trace[541948387] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pmrnm; range_end:; response_count:1; response_revision:18152; }","duration":"153.948439ms","start":"2026-10-16T00:44:14.865392Z","end":"2026-10-16T00:44:15.01934Z","steps":["trace[541948387] 'agreement among raft nodes before linearized reading' (duration: 153.57112ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:44:15.166073Z","caller":"traceutil/trace.go:171","msg":"trace[788491602] transaction","detail":"{read_only:false; response_revision:18153; number_of_response:1; }","duration":"140.110359ms","start":"2026-10-16T00:44:15.025935Z","end":"2026-10-16T00:44:15.166045Z","steps":["trace[788491602] 'process raft request' (duration: 137.439303ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:44:15.16613Z","caller":"traceutil/trace.go:171","msg":"trace[1339994107] transaction","detail":"{read_only:false; response_revision:18154; number_of_response:1; }","duration":"138.833667ms","start":"2026-10-16T00:44:15.027254Z","end":"2026-10-16T00:44:15.166088Z","steps":["trace[1339994107] 'process raft request' (duration: 138.762455ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:44:15.166135Z","caller":"traceutil/trace.go:171","msg":"trace[425169835] linearizableReadLoop","detail":"{readStateIndex:18770; appliedIndex:18769; }","duration":"139.061143ms","start":"2026-10-16T00:44:15.027027Z","end":"2026-10-16T00:44:15.166089Z","steps":["trace[425169835] 'read index received' (duration: 136.359406ms)","trace[425169835] 'applied index is now lower than readState.Index' (duration: 2.697757ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:44:15.167292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.228681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:952825"} {"level":"info","ts":"2026-10-16T00:44:15.167342Z","caller":"traceutil/trace.go:171","msg":"trace[1257142943] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:18154; }","duration":"140.292833ms","start":"2026-10-16T00:44:15.027035Z","end":"2026-10-16T00:44:15.167328Z","steps":["trace[1257142943] 'agreement among raft nodes before linearized reading' (duration: 139.312499ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:44:15.168961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.891072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:952825"} {"level":"info","ts":"2026-10-16T00:44:15.169059Z","caller":"traceutil/trace.go:171","msg":"trace[1956241643] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:18154; }","duration":"142.023925ms","start":"2026-10-16T00:44:15.027015Z","end":"2026-10-16T00:44:15.169039Z","steps":["trace[1956241643] 'agreement among raft nodes before linearized reading' (duration: 139.216736ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:46:33.478439Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"308ce60d3e55f7ff","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T00:46:33.484537Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-16T00:46:33.484651Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-16T00:47:58.204241Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17526} {"level":"info","ts":"2026-10-16T00:47:58.312058Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17526,"took":"102.959771ms","hash":2616400016,"current-db-size-bytes":56659968,"current-db-size":"57 MB","current-db-size-in-use-bytes":34570240,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-16T00:47:58.312132Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2616400016,"revision":17526,"compact-revision":15357} {"level":"info","ts":"2026-10-16T00:49:30.717028Z","caller":"traceutil/trace.go:171","msg":"trace[962182159] linearizableReadLoop","detail":"{readStateIndex:21290; appliedIndex:21289; }","duration":"162.389714ms","start":"2026-10-16T00:49:30.554611Z","end":"2026-10-16T00:49:30.717Z","steps":["trace[962182159] 'read index received' (duration: 162.149968ms)","trace[962182159] 'applied index is now lower than readState.Index' (duration: 238.906µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:49:30.71708Z","caller":"traceutil/trace.go:171","msg":"trace[1446818826] transaction","detail":"{read_only:false; response_revision:20594; number_of_response:1; }","duration":"162.751663ms","start":"2026-10-16T00:49:30.55431Z","end":"2026-10-16T00:49:30.717062Z","steps":["trace[1446818826] 'process raft request' (duration: 162.535497ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:49:30.717142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.508597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:49:30.71717Z","caller":"traceutil/trace.go:171","msg":"trace[1725568973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20594; }","duration":"162.573448ms","start":"2026-10-16T00:49:30.554587Z","end":"2026-10-16T00:49:30.717161Z","steps":["trace[1725568973] 'agreement among raft nodes before linearized reading' (duration: 162.502476ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:49:30.729056Z","caller":"traceutil/trace.go:171","msg":"trace[6515873] transaction","detail":"{read_only:false; response_revision:20595; number_of_response:1; }","duration":"109.416587ms","start":"2026-10-16T00:49:30.619619Z","end":"2026-10-16T00:49:30.729035Z","steps":["trace[6515873] 'process raft request' (duration: 109.292794ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:49:46.786717Z","caller":"traceutil/trace.go:171","msg":"trace[1979619740] transaction","detail":"{read_only:false; response_revision:20675; number_of_response:1; }","duration":"119.562581ms","start":"2026-10-16T00:49:46.667104Z","end":"2026-10-16T00:49:46.786667Z","steps":["trace[1979619740] 'process raft request' (duration: 119.278995ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:49:47.098442Z","caller":"traceutil/trace.go:171","msg":"trace[1651055355] transaction","detail":"{read_only:false; response_revision:20676; number_of_response:1; }","duration":"371.461292ms","start":"2026-10-16T00:49:46.726943Z","end":"2026-10-16T00:49:47.098404Z","steps":["trace[1651055355] 'process raft request' (duration: 371.213966ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:49:47.098644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:49:46.7269Z","time spent":"371.635956ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:49:47.098785Z","caller":"traceutil/trace.go:171","msg":"trace[1968248743] transaction","detail":"{read_only:false; response_revision:20677; number_of_response:1; }","duration":"317.03211ms","start":"2026-10-16T00:49:46.781713Z","end":"2026-10-16T00:49:47.098745Z","steps":["trace[1968248743] 'process raft request' (duration: 316.60732ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:49:47.09898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:49:46.781675Z","time spent":"317.184434ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:49:47.177449Z","caller":"traceutil/trace.go:171","msg":"trace[1309749864] transaction","detail":"{read_only:false; response_revision:20678; number_of_response:1; }","duration":"390.798058ms","start":"2026-10-16T00:49:46.786628Z","end":"2026-10-16T00:49:47.177426Z","steps":["trace[1309749864] 'process raft request' (duration: 390.500521ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:49:47.177456Z","caller":"traceutil/trace.go:171","msg":"trace[1924823593] linearizableReadLoop","detail":"{readStateIndex:21379; appliedIndex:21375; }","duration":"253.51171ms","start":"2026-10-16T00:49:46.923902Z","end":"2026-10-16T00:49:47.177413Z","steps":["trace[1924823593] 'read index received' (duration: 174.315841ms)","trace[1924823593] 'applied index is now lower than readState.Index' (duration: 79.193609ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:49:47.177606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:49:46.786611Z","time spent":"390.91691ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:49:47.200752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.844432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T00:49:47.200816Z","caller":"traceutil/trace.go:171","msg":"trace[1696335490] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:20678; }","duration":"276.963934ms","start":"2026-10-16T00:49:46.923836Z","end":"2026-10-16T00:49:47.2008Z","steps":["trace[1696335490] 'agreement among raft nodes before linearized reading' (duration: 253.708154ms)","trace[1696335490] 'count revisions from in-memory index tree' (duration: 23.151228ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:49:47.380661Z","caller":"traceutil/trace.go:171","msg":"trace[533904503] transaction","detail":"{read_only:false; response_revision:20679; number_of_response:1; }","duration":"148.366095ms","start":"2026-10-16T00:49:47.232268Z","end":"2026-10-16T00:49:47.380634Z","steps":["trace[533904503] 'process raft request' (duration: 148.189701ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:49:47.625199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.626165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:49:47.625373Z","caller":"traceutil/trace.go:171","msg":"trace[2125710447] transaction","detail":"{read_only:false; response_revision:20680; number_of_response:1; }","duration":"390.573032ms","start":"2026-10-16T00:49:47.234777Z","end":"2026-10-16T00:49:47.62535Z","steps":["trace[2125710447] 'process raft request' (duration: 262.695691ms)","trace[2125710447] 'compare' (duration: 127.317898ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:49:47.625529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:49:47.234755Z","time spent":"390.685325ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:49:47.936676Z","caller":"traceutil/trace.go:171","msg":"trace[2115742708] linearizableReadLoop","detail":"{readStateIndex:21382; appliedIndex:21381; }","duration":"152.574957ms","start":"2026-10-16T00:49:47.784069Z","end":"2026-10-16T00:49:47.936644Z","steps":["trace[2115742708] 'read index received' (duration: 52.710171ms)","trace[2115742708] 'applied index is now lower than readState.Index' (duration: 99.863056ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:49:47.936796Z","caller":"traceutil/trace.go:171","msg":"trace[1370932956] transaction","detail":"{read_only:false; response_revision:20681; number_of_response:1; }","duration":"257.186438ms","start":"2026-10-16T00:49:47.67959Z","end":"2026-10-16T00:49:47.936776Z","steps":["trace[1370932956] 'process raft request' (duration: 157.217209ms)","trace[1370932956] 'compare' (duration: 99.465077ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:49:47.936977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.898495ms","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-16T00:49:47.937055Z","caller":"traceutil/trace.go:171","msg":"trace[822345971] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20681; }","duration":"153.025867ms","start":"2026-10-16T00:49:47.784003Z","end":"2026-10-16T00:49:47.937029Z","steps":["trace[822345971] 'agreement among raft nodes before linearized reading' (duration: 152.809072ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:49:48.255036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.991481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:49:48.255241Z","caller":"traceutil/trace.go:171","msg":"trace[1997747827] transaction","detail":"{read_only:false; response_revision:20683; number_of_response:1; }","duration":"313.72739ms","start":"2026-10-16T00:49:47.941491Z","end":"2026-10-16T00:49:48.255218Z","steps":["trace[1997747827] 'process raft request' (duration: 313.642868ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:49:48.255263Z","caller":"traceutil/trace.go:171","msg":"trace[1870459138] linearizableReadLoop","detail":"{readStateIndex:21383; appliedIndex:21382; }","duration":"318.479165ms","start":"2026-10-16T00:49:47.936739Z","end":"2026-10-16T00:49:48.255218Z","steps":["trace[1870459138] 'read index received' (duration: 147.158107ms)","trace[1870459138] 'applied index is now lower than readState.Index' (duration: 171.318088ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:49:48.25533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:49:47.941467Z","time spent":"313.829532ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:49:48.255536Z","caller":"traceutil/trace.go:171","msg":"trace[1292581938] transaction","detail":"{read_only:false; response_revision:20682; number_of_response:1; }","duration":"438.267001ms","start":"2026-10-16T00:49:47.816873Z","end":"2026-10-16T00:49:48.25514Z","steps":["trace[1292581938] 'process raft request' (duration: 267.088896ms)","trace[1292581938] 'compare' (duration: 170.367446ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:49:48.255916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.876144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"warn","ts":"2026-10-16T00:49:48.256015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:49:47.816844Z","time spent":"438.987899ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:49:48.256146Z","caller":"traceutil/trace.go:171","msg":"trace[552444056] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:20683; }","duration":"375.164111ms","start":"2026-10-16T00:49:47.880952Z","end":"2026-10-16T00:49:48.256116Z","steps":["trace[552444056] 'agreement among raft nodes before linearized reading' (duration: 374.368322ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:49:48.25621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:49:47.880899Z","time spent":"375.294324ms","remote":"127.0.0.1:54202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-10-16T00:52:58.213505Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19928} {"level":"info","ts":"2026-10-16T00:52:58.337391Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19928,"took":"118.529572ms","hash":2306996216,"current-db-size-bytes":56659968,"current-db-size":"57 MB","current-db-size-in-use-bytes":31047680,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T00:52:58.337466Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2306996216,"revision":19928,"compact-revision":17526} {"level":"info","ts":"2026-10-16T00:53:47.60997Z","caller":"traceutil/trace.go:171","msg":"trace[152916997] transaction","detail":"{read_only:false; response_revision:23172; number_of_response:1; }","duration":"124.135856ms","start":"2026-10-16T00:53:47.485794Z","end":"2026-10-16T00:53:47.60993Z","steps":["trace[152916997] 'process raft request' (duration: 121.690587ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:48.029193Z","caller":"traceutil/trace.go:171","msg":"trace[980423947] linearizableReadLoop","detail":"{readStateIndex:23951; appliedIndex:23950; }","duration":"354.56121ms","start":"2026-10-16T00:53:47.67461Z","end":"2026-10-16T00:53:48.029171Z","steps":["trace[980423947] 'read index received' (duration: 330.240275ms)","trace[980423947] 'applied index is now lower than readState.Index' (duration: 24.319845ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:53:48.029326Z","caller":"traceutil/trace.go:171","msg":"trace[463582642] transaction","detail":"{read_only:false; response_revision:23176; number_of_response:1; }","duration":"407.098924ms","start":"2026-10-16T00:53:47.622191Z","end":"2026-10-16T00:53:48.02929Z","steps":["trace[463582642] 'process raft request' (duration: 382.672887ms)","trace[463582642] 'compare' (duration: 23.948646ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:53:48.029495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:53:47.622161Z","time spent":"407.237998ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:53:48.029863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.330104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-10-16T00:53:48.02991Z","caller":"traceutil/trace.go:171","msg":"trace[1409545667] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:23176; }","duration":"179.427777ms","start":"2026-10-16T00:53:47.850466Z","end":"2026-10-16T00:53:48.029894Z","steps":["trace[1409545667] 'agreement among raft nodes before linearized reading' (duration: 179.248882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:53:48.02987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.745195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:53:48.029954Z","caller":"traceutil/trace.go:171","msg":"trace[313229155] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:23176; }","duration":"355.36312ms","start":"2026-10-16T00:53:47.674573Z","end":"2026-10-16T00:53:48.029936Z","steps":["trace[313229155] 'agreement among raft nodes before linearized reading' (duration: 354.711934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:53:48.029999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:53:47.674561Z","time spent":"355.422951ms","remote":"127.0.0.1:34068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"info","ts":"2026-10-16T00:57:21.174553Z","caller":"traceutil/trace.go:171","msg":"trace[1355809439] transaction","detail":"{read_only:false; response_revision:25269; number_of_response:1; }","duration":"290.28252ms","start":"2026-10-16T00:57:20.88424Z","end":"2026-10-16T00:57:21.174522Z","steps":["trace[1355809439] 'process raft request' (duration: 290.008023ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:57:21.175448Z","caller":"traceutil/trace.go:171","msg":"trace[2035243066] linearizableReadLoop","detail":"{readStateIndex:26097; appliedIndex:26094; }","duration":"156.431885ms","start":"2026-10-16T00:57:21.018997Z","end":"2026-10-16T00:57:21.175429Z","steps":["trace[2035243066] 'read index received' (duration: 155.198706ms)","trace[2035243066] 'applied index is now lower than readState.Index' (duration: 1.232239ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:57:21.175526Z","caller":"traceutil/trace.go:171","msg":"trace[438235178] transaction","detail":"{read_only:false; response_revision:25270; number_of_response:1; }","duration":"264.545904ms","start":"2026-10-16T00:57:20.910943Z","end":"2026-10-16T00:57:21.175489Z","steps":["trace[438235178] 'process raft request' (duration: 264.306138ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:57:21.175608Z","caller":"traceutil/trace.go:171","msg":"trace[1648959128] transaction","detail":"{read_only:false; response_revision:25271; number_of_response:1; }","duration":"254.250688ms","start":"2026-10-16T00:57:20.921311Z","end":"2026-10-16T00:57:21.175561Z","steps":["trace[1648959128] 'process raft request' (duration: 254.059523ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:57:21.17583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.809564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19233"} {"level":"info","ts":"2026-10-16T00:57:21.176403Z","caller":"traceutil/trace.go:171","msg":"trace[807862092] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25271; }","duration":"157.439779ms","start":"2026-10-16T00:57:21.01894Z","end":"2026-10-16T00:57:21.176379Z","steps":["trace[807862092] 'agreement among raft nodes before linearized reading' (duration: 156.671091ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:57:21.381082Z","caller":"traceutil/trace.go:171","msg":"trace[1347697642] linearizableReadLoop","detail":"{readStateIndex:26098; appliedIndex:26097; }","duration":"199.488426ms","start":"2026-10-16T00:57:21.181574Z","end":"2026-10-16T00:57:21.381063Z","steps":["trace[1347697642] 'read index received' (duration: 125.953925ms)","trace[1347697642] 'applied index is now lower than readState.Index' (duration: 73.533541ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:57:21.381305Z","caller":"traceutil/trace.go:171","msg":"trace[414392090] transaction","detail":"{read_only:false; response_revision:25272; number_of_response:1; }","duration":"199.961428ms","start":"2026-10-16T00:57:21.181307Z","end":"2026-10-16T00:57:21.381268Z","steps":["trace[414392090] 'process raft request' (duration: 126.154531ms)","trace[414392090] 'compare' (duration: 73.52059ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:57:21.381368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.140498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-16T00:57:21.381394Z","caller":"traceutil/trace.go:171","msg":"trace[1267055381] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25272; }","duration":"166.2268ms","start":"2026-10-16T00:57:21.21516Z","end":"2026-10-16T00:57:21.381386Z","steps":["trace[1267055381] 'agreement among raft nodes before linearized reading' (duration: 166.114188ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:57:21.381376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.702296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T00:57:21.381464Z","caller":"traceutil/trace.go:171","msg":"trace[893341718] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:25272; }","duration":"175.84146ms","start":"2026-10-16T00:57:21.205598Z","end":"2026-10-16T00:57:21.38144Z","steps":["trace[893341718] 'agreement among raft nodes before linearized reading' (duration: 175.664215ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:57:21.381495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.946847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19233"} {"level":"info","ts":"2026-10-16T00:57:21.381572Z","caller":"traceutil/trace.go:171","msg":"trace[582339873] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25272; }","duration":"200.06899ms","start":"2026-10-16T00:57:21.181484Z","end":"2026-10-16T00:57:21.381553Z","steps":["trace[582339873] 'agreement among raft nodes before linearized reading' (duration: 199.808714ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:57:21.712596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.521856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:57:21.712726Z","caller":"traceutil/trace.go:171","msg":"trace[918978759] transaction","detail":"{read_only:false; response_revision:25274; number_of_response:1; }","duration":"301.615201ms","start":"2026-10-16T00:57:21.4111Z","end":"2026-10-16T00:57:21.712715Z","steps":["trace[918978759] 'process raft request' (duration: 301.56301ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:57:21.712761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:57:21.411081Z","time spent":"301.663463ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:57:21.712878Z","caller":"traceutil/trace.go:171","msg":"trace[1248315970] transaction","detail":"{read_only:false; response_revision:25273; number_of_response:1; }","duration":"310.067454ms","start":"2026-10-16T00:57:21.402804Z","end":"2026-10-16T00:57:21.712872Z","steps":["trace[1248315970] 'process raft request' (duration: 123.21986ms)","trace[1248315970] 'compare' (duration: 186.209128ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:57:21.712909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:57:21.402779Z","time spent":"310.115484ms","remote":"127.0.0.1:54060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:57:21.712977Z","caller":"traceutil/trace.go:171","msg":"trace[969331645] linearizableReadLoop","detail":"{readStateIndex:26100; appliedIndex:26099; }","duration":"309.771286ms","start":"2026-10-16T00:57:21.403199Z","end":"2026-10-16T00:57:21.71297Z","steps":["trace[969331645] 'read index received' (duration: 122.773289ms)","trace[969331645] 'applied index is now lower than readState.Index' (duration: 186.997337ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:57:21.713308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.137135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19233"} {"level":"info","ts":"2026-10-16T00:57:21.713327Z","caller":"traceutil/trace.go:171","msg":"trace[153696745] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25274; }","duration":"310.185777ms","start":"2026-10-16T00:57:21.403137Z","end":"2026-10-16T00:57:21.713322Z","steps":["trace[153696745] 'agreement among raft nodes before linearized reading' (duration: 310.111165ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:57:21.713339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:57:21.403121Z","time spent":"310.214997ms","remote":"127.0.0.1:54212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19256,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-10-16T00:57:22.048494Z","caller":"traceutil/trace.go:171","msg":"trace[1477798443] linearizableReadLoop","detail":"{readStateIndex:26102; appliedIndex:26101; }","duration":"335.484481ms","start":"2026-10-16T00:57:21.712991Z","end":"2026-10-16T00:57:22.048475Z","steps":["trace[1477798443] 'read index received' (duration: 333.717519ms)","trace[1477798443] 'applied index is now lower than readState.Index' (duration: 1.766062ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:57:22.048641Z","caller":"traceutil/trace.go:171","msg":"trace[1364128384] transaction","detail":"{read_only:false; response_revision:25275; number_of_response:1; }","duration":"424.78397ms","start":"2026-10-16T00:57:21.623845Z","end":"2026-10-16T00:57:22.048629Z","steps":["trace[1364128384] 'process raft request' (duration: 422.850544ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:57:22.048647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.716124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:57:22.048726Z","caller":"traceutil/trace.go:171","msg":"trace[1503693549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25275; }","duration":"494.833337ms","start":"2026-10-16T00:57:21.553874Z","end":"2026-10-16T00:57:22.048707Z","steps":["trace[1503693549] 'agreement among raft nodes before linearized reading' (duration: 494.655593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:57:22.048713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.024092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-16T00:57:22.048767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:57:21.553859Z","time spent":"494.901138ms","remote":"127.0.0.1:54034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-16T00:57:22.048767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:57:21.62382Z","time spent":"424.887853ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:57:22.048786Z","caller":"traceutil/trace.go:171","msg":"trace[199394648] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:25275; }","duration":"584.172665ms","start":"2026-10-16T00:57:21.464596Z","end":"2026-10-16T00:57:22.048769Z","steps":["trace[199394648] 'agreement among raft nodes before linearized reading' (duration: 583.979601ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:57:22.04884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:57:21.464581Z","time spent":"584.249388ms","remote":"127.0.0.1:54202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":16,"response size":31,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-10-16T00:57:22.049476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.152619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11835"} {"level":"info","ts":"2026-10-16T00:57:22.049565Z","caller":"traceutil/trace.go:171","msg":"trace[87381743] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25275; }","duration":"415.290372ms","start":"2026-10-16T00:57:21.634255Z","end":"2026-10-16T00:57:22.049545Z","steps":["trace[87381743] 'agreement among raft nodes before linearized reading' (duration: 414.250067ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:57:22.049613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:57:21.634243Z","time spent":"415.361224ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11858,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-10-16T00:57:22.049814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.347034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-10-16T00:57:22.049865Z","caller":"traceutil/trace.go:171","msg":"trace[718280006] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25277; }","duration":"334.435026ms","start":"2026-10-16T00:57:21.715418Z","end":"2026-10-16T00:57:22.049853Z","steps":["trace[718280006] 'agreement among raft nodes before linearized reading' (duration: 334.230511ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:57:22.049896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:57:21.715401Z","time spent":"334.487378ms","remote":"127.0.0.1:54206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-10-16T00:57:22.049974Z","caller":"traceutil/trace.go:171","msg":"trace[302817349] transaction","detail":"{read_only:false; response_revision:25276; number_of_response:1; }","duration":"247.798253ms","start":"2026-10-16T00:57:21.802159Z","end":"2026-10-16T00:57:22.049957Z","steps":["trace[302817349] 'process raft request' (duration: 247.379783ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:57:22.050169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.862811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-16T00:57:22.050219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.849907ms","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-16T00:57:22.050246Z","caller":"traceutil/trace.go:171","msg":"trace[1618962005] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25277; }","duration":"269.938072ms","start":"2026-10-16T00:57:21.780295Z","end":"2026-10-16T00:57:22.050233Z","steps":["trace[1618962005] 'agreement among raft nodes before linearized reading' (duration: 269.800899ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:57:22.050264Z","caller":"traceutil/trace.go:171","msg":"trace[1407596313] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25277; }","duration":"121.912979ms","start":"2026-10-16T00:57:21.928331Z","end":"2026-10-16T00:57:22.050244Z","steps":["trace[1407596313] 'agreement among raft nodes before linearized reading' (duration: 121.803786ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:57:22.049176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.956976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:57:22.050547Z","caller":"traceutil/trace.go:171","msg":"trace[1292096956] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:25275; }","duration":"609.172884ms","start":"2026-10-16T00:57:21.441176Z","end":"2026-10-16T00:57:22.050349Z","steps":["trace[1292096956] 'agreement among raft nodes before linearized reading' (duration: 607.394672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:57:22.050614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:57:21.44116Z","time spent":"609.438991ms","remote":"127.0.0.1:33512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true "} {"level":"info","ts":"2026-10-16T00:57:58.228206Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22255} {"level":"info","ts":"2026-10-16T00:57:58.344735Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22255,"took":"112.749899ms","hash":1751573344,"current-db-size-bytes":56659968,"current-db-size":"57 MB","current-db-size-in-use-bytes":45076480,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-16T00:57:58.34488Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1751573344,"revision":22255,"compact-revision":19928} {"level":"info","ts":"2026-10-16T00:58:24.443908Z","caller":"traceutil/trace.go:171","msg":"trace[225774688] transaction","detail":"{read_only:false; response_revision:25833; number_of_response:1; }","duration":"140.75086ms","start":"2026-10-16T00:58:24.303124Z","end":"2026-10-16T00:58:24.443875Z","steps":["trace[225774688] 'process raft request' (duration: 122.843671ms)","trace[225774688] 'compare' (duration: 16.952486ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:58:24.863695Z","caller":"traceutil/trace.go:171","msg":"trace[2050958954] transaction","detail":"{read_only:false; response_revision:25834; number_of_response:1; }","duration":"559.854324ms","start":"2026-10-16T00:58:24.303811Z","end":"2026-10-16T00:58:24.863665Z","steps":["trace[2050958954] 'process raft request' (duration: 559.640179ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:58:24.863867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:58:24.303787Z","time spent":"560.005238ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:58:24.864101Z","caller":"traceutil/trace.go:171","msg":"trace[1488827430] linearizableReadLoop","detail":"{readStateIndex:26677; appliedIndex:26674; }","duration":"480.219377ms","start":"2026-10-16T00:58:24.383827Z","end":"2026-10-16T00:58:24.864047Z","steps":["trace[1488827430] 'read index received' (duration: 42.072337ms)","trace[1488827430] 'applied index is now lower than readState.Index' (duration: 438.14491ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:58:24.864139Z","caller":"traceutil/trace.go:171","msg":"trace[59954827] transaction","detail":"{read_only:false; response_revision:25835; number_of_response:1; }","duration":"558.920991ms","start":"2026-10-16T00:58:24.305189Z","end":"2026-10-16T00:58:24.86411Z","steps":["trace[59954827] 'process raft request' (duration: 558.43432ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:58:24.864244Z","caller":"traceutil/trace.go:171","msg":"trace[653341940] transaction","detail":"{read_only:false; response_revision:25836; number_of_response:1; }","duration":"450.600828ms","start":"2026-10-16T00:58:24.413628Z","end":"2026-10-16T00:58:24.864229Z","steps":["trace[653341940] 'process raft request' (duration: 450.299741ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:58:24.864278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:58:24.30516Z","time spent":"559.042805ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:58:24.864366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:58:24.413604Z","time spent":"450.672989ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:58:24.864384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.766627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-16T00:58:24.864424Z","caller":"traceutil/trace.go:171","msg":"trace[1785431952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25836; }","duration":"479.827508ms","start":"2026-10-16T00:58:24.384583Z","end":"2026-10-16T00:58:24.864411Z","steps":["trace[1785431952] 'agreement among raft nodes before linearized reading' (duration: 479.704095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:58:24.864451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:58:24.384572Z","time spent":"479.872839ms","remote":"127.0.0.1:54326","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":"warn","ts":"2026-10-16T00:58:24.864562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.965275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:58:24.864603Z","caller":"traceutil/trace.go:171","msg":"trace[1584091717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25836; }","duration":"311.032157ms","start":"2026-10-16T00:58:24.55356Z","end":"2026-10-16T00:58:24.864593Z","steps":["trace[1584091717] 'agreement among raft nodes before linearized reading' (duration: 310.965305ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:58:24.86461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.781081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-16T00:58:24.864629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:58:24.553543Z","time spent":"311.080678ms","remote":"127.0.0.1:54034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-16T00:58:24.864644Z","caller":"traceutil/trace.go:171","msg":"trace[117632504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25836; }","duration":"480.850722ms","start":"2026-10-16T00:58:24.383783Z","end":"2026-10-16T00:58:24.864633Z","steps":["trace[117632504] 'agreement among raft nodes before linearized reading' (duration: 480.732959ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:58:24.864679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:58:24.383763Z","time spent":"480.910364ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-16T00:58:25.221069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.450433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:58:25.221179Z","caller":"traceutil/trace.go:171","msg":"trace[66291441] transaction","detail":"{read_only:false; response_revision:25837; number_of_response:1; }","duration":"351.505414ms","start":"2026-10-16T00:58:24.869651Z","end":"2026-10-16T00:58:25.221156Z","steps":["trace[66291441] 'process raft request' (duration: 207.875227ms)","trace[66291441] 'compare' (duration: 143.067744ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:58:25.221258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:58:24.869632Z","time spent":"351.597787ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:58:25.304857Z","caller":"traceutil/trace.go:171","msg":"trace[1068887348] linearizableReadLoop","detail":"{readStateIndex:26680; appliedIndex:26678; }","duration":"330.370049ms","start":"2026-10-16T00:58:24.974461Z","end":"2026-10-16T00:58:25.304831Z","steps":["trace[1068887348] 'read index received' (duration: 103.078908ms)","trace[1068887348] 'applied index is now lower than readState.Index' (duration: 227.290241ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:58:25.304976Z","caller":"traceutil/trace.go:171","msg":"trace[1304179939] transaction","detail":"{read_only:false; response_revision:25838; number_of_response:1; }","duration":"434.108462ms","start":"2026-10-16T00:58:24.87083Z","end":"2026-10-16T00:58:25.304938Z","steps":["trace[1304179939] 'process raft request' (duration: 433.841176ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:58:25.305095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.622124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-16T00:58:25.305136Z","caller":"traceutil/trace.go:171","msg":"trace[1293750095] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25838; }","duration":"330.721487ms","start":"2026-10-16T00:58:24.974403Z","end":"2026-10-16T00:58:25.305125Z","steps":["trace[1293750095] 'agreement among raft nodes before linearized reading' (duration: 330.543732ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:58:25.305132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:58:24.87082Z","time spent":"434.233105ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:58:25.305174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:58:24.974374Z","time spent":"330.791799ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-16T00:58:25.66734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.981769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:58:25.667568Z","caller":"traceutil/trace.go:171","msg":"trace[1489604127] transaction","detail":"{read_only:false; response_revision:25840; number_of_response:1; }","duration":"358.119514ms","start":"2026-10-16T00:58:25.309431Z","end":"2026-10-16T00:58:25.66755Z","steps":["trace[1489604127] 'process raft request' (duration: 358.025882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:58:25.667652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:58:25.309409Z","time spent":"358.196016ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:58:25.667857Z","caller":"traceutil/trace.go:171","msg":"trace[1489163990] transaction","detail":"{read_only:false; response_revision:25839; number_of_response:1; }","duration":"423.577871ms","start":"2026-10-16T00:58:25.244257Z","end":"2026-10-16T00:58:25.667835Z","steps":["trace[1489163990] 'process raft request' (duration: 138.898075ms)","trace[1489163990] 'compare' (duration: 283.767384ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:58:25.667857Z","caller":"traceutil/trace.go:171","msg":"trace[199323779] linearizableReadLoop","detail":"{readStateIndex:26681; appliedIndex:26680; }","duration":"362.908629ms","start":"2026-10-16T00:58:25.304921Z","end":"2026-10-16T00:58:25.66783Z","steps":["trace[199323779] 'read index received' (duration: 78.304995ms)","trace[199323779] 'applied index is now lower than readState.Index' (duration: 284.600534ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:58:25.667954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:58:25.244228Z","time spent":"423.674683ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:58:25.668009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.012398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:58:25.668048Z","caller":"traceutil/trace.go:171","msg":"trace[2016489075] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:25840; }","duration":"601.10187ms","start":"2026-10-16T00:58:25.066931Z","end":"2026-10-16T00:58:25.668033Z","steps":["trace[2016489075] 'agreement among raft nodes before linearized reading' (duration: 601.009488ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:58:25.66804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.751603ms","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":"warn","ts":"2026-10-16T00:58:25.668081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:58:25.066913Z","time spent":"601.159191ms","remote":"127.0.0.1:34146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"info","ts":"2026-10-16T00:58:25.668128Z","caller":"traceutil/trace.go:171","msg":"trace[279017200] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25840; }","duration":"575.869286ms","start":"2026-10-16T00:58:25.092238Z","end":"2026-10-16T00:58:25.668108Z","steps":["trace[279017200] 'agreement among raft nodes before linearized reading' (duration: 575.59851ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:58:25.668179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:58:25.092229Z","time spent":"575.938688ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-16T00:58:25.668193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.383522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-16T00:58:25.668362Z","caller":"traceutil/trace.go:171","msg":"trace[1105816203] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25840; }","duration":"592.627107ms","start":"2026-10-16T00:58:25.075697Z","end":"2026-10-16T00:58:25.668324Z","steps":["trace[1105816203] 'agreement among raft nodes before linearized reading' (duration: 592.152866ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:58:25.668435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:58:25.075677Z","time spent":"592.743251ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-16T00:58:25.735899Z","caller":"traceutil/trace.go:171","msg":"trace[89313224] transaction","detail":"{read_only:false; response_revision:25841; number_of_response:1; }","duration":"310.549625ms","start":"2026-10-16T00:58:25.42532Z","end":"2026-10-16T00:58:25.73587Z","steps":["trace[89313224] 'process raft request' (duration: 310.33535ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:58:25.735949Z","caller":"traceutil/trace.go:171","msg":"trace[904781424] transaction","detail":"{read_only:false; response_revision:25842; number_of_response:1; }","duration":"308.428535ms","start":"2026-10-16T00:58:25.427497Z","end":"2026-10-16T00:58:25.735925Z","steps":["trace[904781424] 'process raft request' (duration: 308.305012ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:58:25.736065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:58:25.425274Z","time spent":"310.697089ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:58:25.736112Z","caller":"traceutil/trace.go:171","msg":"trace[533164503] transaction","detail":"{read_only:false; response_revision:25843; number_of_response:1; }","duration":"306.465147ms","start":"2026-10-16T00:58:25.429638Z","end":"2026-10-16T00:58:25.736103Z","steps":["trace[533164503] 'process raft request' (duration: 306.207641ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:58:25.736179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:58:25.429621Z","time spent":"306.523629ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:58:25.736073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:58:25.427464Z","time spent":"308.545787ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:58:25.736316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.542486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:58:25.736409Z","caller":"traceutil/trace.go:171","msg":"trace[1722886509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25843; }","duration":"181.7004ms","start":"2026-10-16T00:58:25.554686Z","end":"2026-10-16T00:58:25.736386Z","steps":["trace[1722886509] 'agreement among raft nodes before linearized reading' (duration: 181.516465ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:58:55.125443Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006975.wal"} {"level":"info","ts":"2026-10-16T01:02:58.236292Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25629} {"level":"info","ts":"2026-10-16T01:02:58.406424Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25629,"took":"165.574042ms","hash":3745809210,"current-db-size-bytes":57597952,"current-db-size":"58 MB","current-db-size-in-use-bytes":36413440,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-16T01:02:58.406491Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3745809210,"revision":25629,"compact-revision":22255} {"level":"info","ts":"2026-10-16T01:04:07.16073Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"308ce60d3e55f7ff","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T01:04:07.167434Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-16T01:04:07.16759Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-16T01:07:58.244084Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28571} {"level":"info","ts":"2026-10-16T01:07:58.391807Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28571,"took":"143.651073ms","hash":38170019,"current-db-size-bytes":57597952,"current-db-size":"58 MB","current-db-size-in-use-bytes":31592448,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T01:07:58.39186Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":38170019,"revision":28571,"compact-revision":25629} {"level":"info","ts":"2026-10-16T01:12:58.25238Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30828} {"level":"info","ts":"2026-10-16T01:12:58.401805Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30828,"took":"142.317182ms","hash":4056722519,"current-db-size-bytes":57597952,"current-db-size":"58 MB","current-db-size-in-use-bytes":30552064,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T01:12:58.401864Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4056722519,"revision":30828,"compact-revision":28571} {"level":"info","ts":"2026-10-16T01:17:58.260389Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33085} {"level":"info","ts":"2026-10-16T01:17:58.420255Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33085,"took":"152.758097ms","hash":923116923,"current-db-size-bytes":57597952,"current-db-size":"58 MB","current-db-size-in-use-bytes":31182848,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T01:17:58.420367Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":923116923,"revision":33085,"compact-revision":30828} {"level":"info","ts":"2026-10-16T01:22:58.267118Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35382} {"level":"info","ts":"2026-10-16T01:22:58.420502Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35382,"took":"148.752703ms","hash":1688987446,"current-db-size-bytes":59719680,"current-db-size":"60 MB","current-db-size-in-use-bytes":31907840,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T01:22:58.42055Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1688987446,"revision":35382,"compact-revision":33085} {"level":"info","ts":"2026-10-16T01:25:30.150322Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"308ce60d3e55f7ff","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T01:25:30.156828Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-16T01:25:30.156979Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-16T01:27:58.276241Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37609} {"level":"info","ts":"2026-10-16T01:27:58.417154Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37609,"took":"136.675476ms","hash":2894178253,"current-db-size-bytes":60145664,"current-db-size":"60 MB","current-db-size-in-use-bytes":32620544,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-16T01:27:58.417253Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2894178253,"revision":37609,"compact-revision":35382} {"level":"info","ts":"2026-10-16T01:32:58.284073Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39882} {"level":"info","ts":"2026-10-16T01:32:58.429706Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39882,"took":"139.152639ms","hash":2898538659,"current-db-size-bytes":60145664,"current-db-size":"60 MB","current-db-size-in-use-bytes":30793728,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T01:32:58.429805Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2898538659,"revision":39882,"compact-revision":37609}