{"level":"warn","ts":"2026-10-15T19:00:45.593932Z","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-15T19:00:45.594056Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.242: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.242:2380","--initial-cluster=instance=https://162.253.55.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.242: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-15T19:00:45.594157Z","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-15T19:00:45.594181Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.242:2380"]} {"level":"info","ts":"2026-10-15T19:00:45.594218Z","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-15T19:00:45.595374Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.242:2379"]} {"level":"info","ts":"2026-10-15T19:00:45.595507Z","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.242:2380"],"listen-peer-urls":["https://162.253.55.242:2380"],"advertise-client-urls":["https://162.253.55.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.242: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-15T19:00:45.599695Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.174423ms"} {"level":"info","ts":"2026-10-15T19:00:45.606784Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cc70bec9404419f6","cluster-id":"38671954b404c50e"} {"level":"info","ts":"2026-10-15T19:00:45.606922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T19:00:45.606962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 became follower at term 0"} {"level":"info","ts":"2026-10-15T19:00:45.606988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cc70bec9404419f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T19:00:45.607006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 became follower at term 1"} {"level":"info","ts":"2026-10-15T19:00:45.607072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 switched to configuration voters=(14731484152704801270)"} {"level":"warn","ts":"2026-10-15T19:00:45.613448Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T19:00:45.61542Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T19:00:45.617004Z","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-15T19:00:45.620239Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cc70bec9404419f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T19:00:45.620618Z","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-15T19:00:45.620964Z","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-15T19:00:45.621034Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cc70bec9404419f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T19:00:45.621213Z","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-15T19:00:45.623411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 switched to configuration voters=(14731484152704801270)"} {"level":"info","ts":"2026-10-15T19:00:45.623604Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"38671954b404c50e","local-member-id":"cc70bec9404419f6","added-peer-id":"cc70bec9404419f6","added-peer-peer-urls":["https://162.253.55.242:2380"]} {"level":"info","ts":"2026-10-15T19:00:45.626648Z","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-15T19:00:45.626846Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.242:2380"} {"level":"info","ts":"2026-10-15T19:00:45.626903Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.242:2380"} {"level":"info","ts":"2026-10-15T19:00:45.627101Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cc70bec9404419f6","initial-advertise-peer-urls":["https://162.253.55.242:2380"],"listen-peer-urls":["https://162.253.55.242:2380"],"advertise-client-urls":["https://162.253.55.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T19:00:45.627169Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T19:00:45.807589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T19:00:45.807668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T19:00:45.807717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 received MsgPreVoteResp from cc70bec9404419f6 at term 1"} {"level":"info","ts":"2026-10-15T19:00:45.807748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 became candidate at term 2"} {"level":"info","ts":"2026-10-15T19:00:45.807765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 received MsgVoteResp from cc70bec9404419f6 at term 2"} {"level":"info","ts":"2026-10-15T19:00:45.807786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 became leader at term 2"} {"level":"info","ts":"2026-10-15T19:00:45.807825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cc70bec9404419f6 elected leader cc70bec9404419f6 at term 2"} {"level":"info","ts":"2026-10-15T19:00:45.809041Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T19:00:45.810128Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T19:00:45.810129Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cc70bec9404419f6","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.242:2379]}","request-path":"/0/members/cc70bec9404419f6/attributes","cluster-id":"38671954b404c50e","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T19:00:45.810209Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T19:00:45.81051Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T19:00:45.810627Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T19:00:45.81077Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"38671954b404c50e","local-member-id":"cc70bec9404419f6","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T19:00:45.810958Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T19:00:45.811003Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T19:00:45.813846Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T19:00:45.816276Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.242:2379"} {"level":"info","ts":"2026-10-15T19:00:52.785878Z","caller":"traceutil/trace.go:171","msg":"trace[1937334796] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"149.921246ms","start":"2026-10-15T19:00:52.635929Z","end":"2026-10-15T19:00:52.78585Z","steps":["trace[1937334796] 'process raft request' (duration: 87.566465ms)","trace[1937334796] 'compare' (duration: 62.186107ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:00:52.798429Z","caller":"traceutil/trace.go:171","msg":"trace[1157687334] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"156.464758ms","start":"2026-10-15T19:00:52.641905Z","end":"2026-10-15T19:00:52.79837Z","steps":["trace[1157687334] 'process raft request' (duration: 156.172211ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:00.363354Z","caller":"traceutil/trace.go:171","msg":"trace[129921558] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"344.623699ms","start":"2026-10-15T19:01:00.018693Z","end":"2026-10-15T19:01:00.363316Z","steps":["trace[129921558] 'process raft request' (duration: 311.323549ms)","trace[129921558] 'compare' (duration: 33.144406ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:01:00.364246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:00.018635Z","time spent":"344.829093ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:01:00.852536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.890616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:01:00.852639Z","caller":"traceutil/trace.go:171","msg":"trace[2125583286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"182.05974ms","start":"2026-10-15T19:01:00.670557Z","end":"2026-10-15T19:01:00.852617Z","steps":["trace[2125583286] 'range keys from in-memory index tree' (duration: 181.774473ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:01.410266Z","caller":"traceutil/trace.go:171","msg":"trace[1541915368] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"494.062624ms","start":"2026-10-15T19:01:00.916174Z","end":"2026-10-15T19:01:01.410237Z","steps":["trace[1541915368] 'read index received' (duration: 493.821248ms)","trace[1541915368] 'applied index is now lower than readState.Index' (duration: 240.466µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:01:01.410319Z","caller":"traceutil/trace.go:171","msg":"trace[1206428826] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"504.999898ms","start":"2026-10-15T19:01:00.905273Z","end":"2026-10-15T19:01:01.410273Z","steps":["trace[1206428826] 'process raft request' (duration: 504.792283ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:01.410608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:00.90525Z","time spent":"505.252723ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:01:01.410626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.423633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-15T19:01:01.410916Z","caller":"traceutil/trace.go:171","msg":"trace[1333090902] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:412; }","duration":"494.72172ms","start":"2026-10-15T19:01:00.916162Z","end":"2026-10-15T19:01:01.410884Z","steps":["trace[1333090902] 'agreement among raft nodes before linearized reading' (duration: 494.266929ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:01.410996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:00.916118Z","time spent":"494.858323ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-15T19:01:37.004322Z","caller":"traceutil/trace.go:171","msg":"trace[871449711] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"168.274744ms","start":"2026-10-15T19:01:36.836002Z","end":"2026-10-15T19:01:37.004277Z","steps":["trace[871449711] 'process raft request' (duration: 166.930772ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:37.272924Z","caller":"traceutil/trace.go:171","msg":"trace[1333183138] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"163.822249ms","start":"2026-10-15T19:01:37.109072Z","end":"2026-10-15T19:01:37.272894Z","steps":["trace[1333183138] 'process raft request' (duration: 146.552424ms)","trace[1333183138] 'compare' (duration: 17.093501ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:01:37.917963Z","caller":"traceutil/trace.go:171","msg":"trace[231017955] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"247.341891ms","start":"2026-10-15T19:01:37.670595Z","end":"2026-10-15T19:01:37.917937Z","steps":["trace[231017955] 'read index received' (duration: 247.091015ms)","trace[231017955] 'applied index is now lower than readState.Index' (duration: 249.716µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:01:37.918133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.509494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:01:37.918178Z","caller":"traceutil/trace.go:171","msg":"trace[1507266322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:658; }","duration":"247.626178ms","start":"2026-10-15T19:01:37.670537Z","end":"2026-10-15T19:01:37.918163Z","steps":["trace[1507266322] 'agreement among raft nodes before linearized reading' (duration: 247.508455ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:37.918338Z","caller":"traceutil/trace.go:171","msg":"trace[315558266] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"286.03723ms","start":"2026-10-15T19:01:37.632272Z","end":"2026-10-15T19:01:37.91831Z","steps":["trace[315558266] 'process raft request' (duration: 285.464227ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:38.280844Z","caller":"traceutil/trace.go:171","msg":"trace[1991726831] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"360.804567ms","start":"2026-10-15T19:01:37.919969Z","end":"2026-10-15T19:01:38.280774Z","steps":["trace[1991726831] 'read index received' (duration: 350.807532ms)","trace[1991726831] 'applied index is now lower than readState.Index' (duration: 9.995845ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:01:38.281012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.016532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:01:38.281121Z","caller":"traceutil/trace.go:171","msg":"trace[1440311197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"361.192996ms","start":"2026-10-15T19:01:37.919897Z","end":"2026-10-15T19:01:38.28109Z","steps":["trace[1440311197] 'agreement among raft nodes before linearized reading' (duration: 361.012641ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:38.281191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:37.91987Z","time spent":"361.309969ms","remote":"127.0.0.1:48710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-15T19:01:38.281478Z","caller":"traceutil/trace.go:171","msg":"trace[992500821] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"605.575127ms","start":"2026-10-15T19:01:37.67529Z","end":"2026-10-15T19:01:38.280865Z","steps":["trace[992500821] 'process raft request' (duration: 595.814168ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:38.281954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:37.675269Z","time spent":"606.279653ms","remote":"127.0.0.1:48796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:01:50.765629Z","caller":"traceutil/trace.go:171","msg":"trace[1691491129] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"108.891928ms","start":"2026-10-15T19:01:50.656706Z","end":"2026-10-15T19:01:50.765598Z","steps":["trace[1691491129] 'process raft request' (duration: 108.667203ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:54.857492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.270396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:01:54.85761Z","caller":"traceutil/trace.go:171","msg":"trace[1829886872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:828; }","duration":"186.42591ms","start":"2026-10-15T19:01:54.671145Z","end":"2026-10-15T19:01:54.857571Z","steps":["trace[1829886872] 'range keys from in-memory index tree' (duration: 186.171124ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:54.857895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.486363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hlvwc\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-10-15T19:01:54.858018Z","caller":"traceutil/trace.go:171","msg":"trace[1433320681] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hlvwc; range_end:; response_count:1; response_revision:828; }","duration":"112.680897ms","start":"2026-10-15T19:01:54.745311Z","end":"2026-10-15T19:01:54.857992Z","steps":["trace[1433320681] 'range keys from in-memory index tree' (duration: 112.223247ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:02:46.701649Z","caller":"traceutil/trace.go:171","msg":"trace[2141437360] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"103.375686ms","start":"2026-10-15T19:02:46.598241Z","end":"2026-10-15T19:02:46.701616Z","steps":["trace[2141437360] 'process raft request' (duration: 22.073066ms)","trace[2141437360] 'compare' (duration: 81.009084ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:03:14.618508Z","caller":"traceutil/trace.go:171","msg":"trace[1137470916] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"354.510256ms","start":"2026-10-15T19:03:14.263963Z","end":"2026-10-15T19:03:14.618473Z","steps":["trace[1137470916] 'process raft request' (duration: 354.253631ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:03:14.618848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:03:14.263929Z","time spent":"354.767382ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:03:14.782078Z","caller":"traceutil/trace.go:171","msg":"trace[1171789167] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"508.481757ms","start":"2026-10-15T19:03:14.27353Z","end":"2026-10-15T19:03:14.782012Z","steps":["trace[1171789167] 'process raft request' (duration: 507.953236ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:03:14.782186Z","caller":"traceutil/trace.go:171","msg":"trace[185580169] linearizableReadLoop","detail":"{readStateIndex:1970; appliedIndex:1967; }","duration":"281.080269ms","start":"2026-10-15T19:03:14.501065Z","end":"2026-10-15T19:03:14.782145Z","steps":["trace[185580169] 'read index received' (duration: 117.260272ms)","trace[185580169] 'applied index is now lower than readState.Index' (duration: 163.812327ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:03:14.782356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:03:14.273509Z","time spent":"508.683871ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10929,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:03:14.78244Z","caller":"traceutil/trace.go:171","msg":"trace[1877304848] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"357.39205ms","start":"2026-10-15T19:03:14.424969Z","end":"2026-10-15T19:03:14.782361Z","steps":["trace[1877304848] 'process raft request' (duration: 356.897699ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:03:14.782585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.505059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e46c1f73-7327-41df-8184-5c53f087a76d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-15T19:03:14.782634Z","caller":"traceutil/trace.go:171","msg":"trace[1713644938] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e46c1f73-7327-41df-8184-5c53f087a76d; range_end:; response_count:1; response_revision:1898; }","duration":"281.627742ms","start":"2026-10-15T19:03:14.500994Z","end":"2026-10-15T19:03:14.782622Z","steps":["trace[1713644938] 'agreement among raft nodes before linearized reading' (duration: 281.348986ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:03:14.782588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.197261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-15T19:03:14.782708Z","caller":"traceutil/trace.go:171","msg":"trace[1802143217] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1898; }","duration":"256.388105ms","start":"2026-10-15T19:03:14.526301Z","end":"2026-10-15T19:03:14.782689Z","steps":["trace[1802143217] 'agreement among raft nodes before linearized reading' (duration: 256.120719ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:03:14.782702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:03:14.424939Z","time spent":"357.608284ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:03:14.962478Z","caller":"traceutil/trace.go:171","msg":"trace[685580148] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"171.557967ms","start":"2026-10-15T19:03:14.790867Z","end":"2026-10-15T19:03:14.962425Z","steps":["trace[685580148] 'process raft request' (duration: 105.916242ms)","trace[685580148] 'compare' (duration: 65.349709ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:03:14.962514Z","caller":"traceutil/trace.go:171","msg":"trace[316444778] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"170.591186ms","start":"2026-10-15T19:03:14.79188Z","end":"2026-10-15T19:03:14.962471Z","steps":["trace[316444778] 'process raft request' (duration: 170.431413ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:03:14.962862Z","caller":"traceutil/trace.go:171","msg":"trace[1291353505] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"168.844618ms","start":"2026-10-15T19:03:14.793976Z","end":"2026-10-15T19:03:14.962821Z","steps":["trace[1291353505] 'process raft request' (duration: 168.380218ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:03:14.963196Z","caller":"traceutil/trace.go:171","msg":"trace[668011822] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"169.065993ms","start":"2026-10-15T19:03:14.794109Z","end":"2026-10-15T19:03:14.963175Z","steps":["trace[668011822] 'process raft request' (duration: 168.623523ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:03:14.97598Z","caller":"traceutil/trace.go:171","msg":"trace[686910914] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"174.949952ms","start":"2026-10-15T19:03:14.801006Z","end":"2026-10-15T19:03:14.975956Z","steps":["trace[686910914] 'process raft request' (duration: 174.716397ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:03:15.226706Z","caller":"traceutil/trace.go:171","msg":"trace[158593903] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1979; }","duration":"171.008086ms","start":"2026-10-15T19:03:15.055621Z","end":"2026-10-15T19:03:15.226629Z","steps":["trace[158593903] 'read index received' (duration: 86.999806ms)","trace[158593903] 'applied index is now lower than readState.Index' (duration: 84.00657ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:03:15.227024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.359424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-15T19:03:15.227086Z","caller":"traceutil/trace.go:171","msg":"trace[1007243941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1907; }","duration":"171.524657ms","start":"2026-10-15T19:03:15.055545Z","end":"2026-10-15T19:03:15.22707Z","steps":["trace[1007243941] 'agreement among raft nodes before linearized reading' (duration: 171.272862ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:03:15.227068Z","caller":"traceutil/trace.go:171","msg":"trace[975025739] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"194.222577ms","start":"2026-10-15T19:03:15.0328Z","end":"2026-10-15T19:03:15.227022Z","steps":["trace[975025739] 'process raft request' (duration: 110.044393ms)","trace[975025739] 'compare' (duration: 83.608451ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:03:55.215085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.442283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-15T19:03:55.215184Z","caller":"traceutil/trace.go:171","msg":"trace[1350832921] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2172; }","duration":"127.596657ms","start":"2026-10-15T19:03:55.087568Z","end":"2026-10-15T19:03:55.215165Z","steps":["trace[1350832921] 'range keys from in-memory index tree' (duration: 127.221448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:03:55.215241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.437766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-15T19:03:55.215318Z","caller":"traceutil/trace.go:171","msg":"trace[484567465] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2172; }","duration":"168.59127ms","start":"2026-10-15T19:03:55.046708Z","end":"2026-10-15T19:03:55.215299Z","steps":["trace[484567465] 'range keys from in-memory index tree' (duration: 168.17536ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:01.330693Z","caller":"traceutil/trace.go:171","msg":"trace[300115168] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"346.449477ms","start":"2026-10-15T19:04:00.98418Z","end":"2026-10-15T19:04:01.33063Z","steps":["trace[300115168] 'process raft request' (duration: 346.243412ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:01.33092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:04:00.984148Z","time spent":"346.656762ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:04:54.016249Z","caller":"traceutil/trace.go:171","msg":"trace[1326156685] linearizableReadLoop","detail":"{readStateIndex:2764; appliedIndex:2763; }","duration":"114.96498ms","start":"2026-10-15T19:04:53.901245Z","end":"2026-10-15T19:04:54.01621Z","steps":["trace[1326156685] 'read index received' (duration: 114.746555ms)","trace[1326156685] 'applied index is now lower than readState.Index' (duration: 217.485µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:04:54.016559Z","caller":"traceutil/trace.go:171","msg":"trace[667903982] transaction","detail":"{read_only:false; response_revision:2662; number_of_response:1; }","duration":"266.968439ms","start":"2026-10-15T19:04:53.749579Z","end":"2026-10-15T19:04:54.016547Z","steps":["trace[667903982] 'process raft request' (duration: 266.482847ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:54.016853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.597384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-15T19:04:54.016892Z","caller":"traceutil/trace.go:171","msg":"trace[694190499] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2662; }","duration":"115.686897ms","start":"2026-10-15T19:04:53.901195Z","end":"2026-10-15T19:04:54.016882Z","steps":["trace[694190499] 'agreement among raft nodes before linearized reading' (duration: 115.526213ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:05:27.843008Z","caller":"traceutil/trace.go:171","msg":"trace[1941297899] transaction","detail":"{read_only:false; response_revision:2862; number_of_response:1; }","duration":"217.19751ms","start":"2026-10-15T19:05:27.625779Z","end":"2026-10-15T19:05:27.842976Z","steps":["trace[1941297899] 'process raft request' (duration: 216.995985ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:05:27.894009Z","caller":"traceutil/trace.go:171","msg":"trace[152775656] transaction","detail":"{read_only:false; response_revision:2863; number_of_response:1; }","duration":"230.707837ms","start":"2026-10-15T19:05:27.663272Z","end":"2026-10-15T19:05:27.89398Z","steps":["trace[152775656] 'process raft request' (duration: 230.510103ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:05:27.894157Z","caller":"traceutil/trace.go:171","msg":"trace[811395158] linearizableReadLoop","detail":"{readStateIndex:2972; appliedIndex:2970; }","duration":"223.12945ms","start":"2026-10-15T19:05:27.670989Z","end":"2026-10-15T19:05:27.894118Z","steps":["trace[811395158] 'read index received' (duration: 171.846196ms)","trace[811395158] 'applied index is now lower than readState.Index' (duration: 51.280264ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:05:27.894163Z","caller":"traceutil/trace.go:171","msg":"trace[815466619] transaction","detail":"{read_only:false; response_revision:2864; number_of_response:1; }","duration":"222.936275ms","start":"2026-10-15T19:05:27.671194Z","end":"2026-10-15T19:05:27.89413Z","steps":["trace[815466619] 'process raft request' (duration: 222.7362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:27.902315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.736424ms","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-15T19:05:27.902336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.323942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T19:05:27.90236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.34908ms","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-15T19:05:27.902386Z","caller":"traceutil/trace.go:171","msg":"trace[1926780688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2864; }","duration":"231.422004ms","start":"2026-10-15T19:05:27.67095Z","end":"2026-10-15T19:05:27.902372Z","steps":["trace[1926780688] 'agreement among raft nodes before linearized reading' (duration: 223.364105ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:05:27.902385Z","caller":"traceutil/trace.go:171","msg":"trace[1795318931] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2864; }","duration":"163.841847ms","start":"2026-10-15T19:05:27.738523Z","end":"2026-10-15T19:05:27.902364Z","steps":["trace[1795318931] 'agreement among raft nodes before linearized reading' (duration: 155.87243ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:05:27.902411Z","caller":"traceutil/trace.go:171","msg":"trace[1581690806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2864; }","duration":"124.455342ms","start":"2026-10-15T19:05:27.777942Z","end":"2026-10-15T19:05:27.902398Z","steps":["trace[1581690806] 'agreement among raft nodes before linearized reading' (duration: 116.471405ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:02.150835Z","caller":"traceutil/trace.go:171","msg":"trace[1866631537] transaction","detail":"{read_only:false; response_revision:3820; number_of_response:1; }","duration":"109.102148ms","start":"2026-10-15T19:08:02.041697Z","end":"2026-10-15T19:08:02.1508Z","steps":["trace[1866631537] 'process raft request' (duration: 108.915153ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:02.150891Z","caller":"traceutil/trace.go:171","msg":"trace[1870025068] linearizableReadLoop","detail":"{readStateIndex:3966; appliedIndex:3965; }","duration":"110.865159ms","start":"2026-10-15T19:08:02.04Z","end":"2026-10-15T19:08:02.150865Z","steps":["trace[1870025068] 'read index received' (duration: 99.600246ms)","trace[1870025068] 'applied index is now lower than readState.Index' (duration: 11.263113ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:08:02.150913Z","caller":"traceutil/trace.go:171","msg":"trace[1091538882] transaction","detail":"{read_only:false; response_revision:3819; number_of_response:1; }","duration":"110.982092ms","start":"2026-10-15T19:08:02.039908Z","end":"2026-10-15T19:08:02.15089Z","steps":["trace[1091538882] 'process raft request' (duration: 99.694348ms)","trace[1091538882] 'compare' (duration: 10.70064ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:08:02.151047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.011573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/secretgen-controller/secretgen-controller-sa\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:08:02.15109Z","caller":"traceutil/trace.go:171","msg":"trace[1194310872] range","detail":"{range_begin:/registry/serviceaccounts/secretgen-controller/secretgen-controller-sa; range_end:; response_count:0; response_revision:3820; }","duration":"111.129155ms","start":"2026-10-15T19:08:02.039946Z","end":"2026-10-15T19:08:02.151076Z","steps":["trace[1194310872] 'agreement among raft nodes before linearized reading' (duration: 111.004772ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:35.407478Z","caller":"traceutil/trace.go:171","msg":"trace[2123305258] linearizableReadLoop","detail":"{readStateIndex:4207; appliedIndex:4206; }","duration":"218.77574ms","start":"2026-10-15T19:08:35.188681Z","end":"2026-10-15T19:08:35.407456Z","steps":["trace[2123305258] 'read index received' (duration: 201.62625ms)","trace[2123305258] 'applied index is now lower than readState.Index' (duration: 17.14826ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:08:35.407689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.148715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-15T19:08:35.407702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.009416ms","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-15T19:08:35.407785Z","caller":"traceutil/trace.go:171","msg":"trace[2096665722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4053; }","duration":"219.128469ms","start":"2026-10-15T19:08:35.188641Z","end":"2026-10-15T19:08:35.407769Z","steps":["trace[2096665722] 'agreement among raft nodes before linearized reading' (duration: 218.905703ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:08:35.40782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.258343ms","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-15T19:08:35.408117Z","caller":"traceutil/trace.go:171","msg":"trace[1036322562] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4053; }","duration":"115.520259ms","start":"2026-10-15T19:08:35.292554Z","end":"2026-10-15T19:08:35.408074Z","steps":["trace[1036322562] 'agreement among raft nodes before linearized reading' (duration: 115.214792ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:35.407725Z","caller":"traceutil/trace.go:171","msg":"trace[880122568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4053; }","duration":"218.221037ms","start":"2026-10-15T19:08:35.189493Z","end":"2026-10-15T19:08:35.407714Z","steps":["trace[880122568] 'agreement among raft nodes before linearized reading' (duration: 218.090594ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:09:55.248849Z","caller":"traceutil/trace.go:171","msg":"trace[301466789] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"107.272107ms","start":"2026-10-15T19:09:55.141552Z","end":"2026-10-15T19:09:55.248825Z","steps":["trace[301466789] 'process raft request' (duration: 107.00984ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:10:03.730913Z","caller":"traceutil/trace.go:171","msg":"trace[1707850431] linearizableReadLoop","detail":"{readStateIndex:5455; appliedIndex:5454; }","duration":"436.912097ms","start":"2026-10-15T19:10:03.293975Z","end":"2026-10-15T19:10:03.730888Z","steps":["trace[1707850431] 'read index received' (duration: 436.725452ms)","trace[1707850431] 'applied index is now lower than readState.Index' (duration: 185.614µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:10:03.731251Z","caller":"traceutil/trace.go:171","msg":"trace[2098040520] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"457.464017ms","start":"2026-10-15T19:10:03.273772Z","end":"2026-10-15T19:10:03.731236Z","steps":["trace[2098040520] 'process raft request' (duration: 456.938084ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:03.731351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:10:03.27375Z","time spent":"457.528019ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3706,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:10:03.731534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.572572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-15T19:10:03.731561Z","caller":"traceutil/trace.go:171","msg":"trace[631226560] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5257; }","duration":"437.644644ms","start":"2026-10-15T19:10:03.293907Z","end":"2026-10-15T19:10:03.731552Z","steps":["trace[631226560] 'agreement among raft nodes before linearized reading' (duration: 437.538412ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:03.731585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:10:03.293884Z","time spent":"437.695015ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-10-15T19:10:03.731683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.062587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-300135ea-d79d-435d-be58-43e705ffae69\" ","response":"range_response_count:1 size:3721"} {"level":"info","ts":"2026-10-15T19:10:03.731751Z","caller":"traceutil/trace.go:171","msg":"trace[1998723936] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-300135ea-d79d-435d-be58-43e705ffae69; range_end:; response_count:1; response_revision:5257; }","duration":"418.160849ms","start":"2026-10-15T19:10:03.313562Z","end":"2026-10-15T19:10:03.731723Z","steps":["trace[1998723936] 'agreement among raft nodes before linearized reading' (duration: 418.050566ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:03.731777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:10:03.313545Z","time spent":"418.2268ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3744,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-300135ea-d79d-435d-be58-43e705ffae69\" "} {"level":"warn","ts":"2026-10-15T19:10:03.731984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.645755ms","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-15T19:10:03.732015Z","caller":"traceutil/trace.go:171","msg":"trace[2111096659] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5257; }","duration":"244.700117ms","start":"2026-10-15T19:10:03.487306Z","end":"2026-10-15T19:10:03.732006Z","steps":["trace[2111096659] 'agreement among raft nodes before linearized reading' (duration: 244.557513ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:03.732016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.274703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r8jpw\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-10-15T19:10:03.732079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.488779ms","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-15T19:10:03.732108Z","caller":"traceutil/trace.go:171","msg":"trace[1319281448] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r8jpw; range_end:; response_count:1; response_revision:5257; }","duration":"243.386355ms","start":"2026-10-15T19:10:03.488695Z","end":"2026-10-15T19:10:03.732081Z","steps":["trace[1319281448] 'agreement among raft nodes before linearized reading' (duration: 243.15009ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:10:03.732134Z","caller":"traceutil/trace.go:171","msg":"trace[1487222876] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5257; }","duration":"273.586631ms","start":"2026-10-15T19:10:03.458533Z","end":"2026-10-15T19:10:03.732119Z","steps":["trace[1487222876] 'agreement among raft nodes before linearized reading' (duration: 273.327055ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:03.732096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.501386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-96q7f-tls\" ","response":"range_response_count:1 size:1967"} {"level":"info","ts":"2026-10-15T19:10:03.732225Z","caller":"traceutil/trace.go:171","msg":"trace[484764552] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-96q7f-tls; range_end:; response_count:1; response_revision:5257; }","duration":"126.656559ms","start":"2026-10-15T19:10:03.605559Z","end":"2026-10-15T19:10:03.732216Z","steps":["trace[484764552] 'agreement among raft nodes before linearized reading' (duration: 126.350921ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:03.732427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.172205ms","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-15T19:10:03.73258Z","caller":"traceutil/trace.go:171","msg":"trace[742710289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5257; }","duration":"256.387059ms","start":"2026-10-15T19:10:03.476158Z","end":"2026-10-15T19:10:03.732545Z","steps":["trace[742710289] 'agreement among raft nodes before linearized reading' (duration: 255.696383ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:05.598879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.326922ms","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":"info","ts":"2026-10-15T19:10:05.598972Z","caller":"traceutil/trace.go:171","msg":"trace[1009079569] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:5292; }","duration":"129.473425ms","start":"2026-10-15T19:10:05.469477Z","end":"2026-10-15T19:10:05.59895Z","steps":["trace[1009079569] 'count revisions from in-memory index tree' (duration: 129.195388ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:10:17.985046Z","caller":"traceutil/trace.go:171","msg":"trace[1180026917] transaction","detail":"{read_only:false; response_revision:5522; number_of_response:1; }","duration":"112.530379ms","start":"2026-10-15T19:10:17.872484Z","end":"2026-10-15T19:10:17.985014Z","steps":["trace[1180026917] 'process raft request' (duration: 112.213302ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:10:18.118045Z","caller":"traceutil/trace.go:171","msg":"trace[139536402] transaction","detail":"{read_only:false; response_revision:5524; number_of_response:1; }","duration":"116.02521ms","start":"2026-10-15T19:10:18.001993Z","end":"2026-10-15T19:10:18.118018Z","steps":["trace[139536402] 'process raft request' (duration: 53.992232ms)","trace[139536402] 'compare' (duration: 61.891755ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:10:18.118113Z","caller":"traceutil/trace.go:171","msg":"trace[804058206] transaction","detail":"{read_only:false; response_revision:5525; number_of_response:1; }","duration":"113.144173ms","start":"2026-10-15T19:10:18.004945Z","end":"2026-10-15T19:10:18.118089Z","steps":["trace[804058206] 'process raft request' (duration: 113.085052ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:10:18.118122Z","caller":"traceutil/trace.go:171","msg":"trace[1097395477] linearizableReadLoop","detail":"{readStateIndex:5731; appliedIndex:5730; }","duration":"113.180864ms","start":"2026-10-15T19:10:18.00492Z","end":"2026-10-15T19:10:18.118101Z","steps":["trace[1097395477] 'read index received' (duration: 52.130228ms)","trace[1097395477] 'applied index is now lower than readState.Index' (duration: 61.048416ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:10:18.118273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.345828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T19:10:18.118307Z","caller":"traceutil/trace.go:171","msg":"trace[1310851618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5525; }","duration":"113.418029ms","start":"2026-10-15T19:10:18.004882Z","end":"2026-10-15T19:10:18.1183Z","steps":["trace[1310851618] 'agreement among raft nodes before linearized reading' (duration: 113.285456ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:10:18.571723Z","caller":"traceutil/trace.go:171","msg":"trace[2077537250] transaction","detail":"{read_only:false; response_revision:5527; number_of_response:1; }","duration":"117.066885ms","start":"2026-10-15T19:10:18.454626Z","end":"2026-10-15T19:10:18.571693Z","steps":["trace[2077537250] 'process raft request' (duration: 116.82439ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:10:20.231986Z","caller":"traceutil/trace.go:171","msg":"trace[1003774271] transaction","detail":"{read_only:false; response_revision:5556; number_of_response:1; }","duration":"105.583006ms","start":"2026-10-15T19:10:20.126365Z","end":"2026-10-15T19:10:20.231948Z","steps":["trace[1003774271] 'process raft request' (duration: 103.543858ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:26.21365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.343909ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:10:26.213819Z","caller":"traceutil/trace.go:171","msg":"trace[1896869413] linearizableReadLoop","detail":"{readStateIndex:5899; appliedIndex:5898; }","duration":"233.193068ms","start":"2026-10-15T19:10:25.980603Z","end":"2026-10-15T19:10:26.213797Z","steps":["trace[1896869413] 'read index received' (duration: 74.413318ms)","trace[1896869413] 'applied index is now lower than readState.Index' (duration: 158.777859ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:10:26.213882Z","caller":"traceutil/trace.go:171","msg":"trace[815788086] transaction","detail":"{read_only:false; response_revision:5686; number_of_response:1; }","duration":"238.498892ms","start":"2026-10-15T19:10:25.975343Z","end":"2026-10-15T19:10:26.213842Z","steps":["trace[815788086] 'process raft request' (duration: 79.63729ms)","trace[815788086] 'compare' (duration: 158.177106ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:10:26.214509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.875094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80096"} {"level":"info","ts":"2026-10-15T19:10:26.214647Z","caller":"traceutil/trace.go:171","msg":"trace[694855442] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5686; }","duration":"234.078009ms","start":"2026-10-15T19:10:25.980544Z","end":"2026-10-15T19:10:26.214622Z","steps":["trace[694855442] 'agreement among raft nodes before linearized reading' (duration: 233.328441ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:26.222418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.791348ms","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-15T19:10:26.222292Z","caller":"traceutil/trace.go:171","msg":"trace[1794244550] transaction","detail":"{read_only:false; response_revision:5687; number_of_response:1; }","duration":"213.301143ms","start":"2026-10-15T19:10:26.008965Z","end":"2026-10-15T19:10:26.222266Z","steps":["trace[1794244550] 'process raft request' (duration: 213.080998ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:10:26.222509Z","caller":"traceutil/trace.go:171","msg":"trace[532973962] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5687; }","duration":"205.920321ms","start":"2026-10-15T19:10:26.01657Z","end":"2026-10-15T19:10:26.222491Z","steps":["trace[532973962] 'agreement among raft nodes before linearized reading' (duration: 205.637534ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:26.222436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.305294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-10-15T19:10:26.222428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.944599ms","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-15T19:10:26.222674Z","caller":"traceutil/trace.go:171","msg":"trace[468328021] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5687; }","duration":"100.543279ms","start":"2026-10-15T19:10:26.122057Z","end":"2026-10-15T19:10:26.222601Z","steps":["trace[468328021] 'agreement among raft nodes before linearized reading' (duration: 100.270943ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:10:26.222716Z","caller":"traceutil/trace.go:171","msg":"trace[440000003] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:5687; }","duration":"128.284116ms","start":"2026-10-15T19:10:26.09441Z","end":"2026-10-15T19:10:26.222694Z","steps":["trace[440000003] 'agreement among raft nodes before linearized reading' (duration: 127.942858ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:26.485857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.429551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:10:26.485991Z","caller":"traceutil/trace.go:171","msg":"trace[488308233] linearizableReadLoop","detail":"{readStateIndex:5902; appliedIndex:5900; }","duration":"257.26428ms","start":"2026-10-15T19:10:26.228701Z","end":"2026-10-15T19:10:26.485965Z","steps":["trace[488308233] 'read index received' (duration: 73.426955ms)","trace[488308233] 'applied index is now lower than readState.Index' (duration: 183.835235ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:10:26.486277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.568747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19473"} {"level":"warn","ts":"2026-10-15T19:10:26.486293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.290157ms","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-15T19:10:26.486334Z","caller":"traceutil/trace.go:171","msg":"trace[79003265] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5689; }","duration":"257.648959ms","start":"2026-10-15T19:10:26.22867Z","end":"2026-10-15T19:10:26.486318Z","steps":["trace[79003265] 'agreement among raft nodes before linearized reading' (duration: 257.414284ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:10:26.486363Z","caller":"traceutil/trace.go:171","msg":"trace[2074170914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5689; }","duration":"226.389939ms","start":"2026-10-15T19:10:26.259955Z","end":"2026-10-15T19:10:26.486345Z","steps":["trace[2074170914] 'agreement among raft nodes before linearized reading' (duration: 226.198505ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:26.486353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.236672ms","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-15T19:10:26.486302Z","caller":"traceutil/trace.go:171","msg":"trace[1336159998] transaction","detail":"{read_only:false; response_revision:5689; number_of_response:1; }","duration":"258.114201ms","start":"2026-10-15T19:10:26.228043Z","end":"2026-10-15T19:10:26.486158Z","steps":["trace[1336159998] 'process raft request' (duration: 141.22224ms)","trace[1336159998] 'compare' (duration: 116.189354ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:10:26.486432Z","caller":"traceutil/trace.go:171","msg":"trace[2062112866] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5689; }","duration":"231.357376ms","start":"2026-10-15T19:10:26.255054Z","end":"2026-10-15T19:10:26.486411Z","steps":["trace[2062112866] 'agreement among raft nodes before linearized reading' (duration: 231.092199ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:10:46.214059Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2966} {"level":"info","ts":"2026-10-15T19:10:46.301202Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2966,"took":"85.528078ms","hash":2013336811,"current-db-size-bytes":56733696,"current-db-size":"57 MB","current-db-size-in-use-bytes":32378880,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T19:10:46.301271Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2013336811,"revision":2966,"compact-revision":-1} {"level":"info","ts":"2026-10-15T19:15:46.221194Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5905} {"level":"info","ts":"2026-10-15T19:15:46.310991Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5905,"took":"86.796793ms","hash":3330510183,"current-db-size-bytes":56733696,"current-db-size":"57 MB","current-db-size-in-use-bytes":26771456,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-15T19:15:46.311072Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3330510183,"revision":5905,"compact-revision":2966} {"level":"info","ts":"2026-10-15T19:16:25.959841Z","caller":"traceutil/trace.go:171","msg":"trace[2023962898] linearizableReadLoop","detail":"{readStateIndex:8787; appliedIndex:8786; }","duration":"287.857117ms","start":"2026-10-15T19:16:25.671953Z","end":"2026-10-15T19:16:25.95981Z","steps":["trace[2023962898] 'read index received' (duration: 287.644172ms)","trace[2023962898] 'applied index is now lower than readState.Index' (duration: 211.665µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:16:25.959881Z","caller":"traceutil/trace.go:171","msg":"trace[34677731] transaction","detail":"{read_only:false; response_revision:8485; number_of_response:1; }","duration":"352.048854ms","start":"2026-10-15T19:16:25.607809Z","end":"2026-10-15T19:16:25.959858Z","steps":["trace[34677731] 'process raft request' (duration: 351.794458ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:16:25.95998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.00864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:16:25.960017Z","caller":"traceutil/trace.go:171","msg":"trace[807881662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8485; }","duration":"288.098212ms","start":"2026-10-15T19:16:25.671909Z","end":"2026-10-15T19:16:25.960007Z","steps":["trace[807881662] 'agreement among raft nodes before linearized reading' (duration: 288.01891ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:16:25.960084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:16:25.607797Z","time spent":"352.187417ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:16:25.960148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.782563ms","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-15T19:16:25.960197Z","caller":"traceutil/trace.go:171","msg":"trace[956929438] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8485; }","duration":"107.863604ms","start":"2026-10-15T19:16:25.85232Z","end":"2026-10-15T19:16:25.960184Z","steps":["trace[956929438] 'agreement among raft nodes before linearized reading' (duration: 107.69143ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:16:26.202463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.047097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:16:26.202556Z","caller":"traceutil/trace.go:171","msg":"trace[822372413] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:8486; }","duration":"139.166549ms","start":"2026-10-15T19:16:26.063369Z","end":"2026-10-15T19:16:26.202535Z","steps":["trace[822372413] 'count revisions from in-memory index tree' (duration: 138.931394ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:17:14.538708Z","caller":"traceutil/trace.go:171","msg":"trace[52882718] transaction","detail":"{read_only:false; response_revision:8800; number_of_response:1; }","duration":"120.644786ms","start":"2026-10-15T19:17:14.417996Z","end":"2026-10-15T19:17:14.538641Z","steps":["trace[52882718] 'process raft request' (duration: 120.461842ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:17:14.576262Z","caller":"traceutil/trace.go:171","msg":"trace[292615872] transaction","detail":"{read_only:false; response_revision:8801; number_of_response:1; }","duration":"147.49875ms","start":"2026-10-15T19:17:14.428748Z","end":"2026-10-15T19:17:14.576247Z","steps":["trace[292615872] 'process raft request' (duration: 147.350327ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:17:21.557319Z","caller":"traceutil/trace.go:171","msg":"trace[925303059] transaction","detail":"{read_only:false; response_revision:8884; number_of_response:1; }","duration":"108.278974ms","start":"2026-10-15T19:17:21.449014Z","end":"2026-10-15T19:17:21.557293Z","steps":["trace[925303059] 'process raft request' (duration: 107.971147ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:17:34.620712Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002461.wal"} {"level":"warn","ts":"2026-10-15T19:18:40.89639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.432079ms","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-15T19:18:40.89648Z","caller":"traceutil/trace.go:171","msg":"trace[439342836] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9642; }","duration":"102.563251ms","start":"2026-10-15T19:18:40.793894Z","end":"2026-10-15T19:18:40.896458Z","steps":["trace[439342836] 'range keys from in-memory index tree' (duration: 102.259445ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:18:41.109841Z","caller":"traceutil/trace.go:171","msg":"trace[132484721] transaction","detail":"{read_only:false; response_revision:9644; number_of_response:1; }","duration":"217.91597ms","start":"2026-10-15T19:18:40.891906Z","end":"2026-10-15T19:18:41.109822Z","steps":["trace[132484721] 'process raft request' (duration: 183.005882ms)","trace[132484721] 'compare' (duration: 34.5749ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:18:41.109962Z","caller":"traceutil/trace.go:171","msg":"trace[609932024] transaction","detail":"{read_only:false; response_revision:9645; number_of_response:1; }","duration":"209.168477ms","start":"2026-10-15T19:18:40.900757Z","end":"2026-10-15T19:18:41.109925Z","steps":["trace[609932024] 'process raft request' (duration: 208.988473ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:18:43.889084Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cc70bec9404419f6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T19:18:43.896675Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-15T19:18:43.89683Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-15T19:20:46.228769Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8098} {"level":"info","ts":"2026-10-15T19:20:46.314215Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8098,"took":"83.723823ms","hash":4212356891,"current-db-size-bytes":56733696,"current-db-size":"57 MB","current-db-size-in-use-bytes":28876800,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-15T19:20:46.31427Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4212356891,"revision":8098,"compact-revision":5905} {"level":"warn","ts":"2026-10-15T19:20:57.461536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.688735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/staffeln-db-init-qnkq2\" ","response":"range_response_count:1 size:7101"} {"level":"info","ts":"2026-10-15T19:20:57.461658Z","caller":"traceutil/trace.go:171","msg":"trace[2031441133] range","detail":"{range_begin:/registry/pods/openstack/staffeln-db-init-qnkq2; range_end:; response_count:1; response_revision:10673; }","duration":"168.863099ms","start":"2026-10-15T19:20:57.292777Z","end":"2026-10-15T19:20:57.46164Z","steps":["trace[2031441133] 'range keys from in-memory index tree' (duration: 168.505711ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:22:21.742972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.68098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:22:21.74309Z","caller":"traceutil/trace.go:171","msg":"trace[1307207244] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11472; }","duration":"126.840803ms","start":"2026-10-15T19:22:21.616223Z","end":"2026-10-15T19:22:21.743064Z","steps":["trace[1307207244] 'range keys from in-memory index tree' (duration: 126.625739ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:22:21.743091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.861074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T19:22:21.743134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.275596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T19:22:21.743159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.207155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-15T19:22:21.743194Z","caller":"traceutil/trace.go:171","msg":"trace[992010946] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11472; }","duration":"131.375318ms","start":"2026-10-15T19:22:21.611803Z","end":"2026-10-15T19:22:21.743179Z","steps":["trace[992010946] 'range keys from in-memory index tree' (duration: 131.204844ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:22:21.743177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.836453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:22:21.743248Z","caller":"traceutil/trace.go:171","msg":"trace[1269096317] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11472; }","duration":"128.354358ms","start":"2026-10-15T19:22:21.614872Z","end":"2026-10-15T19:22:21.743226Z","steps":["trace[1269096317] 'range keys from in-memory index tree' (duration: 127.99459ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:22:21.743276Z","caller":"traceutil/trace.go:171","msg":"trace[696037031] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11472; }","duration":"126.988487ms","start":"2026-10-15T19:22:21.616262Z","end":"2026-10-15T19:22:21.74325Z","steps":["trace[696037031] 'range keys from in-memory index tree' (duration: 126.720081ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:22:21.743184Z","caller":"traceutil/trace.go:171","msg":"trace[220813306] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11472; }","duration":"126.998808ms","start":"2026-10-15T19:22:21.616164Z","end":"2026-10-15T19:22:21.743163Z","steps":["trace[220813306] 'range keys from in-memory index tree' (duration: 126.807953ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:22:21.74346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.267217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-10-15T19:22:21.743531Z","caller":"traceutil/trace.go:171","msg":"trace[1909371628] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11472; }","duration":"118.360079ms","start":"2026-10-15T19:22:21.625151Z","end":"2026-10-15T19:22:21.743511Z","steps":["trace[1909371628] 'range keys from in-memory index tree' (duration: 118.100973ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:25:46.236713Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10539} {"level":"info","ts":"2026-10-15T19:25:46.321806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10539,"took":"82.664827ms","hash":4055706526,"current-db-size-bytes":56733696,"current-db-size":"57 MB","current-db-size-in-use-bytes":27377664,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-15T19:25:46.321876Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4055706526,"revision":10539,"compact-revision":8098} {"level":"info","ts":"2026-10-15T19:28:07.952862Z","caller":"traceutil/trace.go:171","msg":"trace[1502163827] linearizableReadLoop","detail":"{readStateIndex:14316; appliedIndex:14315; }","duration":"101.136566ms","start":"2026-10-15T19:28:07.851701Z","end":"2026-10-15T19:28:07.952838Z","steps":["trace[1502163827] 'read index received' (duration: 100.910621ms)","trace[1502163827] 'applied index is now lower than readState.Index' (duration: 224.865µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:28:07.95293Z","caller":"traceutil/trace.go:171","msg":"trace[282439593] transaction","detail":"{read_only:false; response_revision:13843; number_of_response:1; }","duration":"136.158738ms","start":"2026-10-15T19:28:07.816739Z","end":"2026-10-15T19:28:07.952897Z","steps":["trace[282439593] 'process raft request' (duration: 135.952524ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:28:07.953093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.380871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r8jpw\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-15T19:28:07.953135Z","caller":"traceutil/trace.go:171","msg":"trace[1693813637] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r8jpw; range_end:; response_count:1; response_revision:13843; }","duration":"101.477134ms","start":"2026-10-15T19:28:07.851648Z","end":"2026-10-15T19:28:07.953125Z","steps":["trace[1693813637] 'agreement among raft nodes before linearized reading' (duration: 101.32616ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:28:31.094253Z","caller":"traceutil/trace.go:171","msg":"trace[1177891246] transaction","detail":"{read_only:false; response_revision:13967; number_of_response:1; }","duration":"110.096157ms","start":"2026-10-15T19:28:30.984129Z","end":"2026-10-15T19:28:31.094225Z","steps":["trace[1177891246] 'process raft request' (duration: 109.932133ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:28:42.271978Z","caller":"traceutil/trace.go:171","msg":"trace[1685473601] linearizableReadLoop","detail":"{readStateIndex:14522; appliedIndex:14521; }","duration":"113.730201ms","start":"2026-10-15T19:28:42.158226Z","end":"2026-10-15T19:28:42.271956Z","steps":["trace[1685473601] 'read index received' (duration: 113.593258ms)","trace[1685473601] 'applied index is now lower than readState.Index' (duration: 135.803µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:28:42.272028Z","caller":"traceutil/trace.go:171","msg":"trace[408159831] transaction","detail":"{read_only:false; response_revision:14041; number_of_response:1; }","duration":"120.959169ms","start":"2026-10-15T19:28:42.151042Z","end":"2026-10-15T19:28:42.272001Z","steps":["trace[408159831] 'process raft request' (duration: 120.771195ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:28:42.272209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.118661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-15T19:28:42.272288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.142998ms","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-15T19:28:42.272311Z","caller":"traceutil/trace.go:171","msg":"trace[512758559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14041; }","duration":"105.238834ms","start":"2026-10-15T19:28:42.167051Z","end":"2026-10-15T19:28:42.27229Z","steps":["trace[512758559] 'agreement among raft nodes before linearized reading' (duration: 105.06461ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:28:42.272336Z","caller":"traceutil/trace.go:171","msg":"trace[1837077550] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14041; }","duration":"104.250311ms","start":"2026-10-15T19:28:42.168076Z","end":"2026-10-15T19:28:42.272326Z","steps":["trace[1837077550] 'agreement among raft nodes before linearized reading' (duration: 104.048836ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:28:42.272195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.955836ms","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-15T19:28:42.272477Z","caller":"traceutil/trace.go:171","msg":"trace[1470780295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14041; }","duration":"114.288274ms","start":"2026-10-15T19:28:42.158168Z","end":"2026-10-15T19:28:42.272456Z","steps":["trace[1470780295] 'agreement among raft nodes before linearized reading' (duration: 113.869034ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:28:50.299598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.324698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-15T19:28:50.299717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.599713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T19:28:50.299787Z","caller":"traceutil/trace.go:171","msg":"trace[902020947] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:14084; }","duration":"158.706606ms","start":"2026-10-15T19:28:50.141059Z","end":"2026-10-15T19:28:50.299765Z","steps":["trace[902020947] 'count revisions from in-memory index tree' (duration: 158.502041ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:28:50.299797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.761633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:28:50.299894Z","caller":"traceutil/trace.go:171","msg":"trace[1021736614] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:14084; }","duration":"135.928687ms","start":"2026-10-15T19:28:50.163943Z","end":"2026-10-15T19:28:50.299871Z","steps":["trace[1021736614] 'count revisions from in-memory index tree' (duration: 135.563409ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:28:50.299902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.94254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:28:50.299679Z","caller":"traceutil/trace.go:171","msg":"trace[272503628] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:14084; }","duration":"239.457021ms","start":"2026-10-15T19:28:50.060202Z","end":"2026-10-15T19:28:50.299659Z","steps":["trace[272503628] 'count revisions from in-memory index tree' (duration: 239.252567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:28:50.29993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.029373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.242\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-10-15T19:28:50.299991Z","caller":"traceutil/trace.go:171","msg":"trace[1134041769] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:14084; }","duration":"633.088183ms","start":"2026-10-15T19:28:49.666878Z","end":"2026-10-15T19:28:50.299966Z","steps":["trace[1134041769] 'count revisions from in-memory index tree' (duration: 632.820177ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:28:50.300037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:28:49.666844Z","time spent":"633.183465ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true "} {"level":"warn","ts":"2026-10-15T19:28:50.300034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.580025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-15T19:28:50.300042Z","caller":"traceutil/trace.go:171","msg":"trace[1497624389] range","detail":"{range_begin:/registry/masterleases/162.253.55.242; range_end:; response_count:1; response_revision:14084; }","duration":"500.183396ms","start":"2026-10-15T19:28:49.799838Z","end":"2026-10-15T19:28:50.300021Z","steps":["trace[1497624389] 'range keys from in-memory index tree' (duration: 499.865179ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:28:50.300096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:28:49.799818Z","time spent":"500.268058ms","remote":"127.0.0.1:48740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":161,"request content":"key:\"/registry/masterleases/162.253.55.242\" "} {"level":"warn","ts":"2026-10-15T19:28:50.300047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.573971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:28:50.300097Z","caller":"traceutil/trace.go:171","msg":"trace[2129958906] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:14084; }","duration":"739.644787ms","start":"2026-10-15T19:28:49.560435Z","end":"2026-10-15T19:28:50.30008Z","steps":["trace[2129958906] 'range keys from in-memory index tree' (duration: 739.36939ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:28:50.300172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:28:49.560365Z","time spent":"739.79706ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-10-15T19:28:50.300088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.194747ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T19:28:50.299483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.424821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T19:28:50.300198Z","caller":"traceutil/trace.go:171","msg":"trace[1856840764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14084; }","duration":"629.788456ms","start":"2026-10-15T19:28:49.670376Z","end":"2026-10-15T19:28:50.300165Z","steps":["trace[1856840764] 'range keys from in-memory index tree' (duration: 629.428578ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:28:50.300237Z","caller":"traceutil/trace.go:171","msg":"trace[823684847] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:14084; }","duration":"786.358361ms","start":"2026-10-15T19:28:49.513856Z","end":"2026-10-15T19:28:50.300214Z","steps":["trace[823684847] 'range keys from in-memory index tree' (duration: 786.014753ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:28:50.300244Z","caller":"traceutil/trace.go:171","msg":"trace[65891448] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:14084; }","duration":"386.2553ms","start":"2026-10-15T19:28:49.91397Z","end":"2026-10-15T19:28:50.300225Z","steps":["trace[65891448] 'count revisions from in-memory index tree' (duration: 385.336829ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:28:50.300251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:28:49.67035Z","time spent":"629.889659ms","remote":"127.0.0.1:48710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T19:28:50.300299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:28:49.913949Z","time spent":"386.327433ms","remote":"127.0.0.1:49134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":33,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-10-15T19:29:58.839901Z","caller":"traceutil/trace.go:171","msg":"trace[2107975670] transaction","detail":"{read_only:false; response_revision:15120; number_of_response:1; }","duration":"161.664554ms","start":"2026-10-15T19:29:58.678215Z","end":"2026-10-15T19:29:58.83988Z","steps":["trace[2107975670] 'process raft request' (duration: 161.555132ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:29:58.840032Z","caller":"traceutil/trace.go:171","msg":"trace[1540487237] linearizableReadLoop","detail":"{readStateIndex:15629; appliedIndex:15629; }","duration":"161.538741ms","start":"2026-10-15T19:29:58.678466Z","end":"2026-10-15T19:29:58.840005Z","steps":["trace[1540487237] 'read index received' (duration: 161.528361ms)","trace[1540487237] 'applied index is now lower than readState.Index' (duration: 8.59µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:29:58.840246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.762526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T19:29:58.840322Z","caller":"traceutil/trace.go:171","msg":"trace[617560708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15120; }","duration":"161.860659ms","start":"2026-10-15T19:29:58.678444Z","end":"2026-10-15T19:29:58.840305Z","steps":["trace[617560708] 'agreement among raft nodes before linearized reading' (duration: 161.668655ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:29:58.840367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.453537ms","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-15T19:29:58.840441Z","caller":"traceutil/trace.go:171","msg":"trace[1181696113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15120; }","duration":"160.575209ms","start":"2026-10-15T19:29:58.679849Z","end":"2026-10-15T19:29:58.840424Z","steps":["trace[1181696113] 'agreement among raft nodes before linearized reading' (duration: 160.311443ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:30:33.702066Z","caller":"traceutil/trace.go:171","msg":"trace[752469316] transaction","detail":"{read_only:false; response_revision:15305; number_of_response:1; }","duration":"106.522854ms","start":"2026-10-15T19:30:33.595521Z","end":"2026-10-15T19:30:33.702044Z","steps":["trace[752469316] 'process raft request' (duration: 106.382921ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:30:33.795665Z","caller":"traceutil/trace.go:171","msg":"trace[1711794159] transaction","detail":"{read_only:false; response_revision:15306; number_of_response:1; }","duration":"115.268957ms","start":"2026-10-15T19:30:33.680362Z","end":"2026-10-15T19:30:33.795631Z","steps":["trace[1711794159] 'process raft request' (duration: 114.872467ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:30:33.795712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.964878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-9rq8c\" ","response":"range_response_count:1 size:22125"} {"level":"warn","ts":"2026-10-15T19:30:33.795675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.813179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:30:33.795781Z","caller":"traceutil/trace.go:171","msg":"trace[1428212183] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-9rq8c; range_end:; response_count:1; response_revision:15306; }","duration":"105.080721ms","start":"2026-10-15T19:30:33.690681Z","end":"2026-10-15T19:30:33.795762Z","steps":["trace[1428212183] 'agreement among raft nodes before linearized reading' (duration: 104.820045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:30:33.79576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.533979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-9rq8c\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-10-15T19:30:33.795816Z","caller":"traceutil/trace.go:171","msg":"trace[1315347743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15306; }","duration":"125.011544ms","start":"2026-10-15T19:30:33.670763Z","end":"2026-10-15T19:30:33.795775Z","steps":["trace[1315347743] 'agreement among raft nodes before linearized reading' (duration: 124.741788ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:30:33.795853Z","caller":"traceutil/trace.go:171","msg":"trace[1994034652] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-9rq8c; range_end:; response_count:1; response_revision:15306; }","duration":"129.677262ms","start":"2026-10-15T19:30:33.666151Z","end":"2026-10-15T19:30:33.795828Z","steps":["trace[1994034652] 'agreement among raft nodes before linearized reading' (duration: 129.367225ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:30:46.244516Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12794} {"level":"info","ts":"2026-10-15T19:30:46.327504Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12794,"took":"80.259204ms","hash":4120121224,"current-db-size-bytes":56733696,"current-db-size":"57 MB","current-db-size-in-use-bytes":31158272,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T19:30:46.327747Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4120121224,"revision":12794,"compact-revision":10539} {"level":"info","ts":"2026-10-15T19:35:46.251607Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15369} {"level":"info","ts":"2026-10-15T19:35:46.34977Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15369,"took":"95.304392ms","hash":468365086,"current-db-size-bytes":56733696,"current-db-size":"57 MB","current-db-size-in-use-bytes":32411648,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T19:35:46.349839Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":468365086,"revision":15369,"compact-revision":12794} {"level":"warn","ts":"2026-10-15T19:36:15.27063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.382055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-dprdw\" ","response":"range_response_count:1 size:20756"} {"level":"info","ts":"2026-10-15T19:36:15.270742Z","caller":"traceutil/trace.go:171","msg":"trace[419677963] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-dprdw; range_end:; response_count:1; response_revision:17897; }","duration":"131.537099ms","start":"2026-10-15T19:36:15.139183Z","end":"2026-10-15T19:36:15.270721Z","steps":["trace[419677963] 'range keys from in-memory index tree' (duration: 131.186121ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:39:09.839671Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cc70bec9404419f6","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T19:39:09.846241Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-15T19:39:09.846405Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-15T19:40:46.259321Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17619} {"level":"info","ts":"2026-10-15T19:40:46.367791Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17619,"took":"103.965872ms","hash":2275879358,"current-db-size-bytes":56733696,"current-db-size":"57 MB","current-db-size-in-use-bytes":34078720,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-15T19:40:46.367894Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2275879358,"revision":17619,"compact-revision":15369} {"level":"warn","ts":"2026-10-15T19:41:05.235122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.629523ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-15T19:41:21.025138Z","caller":"traceutil/trace.go:171","msg":"trace[1907610415] linearizableReadLoop","detail":"{readStateIndex:20961; appliedIndex:20960; }","duration":"154.095735ms","start":"2026-10-15T19:41:20.871012Z","end":"2026-10-15T19:41:21.025108Z","steps":["trace[1907610415] 'read index received' (duration: 153.88409ms)","trace[1907610415] 'applied index is now lower than readState.Index' (duration: 189.875µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:41:21.026223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.15953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-10-15T19:41:21.026443Z","caller":"traceutil/trace.go:171","msg":"trace[592918767] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:20278; }","duration":"155.396946ms","start":"2026-10-15T19:41:20.870969Z","end":"2026-10-15T19:41:21.026366Z","steps":["trace[592918767] 'agreement among raft nodes before linearized reading' (duration: 154.235729ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:41:21.034364Z","caller":"traceutil/trace.go:171","msg":"trace[269762633] transaction","detail":"{read_only:false; response_revision:20278; number_of_response:1; }","duration":"347.39204ms","start":"2026-10-15T19:41:20.678224Z","end":"2026-10-15T19:41:21.025616Z","steps":["trace[269762633] 'process raft request' (duration: 346.702734ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:41:21.034593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:41:20.678197Z","time spent":"356.265185ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:41:21.464455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.036959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T19:41:21.464612Z","caller":"traceutil/trace.go:171","msg":"trace[1622894676] transaction","detail":"{read_only:false; response_revision:20279; number_of_response:1; }","duration":"429.732849ms","start":"2026-10-15T19:41:21.034852Z","end":"2026-10-15T19:41:21.464585Z","steps":["trace[1622894676] 'process raft request' (duration: 123.397243ms)","trace[1622894676] 'compare' (duration: 305.853635ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:41:21.46468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:41:21.034597Z","time spent":"430.050077ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:41:21.818375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.128754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:41:21.81846Z","caller":"traceutil/trace.go:171","msg":"trace[983909835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20279; }","duration":"144.346749ms","start":"2026-10-15T19:41:21.674092Z","end":"2026-10-15T19:41:21.818439Z","steps":["trace[983909835] 'range keys from in-memory index tree' (duration: 143.913658ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:41:22.339675Z","caller":"traceutil/trace.go:171","msg":"trace[1689772369] transaction","detail":"{read_only:false; response_revision:20282; number_of_response:1; }","duration":"157.33924ms","start":"2026-10-15T19:41:22.182308Z","end":"2026-10-15T19:41:22.339647Z","steps":["trace[1689772369] 'process raft request' (duration: 157.256998ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:41:22.339705Z","caller":"traceutil/trace.go:171","msg":"trace[1286442640] transaction","detail":"{read_only:false; response_revision:20281; number_of_response:1; }","duration":"157.945055ms","start":"2026-10-15T19:41:22.181711Z","end":"2026-10-15T19:41:22.339656Z","steps":["trace[1286442640] 'process raft request' (duration: 157.013663ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:41:22.340055Z","caller":"traceutil/trace.go:171","msg":"trace[1483368948] transaction","detail":"{read_only:false; response_revision:20283; number_of_response:1; }","duration":"151.245979ms","start":"2026-10-15T19:41:22.188773Z","end":"2026-10-15T19:41:22.340019Z","steps":["trace[1483368948] 'process raft request' (duration: 150.835249ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:45:03.559153Z","caller":"traceutil/trace.go:171","msg":"trace[154119335] transaction","detail":"{read_only:false; response_revision:22136; number_of_response:1; }","duration":"209.167513ms","start":"2026-10-15T19:45:03.34996Z","end":"2026-10-15T19:45:03.559127Z","steps":["trace[154119335] 'process raft request' (duration: 209.012869ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:45:15.940577Z","caller":"traceutil/trace.go:171","msg":"trace[880944216] transaction","detail":"{read_only:false; response_revision:22717; number_of_response:1; }","duration":"291.349929ms","start":"2026-10-15T19:45:15.649197Z","end":"2026-10-15T19:45:15.940547Z","steps":["trace[880944216] 'process raft request' (duration: 206.678885ms)","trace[880944216] 'compare' (duration: 84.373497ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:45:15.940646Z","caller":"traceutil/trace.go:171","msg":"trace[687734639] transaction","detail":"{read_only:false; response_revision:22718; number_of_response:1; }","duration":"213.554665ms","start":"2026-10-15T19:45:15.727061Z","end":"2026-10-15T19:45:15.940616Z","steps":["trace[687734639] 'process raft request' (duration: 213.434772ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:45:15.940696Z","caller":"traceutil/trace.go:171","msg":"trace[1180394685] linearizableReadLoop","detail":"{readStateIndex:23471; appliedIndex:23470; }","duration":"268.562601ms","start":"2026-10-15T19:45:15.67208Z","end":"2026-10-15T19:45:15.940643Z","steps":["trace[1180394685] 'read index received' (duration: 183.814244ms)","trace[1180394685] 'applied index is now lower than readState.Index' (duration: 84.742286ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:45:15.940876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.765786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:45:15.940941Z","caller":"traceutil/trace.go:171","msg":"trace[1045664357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22718; }","duration":"268.914399ms","start":"2026-10-15T19:45:15.672012Z","end":"2026-10-15T19:45:15.940927Z","steps":["trace[1045664357] 'agreement among raft nodes before linearized reading' (duration: 268.785886ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:45:15.941006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.768494ms","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-15T19:45:15.9411Z","caller":"traceutil/trace.go:171","msg":"trace[2094977688] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22718; }","duration":"227.913808ms","start":"2026-10-15T19:45:15.713166Z","end":"2026-10-15T19:45:15.94108Z","steps":["trace[2094977688] 'agreement among raft nodes before linearized reading' (duration: 227.681242ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:45:15.941207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.132268ms","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-15T19:45:15.941295Z","caller":"traceutil/trace.go:171","msg":"trace[539317826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22718; }","duration":"189.283001ms","start":"2026-10-15T19:45:15.751995Z","end":"2026-10-15T19:45:15.941278Z","steps":["trace[539317826] 'agreement among raft nodes before linearized reading' (duration: 189.058106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:45:15.941421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.880543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-15T19:45:15.941462Z","caller":"traceutil/trace.go:171","msg":"trace[1221217589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22718; }","duration":"204.943455ms","start":"2026-10-15T19:45:15.736508Z","end":"2026-10-15T19:45:15.941452Z","steps":["trace[1221217589] 'agreement among raft nodes before linearized reading' (duration: 204.839923ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:45:15.941472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.202075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-15T19:45:15.941539Z","caller":"traceutil/trace.go:171","msg":"trace[1269798351] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22718; }","duration":"219.313208ms","start":"2026-10-15T19:45:15.722209Z","end":"2026-10-15T19:45:15.941522Z","steps":["trace[1269798351] 'agreement among raft nodes before linearized reading' (duration: 219.143884ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:45:15.941746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.025894ms","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-15T19:45:15.941852Z","caller":"traceutil/trace.go:171","msg":"trace[1008259945] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:22718; }","duration":"151.199118ms","start":"2026-10-15T19:45:15.790627Z","end":"2026-10-15T19:45:15.941826Z","steps":["trace[1008259945] 'agreement among raft nodes before linearized reading' (duration: 150.799529ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:45:46.266983Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20098} {"level":"info","ts":"2026-10-15T19:45:46.389965Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20098,"took":"118.544774ms","hash":3725718289,"current-db-size-bytes":56733696,"current-db-size":"57 MB","current-db-size-in-use-bytes":42823680,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-15T19:45:46.390039Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3725718289,"revision":20098,"compact-revision":17619} {"level":"info","ts":"2026-10-15T19:48:36.121031Z","caller":"traceutil/trace.go:171","msg":"trace[1013158088] transaction","detail":"{read_only:false; response_revision:24693; number_of_response:1; }","duration":"181.23376ms","start":"2026-10-15T19:48:35.939771Z","end":"2026-10-15T19:48:36.121005Z","steps":["trace[1013158088] 'process raft request' (duration: 181.088887ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:36.337092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.725162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T19:48:36.337193Z","caller":"traceutil/trace.go:171","msg":"trace[562610478] transaction","detail":"{read_only:false; response_revision:24694; number_of_response:1; }","duration":"356.671746ms","start":"2026-10-15T19:48:35.980503Z","end":"2026-10-15T19:48:36.337174Z","steps":["trace[562610478] 'process raft request' (duration: 248.776269ms)","trace[562610478] 'compare' (duration: 107.6156ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:48:36.33725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:35.980472Z","time spent":"356.749438ms","remote":"127.0.0.1:48938","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-15T19:48:36.353923Z","caller":"traceutil/trace.go:171","msg":"trace[817347045] transaction","detail":"{read_only:false; response_revision:24695; number_of_response:1; }","duration":"188.929629ms","start":"2026-10-15T19:48:36.164971Z","end":"2026-10-15T19:48:36.353901Z","steps":["trace[817347045] 'process raft request' (duration: 188.767765ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:36.354083Z","caller":"traceutil/trace.go:171","msg":"trace[413851228] transaction","detail":"{read_only:false; response_revision:24696; number_of_response:1; }","duration":"184.863735ms","start":"2026-10-15T19:48:36.169203Z","end":"2026-10-15T19:48:36.354066Z","steps":["trace[413851228] 'process raft request' (duration: 184.6559ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:36.354433Z","caller":"traceutil/trace.go:171","msg":"trace[234109544] transaction","detail":"{read_only:false; response_revision:24697; number_of_response:1; }","duration":"183.264667ms","start":"2026-10-15T19:48:36.17113Z","end":"2026-10-15T19:48:36.354395Z","steps":["trace[234109544] 'process raft request' (duration: 182.888549ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:36.711439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.190578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-10-15T19:48:36.71149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.062266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-10-15T19:48:36.711517Z","caller":"traceutil/trace.go:171","msg":"trace[1319218025] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:24697; }","duration":"159.28355ms","start":"2026-10-15T19:48:36.552213Z","end":"2026-10-15T19:48:36.711497Z","steps":["trace[1319218025] 'count revisions from in-memory index tree' (duration: 159.081866ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:36.711537Z","caller":"traceutil/trace.go:171","msg":"trace[455713479] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:24697; }","duration":"212.109267ms","start":"2026-10-15T19:48:36.499413Z","end":"2026-10-15T19:48:36.711522Z","steps":["trace[455713479] 'range keys from in-memory index tree' (duration: 211.893522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:36.712337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.94909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-10-15T19:48:36.71238Z","caller":"traceutil/trace.go:171","msg":"trace[27368434] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24697; }","duration":"176.994291ms","start":"2026-10-15T19:48:36.535375Z","end":"2026-10-15T19:48:36.712369Z","steps":["trace[27368434] 'range keys from in-memory index tree' (duration: 176.757556ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:37.147419Z","caller":"traceutil/trace.go:171","msg":"trace[53884959] linearizableReadLoop","detail":"{readStateIndex:25503; appliedIndex:25502; }","duration":"172.803354ms","start":"2026-10-15T19:48:36.974579Z","end":"2026-10-15T19:48:37.147382Z","steps":["trace[53884959] 'read index received' (duration: 172.576108ms)","trace[53884959] 'applied index is now lower than readState.Index' (duration: 225.866µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:48:37.147473Z","caller":"traceutil/trace.go:171","msg":"trace[1672629834] transaction","detail":"{read_only:false; response_revision:24698; number_of_response:1; }","duration":"335.654227ms","start":"2026-10-15T19:48:36.811792Z","end":"2026-10-15T19:48:37.147446Z","steps":["trace[1672629834] 'process raft request' (duration: 335.446102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:37.147629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.0446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-15T19:48:37.147659Z","caller":"traceutil/trace.go:171","msg":"trace[96091895] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24698; }","duration":"173.121251ms","start":"2026-10-15T19:48:36.974529Z","end":"2026-10-15T19:48:37.14765Z","steps":["trace[96091895] 'agreement among raft nodes before linearized reading' (duration: 172.995078ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:37.147657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:36.811745Z","time spent":"335.830051ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:48:37.147684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.721271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:48:37.147746Z","caller":"traceutil/trace.go:171","msg":"trace[1956077829] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:24698; }","duration":"119.826714ms","start":"2026-10-15T19:48:37.027901Z","end":"2026-10-15T19:48:37.147728Z","steps":["trace[1956077829] 'agreement among raft nodes before linearized reading' (duration: 119.695121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:37.147716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.561926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-15T19:48:37.147783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.90845ms","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-15T19:48:37.147809Z","caller":"traceutil/trace.go:171","msg":"trace[1566328490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24698; }","duration":"134.688739ms","start":"2026-10-15T19:48:37.013101Z","end":"2026-10-15T19:48:37.14779Z","steps":["trace[1566328490] 'agreement among raft nodes before linearized reading' (duration: 134.472044ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:37.147829Z","caller":"traceutil/trace.go:171","msg":"trace[1671854500] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24698; }","duration":"133.990173ms","start":"2026-10-15T19:48:37.013824Z","end":"2026-10-15T19:48:37.147814Z","steps":["trace[1671854500] 'agreement among raft nodes before linearized reading' (duration: 133.845849ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:37.147848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.373447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-15T19:48:37.147852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.736709ms","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-15T19:48:37.147885Z","caller":"traceutil/trace.go:171","msg":"trace[1540687026] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24698; }","duration":"142.421128ms","start":"2026-10-15T19:48:37.00545Z","end":"2026-10-15T19:48:37.147871Z","steps":["trace[1540687026] 'agreement among raft nodes before linearized reading' (duration: 142.336626ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:37.147886Z","caller":"traceutil/trace.go:171","msg":"trace[102719998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24698; }","duration":"137.78709ms","start":"2026-10-15T19:48:37.010087Z","end":"2026-10-15T19:48:37.147874Z","steps":["trace[102719998] 'agreement among raft nodes before linearized reading' (duration: 137.693908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:37.147739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.627777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-15T19:48:37.147939Z","caller":"traceutil/trace.go:171","msg":"trace[2055552736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24698; }","duration":"143.876822ms","start":"2026-10-15T19:48:37.004051Z","end":"2026-10-15T19:48:37.147928Z","steps":["trace[2055552736] 'agreement among raft nodes before linearized reading' (duration: 143.525294ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:49:40.052407Z","caller":"traceutil/trace.go:171","msg":"trace[520914972] linearizableReadLoop","detail":"{readStateIndex:26081; appliedIndex:26080; }","duration":"496.191166ms","start":"2026-10-15T19:49:39.556186Z","end":"2026-10-15T19:49:40.052378Z","steps":["trace[520914972] 'read index received' (duration: 80.679654ms)","trace[520914972] 'applied index is now lower than readState.Index' (duration: 415.509942ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:49:40.052504Z","caller":"traceutil/trace.go:171","msg":"trace[750659433] transaction","detail":"{read_only:false; response_revision:25263; number_of_response:1; }","duration":"507.007948ms","start":"2026-10-15T19:49:39.545467Z","end":"2026-10-15T19:49:40.052475Z","steps":["trace[750659433] 'process raft request' (duration: 439.065999ms)","trace[750659433] 'compare' (duration: 66.940025ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:49:40.052627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.545448Z","time spent":"507.119471ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:49:40.052639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.432373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-15T19:49:40.052735Z","caller":"traceutil/trace.go:171","msg":"trace[390428808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25263; }","duration":"496.559595ms","start":"2026-10-15T19:49:39.556152Z","end":"2026-10-15T19:49:40.052712Z","steps":["trace[390428808] 'agreement among raft nodes before linearized reading' (duration: 496.32472ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:40.052793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.55614Z","time spent":"496.640497ms","remote":"127.0.0.1:48938","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-15T19:49:40.553388Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1870859995498854660,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-15T19:49:40.842039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.263414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T19:49:40.842487Z","caller":"traceutil/trace.go:171","msg":"trace[2091605839] transaction","detail":"{read_only:false; response_revision:25264; number_of_response:1; }","duration":"1.027479099s","start":"2026-10-15T19:49:39.814929Z","end":"2026-10-15T19:49:40.842408Z","steps":["trace[2091605839] 'process raft request' (duration: 810.699643ms)","trace[2091605839] 'compare' (duration: 215.556388ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:49:40.842594Z","caller":"traceutil/trace.go:171","msg":"trace[1023969640] transaction","detail":"{read_only:false; response_revision:25265; number_of_response:1; }","duration":"1.026026605s","start":"2026-10-15T19:49:39.816504Z","end":"2026-10-15T19:49:40.84253Z","steps":["trace[1023969640] 'process raft request' (duration: 1.02581873s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:40.84282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.814908Z","time spent":"1.027719324s","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:49:40.842484Z","caller":"traceutil/trace.go:171","msg":"trace[955451266] transaction","detail":"{read_only:false; response_revision:25266; number_of_response:1; }","duration":"1.012118451s","start":"2026-10-15T19:49:39.830344Z","end":"2026-10-15T19:49:40.842462Z","steps":["trace[955451266] 'process raft request' (duration: 1.01207342s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:40.842849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.816474Z","time spent":"1.026258351s","remote":"127.0.0.1:48938","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-15T19:49:40.84295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.830313Z","time spent":"1.012583102s","remote":"127.0.0.1:48938","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-15T19:49:41.143814Z","caller":"traceutil/trace.go:171","msg":"trace[702570027] transaction","detail":"{read_only:false; response_revision:25268; number_of_response:1; }","duration":"584.047068ms","start":"2026-10-15T19:49:40.559736Z","end":"2026-10-15T19:49:41.143784Z","steps":["trace[702570027] 'process raft request' (duration: 583.840163ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:49:41.143863Z","caller":"traceutil/trace.go:171","msg":"trace[1102243947] transaction","detail":"{read_only:false; response_revision:25269; number_of_response:1; }","duration":"546.826953ms","start":"2026-10-15T19:49:40.597003Z","end":"2026-10-15T19:49:41.143829Z","steps":["trace[1102243947] 'process raft request' (duration: 546.636869ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:49:41.143858Z","caller":"traceutil/trace.go:171","msg":"trace[1309243665] transaction","detail":"{read_only:false; response_revision:25267; number_of_response:1; }","duration":"1.085403914s","start":"2026-10-15T19:49:40.058387Z","end":"2026-10-15T19:49:41.143791Z","steps":["trace[1309243665] 'process raft request' (duration: 1.046664054s)","trace[1309243665] 'compare' (duration: 38.349321ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:49:41.143952Z","caller":"traceutil/trace.go:171","msg":"trace[1483539434] transaction","detail":"{read_only:false; response_revision:25270; number_of_response:1; }","duration":"540.626909ms","start":"2026-10-15T19:49:40.603292Z","end":"2026-10-15T19:49:41.143919Z","steps":["trace[1483539434] 'process raft request' (duration: 540.400054ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:41.144007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:40.559714Z","time spent":"584.17826ms","remote":"127.0.0.1:48938","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-15T19:49:41.144147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:40.603259Z","time spent":"540.787303ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:49:41.144139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:40.058364Z","time spent":"1.08563567s","remote":"127.0.0.1:48938","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-15T19:49:41.144174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:40.596954Z","time spent":"546.988947ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:49:41.14471Z","caller":"traceutil/trace.go:171","msg":"trace[853792903] linearizableReadLoop","detail":"{readStateIndex:26085; appliedIndex:26081; }","duration":"1.092190742s","start":"2026-10-15T19:49:40.052489Z","end":"2026-10-15T19:49:41.14468Z","steps":["trace[853792903] 'read index received' (duration: 573.287457ms)","trace[853792903] 'applied index is now lower than readState.Index' (duration: 518.901465ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:49:41.144802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.472348953s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:49:41.144744Z","caller":"traceutil/trace.go:171","msg":"trace[1785812933] transaction","detail":"{read_only:false; response_revision:25271; number_of_response:1; }","duration":"534.788293ms","start":"2026-10-15T19:49:40.609897Z","end":"2026-10-15T19:49:41.144685Z","steps":["trace[1785812933] 'process raft request' (duration: 533.840861ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:49:41.144862Z","caller":"traceutil/trace.go:171","msg":"trace[1693272268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25271; }","duration":"1.472437675s","start":"2026-10-15T19:49:39.672406Z","end":"2026-10-15T19:49:41.144844Z","steps":["trace[1693272268] 'agreement among raft nodes before linearized reading' (duration: 1.472304201s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:41.144887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.575799646s","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":"warn","ts":"2026-10-15T19:49:41.144884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190334711s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:49:41.144937Z","caller":"traceutil/trace.go:171","msg":"trace[493354311] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25271; }","duration":"1.575854657s","start":"2026-10-15T19:49:39.569069Z","end":"2026-10-15T19:49:41.144924Z","steps":["trace[493354311] 'agreement among raft nodes before linearized reading' (duration: 1.575673353s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:41.144927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190335762s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:49:41.144984Z","caller":"traceutil/trace.go:171","msg":"trace[1187157003] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:25271; }","duration":"1.190442124s","start":"2026-10-15T19:49:39.954498Z","end":"2026-10-15T19:49:41.144941Z","steps":["trace[1187157003] 'agreement among raft nodes before linearized reading' (duration: 1.190313101s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:41.144992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.56905Z","time spent":"1.575933759s","remote":"127.0.0.1:48842","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-barbican-server-0\" "} {"level":"warn","ts":"2026-10-15T19:49:41.145036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.95448Z","time spent":"1.190540587s","remote":"127.0.0.1:46212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"info","ts":"2026-10-15T19:49:41.145031Z","caller":"traceutil/trace.go:171","msg":"trace[1945683475] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:25271; }","duration":"1.190462514s","start":"2026-10-15T19:49:39.954542Z","end":"2026-10-15T19:49:41.145004Z","steps":["trace[1945683475] 'agreement among raft nodes before linearized reading' (duration: 1.19029889s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:41.145043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:40.609876Z","time spent":"535.004228ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:49:41.145037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.555820472s","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":"warn","ts":"2026-10-15T19:49:41.14508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.576970673s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-15T19:49:41.145075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267168387s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.242\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-10-15T19:49:41.145089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.95448Z","time spent":"1.190596848s","remote":"127.0.0.1:35918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-10-15T19:49:41.145107Z","caller":"traceutil/trace.go:171","msg":"trace[2105290180] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25271; }","duration":"1.555951755s","start":"2026-10-15T19:49:39.58914Z","end":"2026-10-15T19:49:41.145092Z","steps":["trace[2105290180] 'agreement among raft nodes before linearized reading' (duration: 1.55573018s)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:49:41.14512Z","caller":"traceutil/trace.go:171","msg":"trace[1541462069] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25271; }","duration":"1.577019865s","start":"2026-10-15T19:49:39.568087Z","end":"2026-10-15T19:49:41.145107Z","steps":["trace[1541462069] 'agreement among raft nodes before linearized reading' (duration: 1.576934113s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:41.145139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.589115Z","time spent":"1.556018826s","remote":"127.0.0.1:48938","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":"info","ts":"2026-10-15T19:49:41.14513Z","caller":"traceutil/trace.go:171","msg":"trace[1113791941] range","detail":"{range_begin:/registry/masterleases/162.253.55.242; range_end:; response_count:1; response_revision:25271; }","duration":"1.267257919s","start":"2026-10-15T19:49:39.877859Z","end":"2026-10-15T19:49:41.145117Z","steps":["trace[1113791941] 'agreement among raft nodes before linearized reading' (duration: 1.267073565s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:41.144904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.67239Z","time spent":"1.472503507s","remote":"127.0.0.1:48710","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-15T19:49:41.145097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.583211678s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-15T19:49:41.145154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.568079Z","time spent":"1.577068436s","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-15T19:49:41.144988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.5785595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-15T19:49:41.145171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.877841Z","time spent":"1.26731925s","remote":"127.0.0.1:48740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/162.253.55.242\" "} {"level":"info","ts":"2026-10-15T19:49:41.145193Z","caller":"traceutil/trace.go:171","msg":"trace[2082608237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25271; }","duration":"1.578809356s","start":"2026-10-15T19:49:39.566373Z","end":"2026-10-15T19:49:41.145182Z","steps":["trace[2082608237] 'agreement among raft nodes before linearized reading' (duration: 1.578469969s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:41.145225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.566363Z","time spent":"1.578852137s","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-15T19:49:41.145198Z","caller":"traceutil/trace.go:171","msg":"trace[1690437992] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25271; }","duration":"1.583337631s","start":"2026-10-15T19:49:39.561839Z","end":"2026-10-15T19:49:41.145177Z","steps":["trace[1690437992] 'agreement among raft nodes before linearized reading' (duration: 1.583041564s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:41.145296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.561821Z","time spent":"1.583458084s","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-15T19:49:41.343071Z","caller":"traceutil/trace.go:171","msg":"trace[1688482350] linearizableReadLoop","detail":"{readStateIndex:26090; appliedIndex:26089; }","duration":"198.232675ms","start":"2026-10-15T19:49:41.144807Z","end":"2026-10-15T19:49:41.34304Z","steps":["trace[1688482350] 'read index received' (duration: 153.186608ms)","trace[1688482350] 'applied index is now lower than readState.Index' (duration: 45.044947ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:49:41.34338Z","caller":"traceutil/trace.go:171","msg":"trace[575132582] transaction","detail":"{read_only:false; response_revision:25272; number_of_response:1; }","duration":"581.14845ms","start":"2026-10-15T19:49:40.76221Z","end":"2026-10-15T19:49:41.343359Z","steps":["trace[575132582] 'process raft request' (duration: 535.800877ms)","trace[575132582] 'compare' (duration: 44.869712ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:49:41.343524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:40.762181Z","time spent":"581.248833ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:49:41.343622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.704996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:49:41.343716Z","caller":"traceutil/trace.go:171","msg":"trace[954755295] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:25272; }","duration":"774.811889ms","start":"2026-10-15T19:49:40.568876Z","end":"2026-10-15T19:49:41.343688Z","steps":["trace[954755295] 'agreement among raft nodes before linearized reading' (duration: 774.649565ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:41.34377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:40.56879Z","time spent":"774.964642ms","remote":"127.0.0.1:41234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"warn","ts":"2026-10-15T19:49:41.343778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.536687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-15T19:49:41.343907Z","caller":"traceutil/trace.go:171","msg":"trace[113302694] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25272; }","duration":"834.70898ms","start":"2026-10-15T19:49:40.509165Z","end":"2026-10-15T19:49:41.343874Z","steps":["trace[113302694] 'agreement among raft nodes before linearized reading' (duration: 834.344442ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:41.34401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:40.509147Z","time spent":"834.842884ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-15T19:49:41.374389Z","caller":"traceutil/trace.go:171","msg":"trace[1958023069] transaction","detail":"{read_only:false; response_revision:25273; number_of_response:1; }","duration":"223.284577ms","start":"2026-10-15T19:49:41.151076Z","end":"2026-10-15T19:49:41.374361Z","steps":["trace[1958023069] 'process raft request' (duration: 223.101973ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:49:41.374737Z","caller":"traceutil/trace.go:171","msg":"trace[1607118432] transaction","detail":"{read_only:false; response_revision:25274; number_of_response:1; }","duration":"223.599004ms","start":"2026-10-15T19:49:41.151107Z","end":"2026-10-15T19:49:41.374706Z","steps":["trace[1607118432] 'process raft request' (duration: 223.208755ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:49:41.374946Z","caller":"traceutil/trace.go:171","msg":"trace[447154867] transaction","detail":"{read_only:false; response_revision:25275; number_of_response:1; }","duration":"223.720517ms","start":"2026-10-15T19:49:41.151191Z","end":"2026-10-15T19:49:41.374912Z","steps":["trace[447154867] 'process raft request' (duration: 223.42265ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:49:41.375042Z","caller":"traceutil/trace.go:171","msg":"trace[19737228] transaction","detail":"{read_only:false; response_revision:25276; number_of_response:1; }","duration":"222.629921ms","start":"2026-10-15T19:49:41.152374Z","end":"2026-10-15T19:49:41.375004Z","steps":["trace[19737228] 'process raft request' (duration: 222.441347ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:49:41.375141Z","caller":"traceutil/trace.go:171","msg":"trace[1843676882] transaction","detail":"{read_only:false; response_revision:25277; number_of_response:1; }","duration":"147.459726ms","start":"2026-10-15T19:49:41.227666Z","end":"2026-10-15T19:49:41.375125Z","steps":["trace[1843676882] 'process raft request' (duration: 147.242081ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:41.375311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.871342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:49:41.375361Z","caller":"traceutil/trace.go:171","msg":"trace[1609912662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25278; }","duration":"211.965984ms","start":"2026-10-15T19:49:41.163384Z","end":"2026-10-15T19:49:41.37535Z","steps":["trace[1609912662] 'agreement among raft nodes before linearized reading' (duration: 211.874772ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:41.375453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.580617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-eed48603-ff2f-4c70-b343-05121b6d453d\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-10-15T19:49:41.3755Z","caller":"traceutil/trace.go:171","msg":"trace[1148420828] range","detail":"{range_begin:/registry/persistentvolumes/pvc-eed48603-ff2f-4c70-b343-05121b6d453d; range_end:; response_count:1; response_revision:25278; }","duration":"224.641409ms","start":"2026-10-15T19:49:41.150845Z","end":"2026-10-15T19:49:41.375487Z","steps":["trace[1148420828] 'agreement among raft nodes before linearized reading' (duration: 224.482885ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:49:41.673489Z","caller":"traceutil/trace.go:171","msg":"trace[143720631] linearizableReadLoop","detail":"{readStateIndex:26098; appliedIndex:26097; }","duration":"296.319883ms","start":"2026-10-15T19:49:41.377144Z","end":"2026-10-15T19:49:41.673464Z","steps":["trace[143720631] 'read index received' (duration: 258.375762ms)","trace[143720631] 'applied index is now lower than readState.Index' (duration: 37.943171ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:49:41.673607Z","caller":"traceutil/trace.go:171","msg":"trace[245031116] transaction","detail":"{read_only:false; response_revision:25279; number_of_response:1; }","duration":"297.020089ms","start":"2026-10-15T19:49:41.376575Z","end":"2026-10-15T19:49:41.673595Z","steps":["trace[245031116] 'process raft request' (duration: 258.911244ms)","trace[245031116] 'compare' (duration: 37.86493ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:49:41.673904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.737943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T19:49:41.67392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.656241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19232"} {"level":"info","ts":"2026-10-15T19:49:41.673983Z","caller":"traceutil/trace.go:171","msg":"trace[1568573349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25279; }","duration":"296.835566ms","start":"2026-10-15T19:49:41.377111Z","end":"2026-10-15T19:49:41.673947Z","steps":["trace[1568573349] 'agreement among raft nodes before linearized reading' (duration: 296.679052ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:49:41.673989Z","caller":"traceutil/trace.go:171","msg":"trace[1435268881] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25279; }","duration":"296.753703ms","start":"2026-10-15T19:49:41.377223Z","end":"2026-10-15T19:49:41.673976Z","steps":["trace[1435268881] 'agreement among raft nodes before linearized reading' (duration: 296.540298ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:50:12.560935Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006713.wal"} {"level":"info","ts":"2026-10-15T19:50:46.277462Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23030} {"level":"info","ts":"2026-10-15T19:50:46.443574Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23030,"took":"160.004917ms","hash":1301208429,"current-db-size-bytes":57577472,"current-db-size":"58 MB","current-db-size-in-use-bytes":36368384,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-15T19:50:46.443643Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1301208429,"revision":23030,"compact-revision":20098} {"level":"info","ts":"2026-10-15T19:55:46.286238Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25953} {"level":"info","ts":"2026-10-15T19:55:46.462903Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25953,"took":"170.582706ms","hash":3104827844,"current-db-size-bytes":57577472,"current-db-size":"58 MB","current-db-size-in-use-bytes":34848768,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-15T19:55:46.462992Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3104827844,"revision":25953,"compact-revision":23030} {"level":"info","ts":"2026-10-15T19:56:46.570678Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cc70bec9404419f6","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T19:56:46.579117Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-15T19:56:46.579301Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-15T20:00:46.293878Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28642} {"level":"info","ts":"2026-10-15T20:00:46.473448Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28642,"took":"167.093735ms","hash":991775222,"current-db-size-bytes":57577472,"current-db-size":"58 MB","current-db-size-in-use-bytes":31420416,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T20:00:46.473573Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":991775222,"revision":28642,"compact-revision":25953} {"level":"info","ts":"2026-10-15T20:05:46.301251Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30931} {"level":"info","ts":"2026-10-15T20:05:46.438383Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30931,"took":"132.478729ms","hash":1323258543,"current-db-size-bytes":57577472,"current-db-size":"58 MB","current-db-size-in-use-bytes":31395840,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T20:05:46.438464Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1323258543,"revision":30931,"compact-revision":28642} {"level":"info","ts":"2026-10-15T20:10:46.309158Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33225} {"level":"info","ts":"2026-10-15T20:10:46.480131Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33225,"took":"166.141451ms","hash":441681836,"current-db-size-bytes":59052032,"current-db-size":"59 MB","current-db-size-in-use-bytes":32071680,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T20:10:46.480195Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":441681836,"revision":33225,"compact-revision":30931} {"level":"info","ts":"2026-10-15T20:15:46.319616Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35526} {"level":"info","ts":"2026-10-15T20:15:46.463905Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35526,"took":"139.604394ms","hash":4228857213,"current-db-size-bytes":59052032,"current-db-size":"59 MB","current-db-size-in-use-bytes":31285248,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T20:15:46.463971Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4228857213,"revision":35526,"compact-revision":33225} {"level":"info","ts":"2026-10-15T20:18:05.278537Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cc70bec9404419f6","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T20:18:05.287362Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-15T20:18:05.287507Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-15T20:20:46.328217Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37750} {"level":"info","ts":"2026-10-15T20:20:46.461787Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37750,"took":"129.116383ms","hash":1162165397,"current-db-size-bytes":59895808,"current-db-size":"60 MB","current-db-size-in-use-bytes":32763904,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T20:20:46.461862Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1162165397,"revision":37750,"compact-revision":35526}