{"level":"warn","ts":"2026-05-05T16:57:22.246588Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T16:57:22.246697Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-05T16:57:22.24679Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T16:57:22.24681Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-05-05T16:57:22.246834Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T16:57:22.248003Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-05-05T16:57:22.248126Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-05T16:57:22.252846Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.383492ms"} {"level":"info","ts":"2026-05-05T16:57:22.261036Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-05-05T16:57:22.261182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T16:57:22.261223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-05-05T16:57:22.26125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T16:57:22.261278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-05-05T16:57:22.26135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-05-05T16:57:22.268448Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T16:57:22.269974Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T16:57:22.271413Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-05T16:57:22.27382Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T16:57:22.274048Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T16:57:22.274264Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T16:57:22.274364Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T16:57:22.274379Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T16:57:22.276797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-05-05T16:57:22.277026Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-05-05T16:57:22.279169Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T16:57:22.279343Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-05-05T16:57:22.279426Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-05-05T16:57:22.279561Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T16:57:22.279615Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T16:57:23.261746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T16:57:23.261836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T16:57:23.261955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-05-05T16:57:23.26199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-05-05T16:57:23.262043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-05-05T16:57:23.262072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-05-05T16:57:23.262118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-05-05T16:57:23.263596Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T16:57:23.263638Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T16:57:23.263631Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T16:57:23.263814Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:57:23.264051Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T16:57:23.26413Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T16:57:23.26516Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:57:23.265304Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:57:23.265347Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:57:23.265405Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T16:57:23.267235Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"warn","ts":"2026-05-05T16:57:23.527339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.919813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:57:23.527419Z","caller":"traceutil/trace.go:171","msg":"trace[1714682361] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"149.987434ms","start":"2026-05-05T16:57:23.377409Z","end":"2026-05-05T16:57:23.527396Z","steps":["trace[1714682361] 'range keys from in-memory index tree' (duration: 149.869743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:23.527519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.741326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T16:57:23.527586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.951745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T16:57:23.527598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.743376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:57:23.527602Z","caller":"traceutil/trace.go:171","msg":"trace[297787844] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"116.878499ms","start":"2026-05-05T16:57:23.410704Z","end":"2026-05-05T16:57:23.527583Z","steps":["trace[297787844] 'count revisions from in-memory index tree' (duration: 116.667804ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:23.527619Z","caller":"traceutil/trace.go:171","msg":"trace[318525569] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"139.998556ms","start":"2026-05-05T16:57:23.387609Z","end":"2026-05-05T16:57:23.527608Z","steps":["trace[318525569] 'range keys from in-memory index tree' (duration: 139.907714ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:23.527634Z","caller":"traceutil/trace.go:171","msg":"trace[1455088878] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"116.821598ms","start":"2026-05-05T16:57:23.410799Z","end":"2026-05-05T16:57:23.527621Z","steps":["trace[1455088878] 'range keys from in-memory index tree' (duration: 116.634544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:23.527539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.789791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:57:23.52772Z","caller":"traceutil/trace.go:171","msg":"trace[1850084382] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"139.997246ms","start":"2026-05-05T16:57:23.387712Z","end":"2026-05-05T16:57:23.527709Z","steps":["trace[1850084382] 'count revisions from in-memory index tree' (duration: 139.73885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:23.527705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.626753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:57:23.527797Z","caller":"traceutil/trace.go:171","msg":"trace[686966854] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"127.756705ms","start":"2026-05-05T16:57:23.400013Z","end":"2026-05-05T16:57:23.52777Z","steps":["trace[686966854] 'count revisions from in-memory index tree' (duration: 127.566222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:23.527254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.859222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T16:57:23.527867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.809946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:57:23.527923Z","caller":"traceutil/trace.go:171","msg":"trace[1209806215] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"150.573786ms","start":"2026-05-05T16:57:23.377335Z","end":"2026-05-05T16:57:23.527909Z","steps":["trace[1209806215] 'count revisions from in-memory index tree' (duration: 149.76902ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:23.527983Z","caller":"traceutil/trace.go:171","msg":"trace[1272123642] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"128.04642ms","start":"2026-05-05T16:57:23.399906Z","end":"2026-05-05T16:57:23.527952Z","steps":["trace[1272123642] 'range keys from in-memory index tree' (duration: 127.704314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:23.528026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.063943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:57:23.528081Z","caller":"traceutil/trace.go:171","msg":"trace[1520832432] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"108.138314ms","start":"2026-05-05T16:57:23.419928Z","end":"2026-05-05T16:57:23.528066Z","steps":["trace[1520832432] 'range keys from in-memory index tree' (duration: 107.969391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:23.528478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.852529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:57:23.528546Z","caller":"traceutil/trace.go:171","msg":"trace[568457438] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"108.562213ms","start":"2026-05-05T16:57:23.419946Z","end":"2026-05-05T16:57:23.528509Z","steps":["trace[568457438] 'count revisions from in-memory index tree' (duration: 107.793107ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:54.448972Z","caller":"traceutil/trace.go:171","msg":"trace[45442659] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"106.017063ms","start":"2026-05-05T16:57:54.342927Z","end":"2026-05-05T16:57:54.448944Z","steps":["trace[45442659] 'process raft request' (duration: 78.129697ms)","trace[45442659] 'compare' (duration: 27.614101ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:57:54.449196Z","caller":"traceutil/trace.go:171","msg":"trace[1509910200] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"101.102892ms","start":"2026-05-05T16:57:54.348041Z","end":"2026-05-05T16:57:54.449144Z","steps":["trace[1509910200] 'process raft request' (duration: 100.816505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:00.851535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.782255ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:58:00.851707Z","caller":"traceutil/trace.go:171","msg":"trace[1581864441] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:621; }","duration":"398.979778ms","start":"2026-05-05T16:58:00.452705Z","end":"2026-05-05T16:58:00.851685Z","steps":["trace[1581864441] 'range keys from in-memory index tree' (duration: 398.761194ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:00.851971Z","caller":"traceutil/trace.go:171","msg":"trace[644212437] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"429.680216ms","start":"2026-05-05T16:58:00.422276Z","end":"2026-05-05T16:58:00.851957Z","steps":["trace[644212437] 'process raft request' (duration: 416.484677ms)","trace[644212437] 'compare' (duration: 12.917044ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:58:00.851987Z","caller":"traceutil/trace.go:171","msg":"trace[918194000] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"404.093494ms","start":"2026-05-05T16:58:00.447867Z","end":"2026-05-05T16:58:00.85196Z","steps":["trace[918194000] 'process raft request' (duration: 403.953772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:00.852675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:00.447838Z","time spent":"404.213077ms","remote":"127.0.0.1:51218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T16:58:00.852952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:00.422249Z","time spent":"430.007423ms","remote":"127.0.0.1:50840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:59:30.72858Z","caller":"traceutil/trace.go:171","msg":"trace[470033789] linearizableReadLoop","detail":"{readStateIndex:1908; appliedIndex:1907; }","duration":"190.925732ms","start":"2026-05-05T16:59:30.537623Z","end":"2026-05-05T16:59:30.728549Z","steps":["trace[470033789] 'read index received' (duration: 190.703798ms)","trace[470033789] 'applied index is now lower than readState.Index' (duration: 220.834µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:59:30.72868Z","caller":"traceutil/trace.go:171","msg":"trace[638100179] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"231.659902ms","start":"2026-05-05T16:59:30.496999Z","end":"2026-05-05T16:59:30.728658Z","steps":["trace[638100179] 'process raft request' (duration: 231.355916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:59:30.728872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.235757ms","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-05-05T16:59:30.728918Z","caller":"traceutil/trace.go:171","msg":"trace[1072088265] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1842; }","duration":"191.351309ms","start":"2026-05-05T16:59:30.537555Z","end":"2026-05-05T16:59:30.728906Z","steps":["trace[1072088265] 'agreement among raft nodes before linearized reading' (duration: 191.162326ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:59:30.746419Z","caller":"traceutil/trace.go:171","msg":"trace[1470885709] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"158.986228ms","start":"2026-05-05T16:59:30.587396Z","end":"2026-05-05T16:59:30.746382Z","steps":["trace[1470885709] 'process raft request' (duration: 158.666732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:59:30.746469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.06257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-05T16:59:30.746509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.264648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-54efd576-b81b-4792-be30-a037145b7fef\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-05T16:59:30.74655Z","caller":"traceutil/trace.go:171","msg":"trace[329998332] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1843; }","duration":"186.177513ms","start":"2026-05-05T16:59:30.560354Z","end":"2026-05-05T16:59:30.746532Z","steps":["trace[329998332] 'agreement among raft nodes before linearized reading' (duration: 185.967319ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:59:30.746561Z","caller":"traceutil/trace.go:171","msg":"trace[2020118257] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-54efd576-b81b-4792-be30-a037145b7fef; range_end:; response_count:1; response_revision:1843; }","duration":"155.36782ms","start":"2026-05-05T16:59:30.591179Z","end":"2026-05-05T16:59:30.746547Z","steps":["trace[2020118257] 'agreement among raft nodes before linearized reading' (duration: 155.216497ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:00:16.177858Z","caller":"traceutil/trace.go:171","msg":"trace[456357720] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"109.269189ms","start":"2026-05-05T17:00:16.068554Z","end":"2026-05-05T17:00:16.177823Z","steps":["trace[456357720] 'process raft request' (duration: 108.989424ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:01:10.993106Z","caller":"traceutil/trace.go:171","msg":"trace[1765405128] linearizableReadLoop","detail":"{readStateIndex:2710; appliedIndex:2709; }","duration":"167.975123ms","start":"2026-05-05T17:01:10.825087Z","end":"2026-05-05T17:01:10.993062Z","steps":["trace[1765405128] 'read index received' (duration: 167.398022ms)","trace[1765405128] 'applied index is now lower than readState.Index' (duration: 575.201µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:01:10.993248Z","caller":"traceutil/trace.go:171","msg":"trace[1836058388] transaction","detail":"{read_only:false; response_revision:2616; number_of_response:1; }","duration":"169.32548ms","start":"2026-05-05T17:01:10.823866Z","end":"2026-05-05T17:01:10.993191Z","steps":["trace[1836058388] 'process raft request' (duration: 168.972003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:01:10.993483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.367741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-05T17:01:10.993609Z","caller":"traceutil/trace.go:171","msg":"trace[1097969679] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2616; }","duration":"168.556765ms","start":"2026-05-05T17:01:10.825024Z","end":"2026-05-05T17:01:10.993581Z","steps":["trace[1097969679] 'agreement among raft nodes before linearized reading' (duration: 168.210919ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:01:44.212684Z","caller":"traceutil/trace.go:171","msg":"trace[2031227635] transaction","detail":"{read_only:false; response_revision:2815; number_of_response:1; }","duration":"150.751258ms","start":"2026-05-05T17:01:44.061905Z","end":"2026-05-05T17:01:44.212656Z","steps":["trace[2031227635] 'process raft request' (duration: 150.457283ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:01:44.412731Z","caller":"traceutil/trace.go:171","msg":"trace[138903339] linearizableReadLoop","detail":"{readStateIndex:2919; appliedIndex:2918; }","duration":"109.139651ms","start":"2026-05-05T17:01:44.30355Z","end":"2026-05-05T17:01:44.41269Z","steps":["trace[138903339] 'read index received' (duration: 99.452958ms)","trace[138903339] 'applied index is now lower than readState.Index' (duration: 9.684903ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:01:44.413035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.454769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T17:01:44.413085Z","caller":"traceutil/trace.go:171","msg":"trace[749091432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2817; }","duration":"109.576561ms","start":"2026-05-05T17:01:44.303493Z","end":"2026-05-05T17:01:44.41307Z","steps":["trace[749091432] 'agreement among raft nodes before linearized reading' (duration: 109.343977ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:01:44.412924Z","caller":"traceutil/trace.go:171","msg":"trace[1724448061] transaction","detail":"{read_only:false; response_revision:2817; number_of_response:1; }","duration":"139.113599ms","start":"2026-05-05T17:01:44.273778Z","end":"2026-05-05T17:01:44.412892Z","steps":["trace[1724448061] 'process raft request' (duration: 129.435635ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:04:30.208109Z","caller":"traceutil/trace.go:171","msg":"trace[955676910] transaction","detail":"{read_only:false; response_revision:3891; number_of_response:1; }","duration":"140.884711ms","start":"2026-05-05T17:04:30.067193Z","end":"2026-05-05T17:04:30.208078Z","steps":["trace[955676910] 'process raft request' (duration: 106.821408ms)","trace[955676910] 'compare' (duration: 33.92795ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:05:57.437157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.742272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T17:05:57.437232Z","caller":"traceutil/trace.go:171","msg":"trace[1916124380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5086; }","duration":"123.858914ms","start":"2026-05-05T17:05:57.313356Z","end":"2026-05-05T17:05:57.437215Z","steps":["trace[1916124380] 'range keys from in-memory index tree' (duration: 123.66127ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:06:02.042864Z","caller":"traceutil/trace.go:171","msg":"trace[1498905836] linearizableReadLoop","detail":"{readStateIndex:5367; appliedIndex:5366; }","duration":"112.110361ms","start":"2026-05-05T17:06:01.930722Z","end":"2026-05-05T17:06:02.042833Z","steps":["trace[1498905836] 'read index received' (duration: 72.111795ms)","trace[1498905836] 'applied index is now lower than readState.Index' (duration: 39.996266ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:06:02.042893Z","caller":"traceutil/trace.go:171","msg":"trace[986574156] transaction","detail":"{read_only:false; response_revision:5178; number_of_response:1; }","duration":"108.387691ms","start":"2026-05-05T17:06:01.93448Z","end":"2026-05-05T17:06:02.042868Z","steps":["trace[986574156] 'process raft request' (duration: 108.255658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:06:02.043193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.454978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-05-05T17:06:02.04328Z","caller":"traceutil/trace.go:171","msg":"trace[737626747] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5178; }","duration":"112.592011ms","start":"2026-05-05T17:06:01.93067Z","end":"2026-05-05T17:06:02.043262Z","steps":["trace[737626747] 'agreement among raft nodes before linearized reading' (duration: 112.283716ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:06:19.333997Z","caller":"traceutil/trace.go:171","msg":"trace[1773574842] transaction","detail":"{read_only:false; response_revision:5482; number_of_response:1; }","duration":"202.148795ms","start":"2026-05-05T17:06:19.131822Z","end":"2026-05-05T17:06:19.33397Z","steps":["trace[1773574842] 'process raft request' (duration: 201.994812ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:07:23.292037Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3030} {"level":"info","ts":"2026-05-05T17:07:23.380636Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3030,"took":"86.757389ms","hash":2829331140,"current-db-size-bytes":57921536,"current-db-size":"58 MB","current-db-size-in-use-bytes":31252480,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-05T17:07:23.380822Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2829331140,"revision":3030,"compact-revision":-1} {"level":"info","ts":"2026-05-05T17:12:16.214189Z","caller":"traceutil/trace.go:171","msg":"trace[223519729] transaction","detail":"{read_only:false; response_revision:8289; number_of_response:1; }","duration":"128.47614ms","start":"2026-05-05T17:12:16.085687Z","end":"2026-05-05T17:12:16.214163Z","steps":["trace[223519729] 'process raft request' (duration: 128.282886ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:12:16.394221Z","caller":"traceutil/trace.go:171","msg":"trace[1942360505] transaction","detail":"{read_only:false; response_revision:8290; number_of_response:1; }","duration":"140.517427ms","start":"2026-05-05T17:12:16.253675Z","end":"2026-05-05T17:12:16.394192Z","steps":["trace[1942360505] 'process raft request' (duration: 83.628361ms)","trace[1942360505] 'compare' (duration: 56.194893ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:12:23.300489Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6108} {"level":"info","ts":"2026-05-05T17:12:23.408144Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6108,"took":"105.574676ms","hash":3866012815,"current-db-size-bytes":57921536,"current-db-size":"58 MB","current-db-size-in-use-bytes":23810048,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-05T17:12:23.408218Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3866012815,"revision":6108,"compact-revision":3030} {"level":"info","ts":"2026-05-05T17:12:27.901302Z","caller":"traceutil/trace.go:171","msg":"trace[750374290] transaction","detail":"{read_only:false; response_revision:8352; number_of_response:1; }","duration":"104.035857ms","start":"2026-05-05T17:12:27.797229Z","end":"2026-05-05T17:12:27.901264Z","steps":["trace[750374290] 'process raft request' (duration: 103.857574ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:12:27.934787Z","caller":"traceutil/trace.go:171","msg":"trace[1448460006] transaction","detail":"{read_only:false; response_revision:8353; number_of_response:1; }","duration":"134.019994ms","start":"2026-05-05T17:12:27.80073Z","end":"2026-05-05T17:12:27.93475Z","steps":["trace[1448460006] 'process raft request' (duration: 133.80296ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:12:28.486672Z","caller":"traceutil/trace.go:171","msg":"trace[923007861] linearizableReadLoop","detail":"{readStateIndex:8640; appliedIndex:8639; }","duration":"173.201175ms","start":"2026-05-05T17:12:28.313447Z","end":"2026-05-05T17:12:28.486648Z","steps":["trace[923007861] 'read index received' (duration: 172.95876ms)","trace[923007861] 'applied index is now lower than readState.Index' (duration: 241.325µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:12:28.486736Z","caller":"traceutil/trace.go:171","msg":"trace[639458806] transaction","detail":"{read_only:false; response_revision:8354; number_of_response:1; }","duration":"229.994559ms","start":"2026-05-05T17:12:28.256709Z","end":"2026-05-05T17:12:28.486703Z","steps":["trace[639458806] 'process raft request' (duration: 229.752894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:12:28.486859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.403559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T17:12:28.486918Z","caller":"traceutil/trace.go:171","msg":"trace[1460051466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8354; }","duration":"173.529081ms","start":"2026-05-05T17:12:28.31337Z","end":"2026-05-05T17:12:28.4869Z","steps":["trace[1460051466] 'agreement among raft nodes before linearized reading' (duration: 173.408489ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:12:30.596754Z","caller":"traceutil/trace.go:171","msg":"trace[1607015854] transaction","detail":"{read_only:false; response_revision:8365; number_of_response:1; }","duration":"102.06799ms","start":"2026-05-05T17:12:30.49466Z","end":"2026-05-05T17:12:30.596728Z","steps":["trace[1607015854] 'process raft request' (duration: 86.330223ms)","trace[1607015854] 'compare' (duration: 15.3844ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:12:57.802077Z","caller":"traceutil/trace.go:171","msg":"trace[1404521532] transaction","detail":"{read_only:false; response_revision:8565; number_of_response:1; }","duration":"374.356229ms","start":"2026-05-05T17:12:57.427695Z","end":"2026-05-05T17:12:57.802052Z","steps":["trace[1404521532] 'process raft request' (duration: 373.690896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:12:57.802237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:12:57.427665Z","time spent":"374.498232ms","remote":"127.0.0.1:37810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":414529,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:13:10.684439Z","caller":"traceutil/trace.go:171","msg":"trace[1552502091] transaction","detail":"{read_only:false; response_revision:8673; number_of_response:1; }","duration":"145.104413ms","start":"2026-05-05T17:13:10.539306Z","end":"2026-05-05T17:13:10.68441Z","steps":["trace[1552502091] 'process raft request' (duration: 90.238836ms)","trace[1552502091] 'compare' (duration: 54.741725ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:13:10.824385Z","caller":"traceutil/trace.go:171","msg":"trace[1640269827] linearizableReadLoop","detail":"{readStateIndex:8973; appliedIndex:8972; }","duration":"135.907629ms","start":"2026-05-05T17:13:10.688447Z","end":"2026-05-05T17:13:10.824355Z","steps":["trace[1640269827] 'read index received' (duration: 123.7619ms)","trace[1640269827] 'applied index is now lower than readState.Index' (duration: 12.144019ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:13:10.824656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.186574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-xdbgj\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-05-05T17:13:10.8247Z","caller":"traceutil/trace.go:171","msg":"trace[1000955699] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-xdbgj; range_end:; response_count:1; response_revision:8674; }","duration":"136.284147ms","start":"2026-05-05T17:13:10.688405Z","end":"2026-05-05T17:13:10.824689Z","steps":["trace[1000955699] 'agreement among raft nodes before linearized reading' (duration: 136.097803ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:13:10.824656Z","caller":"traceutil/trace.go:171","msg":"trace[1543204786] transaction","detail":"{read_only:false; response_revision:8674; number_of_response:1; }","duration":"143.371551ms","start":"2026-05-05T17:13:10.681106Z","end":"2026-05-05T17:13:10.824477Z","steps":["trace[1543204786] 'process raft request' (duration: 130.978707ms)","trace[1543204786] 'compare' (duration: 12.088348ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:13:11.460282Z","caller":"traceutil/trace.go:171","msg":"trace[261091061] transaction","detail":"{read_only:false; response_revision:8681; number_of_response:1; }","duration":"178.072537ms","start":"2026-05-05T17:13:11.282172Z","end":"2026-05-05T17:13:11.460244Z","steps":["trace[261091061] 'process raft request' (duration: 144.237587ms)","trace[261091061] 'compare' (duration: 33.677497ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:13:12.240881Z","caller":"traceutil/trace.go:171","msg":"trace[841439814] linearizableReadLoop","detail":"{readStateIndex:8988; appliedIndex:8987; }","duration":"103.12701ms","start":"2026-05-05T17:13:12.137725Z","end":"2026-05-05T17:13:12.240852Z","steps":["trace[841439814] 'read index received' (duration: 102.825015ms)","trace[841439814] 'applied index is now lower than readState.Index' (duration: 301.095µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:13:12.241014Z","caller":"traceutil/trace.go:171","msg":"trace[1154974305] transaction","detail":"{read_only:false; response_revision:8688; number_of_response:1; }","duration":"108.841479ms","start":"2026-05-05T17:13:12.132133Z","end":"2026-05-05T17:13:12.240974Z","steps":["trace[1154974305] 'process raft request' (duration: 108.515963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:13:12.241176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.430556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T17:13:12.24122Z","caller":"traceutil/trace.go:171","msg":"trace[1698543229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8688; }","duration":"103.538268ms","start":"2026-05-05T17:13:12.13767Z","end":"2026-05-05T17:13:12.241208Z","steps":["trace[1698543229] 'agreement among raft nodes before linearized reading' (duration: 103.328794ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:13:57.520285Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024bb.wal"} {"level":"info","ts":"2026-05-05T17:14:23.555412Z","caller":"traceutil/trace.go:171","msg":"trace[2133955208] transaction","detail":"{read_only:false; response_revision:9402; number_of_response:1; }","duration":"114.428702ms","start":"2026-05-05T17:14:23.440945Z","end":"2026-05-05T17:14:23.555374Z","steps":["trace[2133955208] 'process raft request' (duration: 114.097876ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:14:59.913483Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T17:14:59.921094Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T17:14:59.921201Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-05T17:17:23.307556Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8329} {"level":"info","ts":"2026-05-05T17:17:23.385433Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8329,"took":"75.503775ms","hash":1418054326,"current-db-size-bytes":57921536,"current-db-size":"58 MB","current-db-size-in-use-bytes":26316800,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-05T17:17:23.385511Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1418054326,"revision":8329,"compact-revision":6108} {"level":"info","ts":"2026-05-05T17:17:57.132043Z","caller":"traceutil/trace.go:171","msg":"trace[349817861] transaction","detail":"{read_only:false; response_revision:11198; number_of_response:1; }","duration":"112.989273ms","start":"2026-05-05T17:17:57.018998Z","end":"2026-05-05T17:17:57.131987Z","steps":["trace[349817861] 'process raft request' (duration: 112.650677ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:22:23.317003Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10816} {"level":"info","ts":"2026-05-05T17:22:23.392232Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10816,"took":"72.060276ms","hash":1499186299,"current-db-size-bytes":57921536,"current-db-size":"58 MB","current-db-size-in-use-bytes":27623424,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-05T17:22:23.392292Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1499186299,"revision":10816,"compact-revision":8329} {"level":"info","ts":"2026-05-05T17:23:50.44934Z","caller":"traceutil/trace.go:171","msg":"trace[406982182] linearizableReadLoop","detail":"{readStateIndex:14069; appliedIndex:14068; }","duration":"111.854389ms","start":"2026-05-05T17:23:50.337457Z","end":"2026-05-05T17:23:50.449311Z","steps":["trace[406982182] 'read index received' (duration: 44.9769ms)","trace[406982182] 'applied index is now lower than readState.Index' (duration: 66.875949ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:23:50.44942Z","caller":"traceutil/trace.go:171","msg":"trace[1181431267] transaction","detail":"{read_only:false; response_revision:13613; number_of_response:1; }","duration":"117.112977ms","start":"2026-05-05T17:23:50.332252Z","end":"2026-05-05T17:23:50.449365Z","steps":["trace[1181431267] 'process raft request' (duration: 115.588148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:23:50.449588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.102173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T17:23:50.449675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.354462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1857"} {"level":"info","ts":"2026-05-05T17:23:50.44973Z","caller":"traceutil/trace.go:171","msg":"trace[783094949] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:13613; }","duration":"102.434173ms","start":"2026-05-05T17:23:50.34728Z","end":"2026-05-05T17:23:50.449714Z","steps":["trace[783094949] 'agreement among raft nodes before linearized reading' (duration: 102.211119ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:23:50.449728Z","caller":"traceutil/trace.go:171","msg":"trace[2055756183] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:13613; }","duration":"112.301237ms","start":"2026-05-05T17:23:50.337406Z","end":"2026-05-05T17:23:50.449707Z","steps":["trace[2055756183] 'agreement among raft nodes before linearized reading' (duration: 112.043583ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:24:27.816779Z","caller":"traceutil/trace.go:171","msg":"trace[1160574788] transaction","detail":"{read_only:false; response_revision:13825; number_of_response:1; }","duration":"270.061544ms","start":"2026-05-05T17:24:27.546685Z","end":"2026-05-05T17:24:27.816747Z","steps":["trace[1160574788] 'process raft request' (duration: 269.716678ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:24:28.171608Z","caller":"traceutil/trace.go:171","msg":"trace[255178508] transaction","detail":"{read_only:false; response_revision:13826; number_of_response:1; }","duration":"341.983706ms","start":"2026-05-05T17:24:27.82959Z","end":"2026-05-05T17:24:28.171574Z","steps":["trace[255178508] 'process raft request' (duration: 248.930889ms)","trace[255178508] 'compare' (duration: 92.785043ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:24:28.171775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:24:27.829569Z","time spent":"342.11886ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:24:28.200323Z","caller":"traceutil/trace.go:171","msg":"trace[974462930] transaction","detail":"{read_only:false; response_revision:13827; number_of_response:1; }","duration":"369.674644ms","start":"2026-05-05T17:24:27.830628Z","end":"2026-05-05T17:24:28.200303Z","steps":["trace[974462930] 'process raft request' (duration: 369.507801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:24:28.200575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:24:27.830617Z","time spent":"369.759535ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:24:28.200736Z","caller":"traceutil/trace.go:171","msg":"trace[1325255223] transaction","detail":"{read_only:false; response_revision:13829; number_of_response:1; }","duration":"182.437197ms","start":"2026-05-05T17:24:28.018269Z","end":"2026-05-05T17:24:28.200707Z","steps":["trace[1325255223] 'process raft request' (duration: 182.283085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:24:28.200869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.024677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T17:24:28.200809Z","caller":"traceutil/trace.go:171","msg":"trace[1828926838] transaction","detail":"{read_only:false; response_revision:13828; number_of_response:1; }","duration":"182.505538ms","start":"2026-05-05T17:24:28.018261Z","end":"2026-05-05T17:24:28.200766Z","steps":["trace[1828926838] 'process raft request' (duration: 181.989869ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:24:28.200931Z","caller":"traceutil/trace.go:171","msg":"trace[1489106426] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:13829; }","duration":"199.17ms","start":"2026-05-05T17:24:28.001745Z","end":"2026-05-05T17:24:28.200915Z","steps":["trace[1489106426] 'agreement among raft nodes before linearized reading' (duration: 199.052478ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:24:28.200737Z","caller":"traceutil/trace.go:171","msg":"trace[2108313224] linearizableReadLoop","detail":"{readStateIndex:14293; appliedIndex:14291; }","duration":"198.868114ms","start":"2026-05-05T17:24:28.001826Z","end":"2026-05-05T17:24:28.200694Z","steps":["trace[2108313224] 'read index received' (duration: 76.679322ms)","trace[2108313224] 'applied index is now lower than readState.Index' (duration: 122.187402ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:26:11.801415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.074722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-05-05T17:26:11.801516Z","caller":"traceutil/trace.go:171","msg":"trace[1988868183] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15047; }","duration":"105.325927ms","start":"2026-05-05T17:26:11.696171Z","end":"2026-05-05T17:26:11.801497Z","steps":["trace[1988868183] 'range keys from in-memory index tree' (duration: 104.818337ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:27:23.324748Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12998} {"level":"info","ts":"2026-05-05T17:27:23.406004Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12998,"took":"78.345623ms","hash":1297655025,"current-db-size-bytes":57921536,"current-db-size":"58 MB","current-db-size-in-use-bytes":31068160,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-05T17:27:23.406061Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1297655025,"revision":12998,"compact-revision":10816} {"level":"info","ts":"2026-05-05T17:31:48.627258Z","caller":"traceutil/trace.go:171","msg":"trace[194146566] transaction","detail":"{read_only:false; response_revision:17625; number_of_response:1; }","duration":"105.722547ms","start":"2026-05-05T17:31:48.521504Z","end":"2026-05-05T17:31:48.627227Z","steps":["trace[194146566] 'process raft request' (duration: 105.477141ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:31:53.941437Z","caller":"traceutil/trace.go:171","msg":"trace[1025186084] transaction","detail":"{read_only:false; response_revision:17661; number_of_response:1; }","duration":"155.420545ms","start":"2026-05-05T17:31:53.785982Z","end":"2026-05-05T17:31:53.941403Z","steps":["trace[1025186084] 'process raft request' (duration: 155.20019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:31:54.521607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.706546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T17:31:54.521749Z","caller":"traceutil/trace.go:171","msg":"trace[800471381] linearizableReadLoop","detail":"{readStateIndex:18253; appliedIndex:18251; }","duration":"492.087892ms","start":"2026-05-05T17:31:54.029649Z","end":"2026-05-05T17:31:54.521737Z","steps":["trace[800471381] 'read index received' (duration: 91.188112ms)","trace[800471381] 'applied index is now lower than readState.Index' (duration: 400.8986ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:31:54.521801Z","caller":"traceutil/trace.go:171","msg":"trace[1890657368] transaction","detail":"{read_only:false; response_revision:17663; number_of_response:1; }","duration":"560.957743ms","start":"2026-05-05T17:31:53.96079Z","end":"2026-05-05T17:31:54.521748Z","steps":["trace[1890657368] 'process raft request' (duration: 159.943271ms)","trace[1890657368] 'compare' (duration: 400.612814ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:31:54.521986Z","caller":"traceutil/trace.go:171","msg":"trace[747473435] transaction","detail":"{read_only:false; response_revision:17664; number_of_response:1; }","duration":"514.952284ms","start":"2026-05-05T17:31:54.007026Z","end":"2026-05-05T17:31:54.521978Z","steps":["trace[747473435] 'process raft request' (duration: 514.671929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:31:54.52203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:31:54.007001Z","time spent":"515.010085ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:31:54.522033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:31:53.960773Z","time spent":"561.139036ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:31:54.52187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.229285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-mrcfj\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-05-05T17:31:54.522195Z","caller":"traceutil/trace.go:171","msg":"trace[147270322] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-mrcfj; range_end:; response_count:1; response_revision:17664; }","duration":"492.599591ms","start":"2026-05-05T17:31:54.02959Z","end":"2026-05-05T17:31:54.522189Z","steps":["trace[147270322] 'agreement among raft nodes before linearized reading' (duration: 492.181413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:31:54.522214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:31:54.029569Z","time spent":"492.639492ms","remote":"127.0.0.1:50840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9818,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-mrcfj\" "} {"level":"info","ts":"2026-05-05T17:31:54.664625Z","caller":"traceutil/trace.go:171","msg":"trace[512824740] transaction","detail":"{read_only:false; response_revision:17665; number_of_response:1; }","duration":"118.859944ms","start":"2026-05-05T17:31:54.545716Z","end":"2026-05-05T17:31:54.664576Z","steps":["trace[512824740] 'process raft request' (duration: 83.919344ms)","trace[512824740] 'compare' (duration: 34.707936ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:32:23.33477Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15517} {"level":"info","ts":"2026-05-05T17:32:23.439542Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15517,"took":"100.907136ms","hash":2804826181,"current-db-size-bytes":57921536,"current-db-size":"58 MB","current-db-size-in-use-bytes":33726464,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-05T17:32:23.439606Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2804826181,"revision":15517,"compact-revision":12998} {"level":"info","ts":"2026-05-05T17:35:12.304784Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T17:35:12.309846Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-05T17:35:12.309927Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-05T17:36:50.818692Z","caller":"traceutil/trace.go:171","msg":"trace[421457831] transaction","detail":"{read_only:false; response_revision:19979; number_of_response:1; }","duration":"104.843386ms","start":"2026-05-05T17:36:50.713754Z","end":"2026-05-05T17:36:50.818597Z","steps":["trace[421457831] 'process raft request' (duration: 104.499578ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:36:50.99137Z","caller":"traceutil/trace.go:171","msg":"trace[1771449822] transaction","detail":"{read_only:false; response_revision:19980; number_of_response:1; }","duration":"167.365004ms","start":"2026-05-05T17:36:50.823967Z","end":"2026-05-05T17:36:50.991332Z","steps":["trace[1771449822] 'process raft request' (duration: 167.063318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:36:51.274448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.073303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T17:36:51.274623Z","caller":"traceutil/trace.go:171","msg":"trace[878778917] linearizableReadLoop","detail":"{readStateIndex:20645; appliedIndex:20644; }","duration":"211.126158ms","start":"2026-05-05T17:36:51.063479Z","end":"2026-05-05T17:36:51.274605Z","steps":["trace[878778917] 'read index received' (duration: 57.663047ms)","trace[878778917] 'applied index is now lower than readState.Index' (duration: 153.461401ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:36:51.274697Z","caller":"traceutil/trace.go:171","msg":"trace[1960485193] transaction","detail":"{read_only:false; response_revision:19981; number_of_response:1; }","duration":"450.636371ms","start":"2026-05-05T17:36:50.824045Z","end":"2026-05-05T17:36:51.274681Z","steps":["trace[1960485193] 'process raft request' (duration: 297.238012ms)","trace[1960485193] 'compare' (duration: 152.943791ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:36:51.27476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:36:50.824015Z","time spent":"450.713092ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:36:51.274921Z","caller":"traceutil/trace.go:171","msg":"trace[2012084727] transaction","detail":"{read_only:false; response_revision:19982; number_of_response:1; }","duration":"139.260743ms","start":"2026-05-05T17:36:51.135578Z","end":"2026-05-05T17:36:51.274839Z","steps":["trace[2012084727] 'process raft request' (duration: 138.983618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:36:51.275236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.75229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:560"} {"level":"info","ts":"2026-05-05T17:36:51.275309Z","caller":"traceutil/trace.go:171","msg":"trace[1926728586] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:19982; }","duration":"211.891522ms","start":"2026-05-05T17:36:51.063399Z","end":"2026-05-05T17:36:51.275291Z","steps":["trace[1926728586] 'agreement among raft nodes before linearized reading' (duration: 211.2607ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:36:51.574409Z","caller":"traceutil/trace.go:171","msg":"trace[1928335476] transaction","detail":"{read_only:false; response_revision:19984; number_of_response:1; }","duration":"138.703793ms","start":"2026-05-05T17:36:51.435679Z","end":"2026-05-05T17:36:51.574383Z","steps":["trace[1928335476] 'process raft request' (duration: 138.505499ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:36:51.773966Z","caller":"traceutil/trace.go:171","msg":"trace[1225889969] transaction","detail":"{read_only:false; response_revision:19985; number_of_response:1; }","duration":"110.92804ms","start":"2026-05-05T17:36:51.662992Z","end":"2026-05-05T17:36:51.77392Z","steps":["trace[1225889969] 'process raft request' (duration: 55.603858ms)","trace[1225889969] 'compare' (duration: 54.526937ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:36:52.062846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.779988ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T17:37:23.341982Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18097} {"level":"info","ts":"2026-05-05T17:37:23.454478Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18097,"took":"108.75428ms","hash":2977179731,"current-db-size-bytes":57921536,"current-db-size":"58 MB","current-db-size-in-use-bytes":29405184,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-05T17:37:23.45453Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2977179731,"revision":18097,"compact-revision":15517} {"level":"info","ts":"2026-05-05T17:40:34.890759Z","caller":"traceutil/trace.go:171","msg":"trace[901698276] linearizableReadLoop","detail":"{readStateIndex:23086; appliedIndex:23085; }","duration":"121.939927ms","start":"2026-05-05T17:40:34.768796Z","end":"2026-05-05T17:40:34.890736Z","steps":["trace[901698276] 'read index received' (duration: 106.12878ms)","trace[901698276] 'applied index is now lower than readState.Index' (duration: 15.810377ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:40:34.890964Z","caller":"traceutil/trace.go:171","msg":"trace[585416658] transaction","detail":"{read_only:false; response_revision:22347; number_of_response:1; }","duration":"224.303496ms","start":"2026-05-05T17:40:34.666649Z","end":"2026-05-05T17:40:34.890953Z","steps":["trace[585416658] 'process raft request' (duration: 208.346036ms)","trace[585416658] 'compare' (duration: 15.373329ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:40:34.891296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.498628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-05T17:40:34.891327Z","caller":"traceutil/trace.go:171","msg":"trace[1057138459] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22347; }","duration":"122.58965ms","start":"2026-05-05T17:40:34.768729Z","end":"2026-05-05T17:40:34.891319Z","steps":["trace[1057138459] 'agreement among raft nodes before linearized reading' (duration: 122.365655ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:40:35.040587Z","caller":"traceutil/trace.go:171","msg":"trace[1247969115] transaction","detail":"{read_only:false; response_revision:22348; number_of_response:1; }","duration":"144.753158ms","start":"2026-05-05T17:40:34.895807Z","end":"2026-05-05T17:40:35.04056Z","steps":["trace[1247969115] 'process raft request' (duration: 144.562384ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:42:23.350141Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20230} {"level":"info","ts":"2026-05-05T17:42:23.461488Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20230,"took":"106.933325ms","hash":524515662,"current-db-size-bytes":58765312,"current-db-size":"59 MB","current-db-size-in-use-bytes":42246144,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-05T17:42:23.461636Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":524515662,"revision":20230,"compact-revision":18097} {"level":"info","ts":"2026-05-05T17:43:58.286359Z","caller":"traceutil/trace.go:171","msg":"trace[1702562213] linearizableReadLoop","detail":"{readStateIndex:25197; appliedIndex:25196; }","duration":"141.926553ms","start":"2026-05-05T17:43:58.144403Z","end":"2026-05-05T17:43:58.286329Z","steps":["trace[1702562213] 'read index received' (duration: 141.801331ms)","trace[1702562213] 'applied index is now lower than readState.Index' (duration: 123.562µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:43:58.286524Z","caller":"traceutil/trace.go:171","msg":"trace[744399486] transaction","detail":"{read_only:false; response_revision:24403; number_of_response:1; }","duration":"150.101437ms","start":"2026-05-05T17:43:58.136392Z","end":"2026-05-05T17:43:58.286494Z","steps":["trace[744399486] 'process raft request' (duration: 149.69812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:43:58.286587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.148207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:43:58.286638Z","caller":"traceutil/trace.go:171","msg":"trace[2018096646] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24403; }","duration":"142.26821ms","start":"2026-05-05T17:43:58.144354Z","end":"2026-05-05T17:43:58.286622Z","steps":["trace[2018096646] 'agreement among raft nodes before linearized reading' (duration: 142.105377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:43:58.541909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.965726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T17:43:58.542003Z","caller":"traceutil/trace.go:171","msg":"trace[1443908973] linearizableReadLoop","detail":"{readStateIndex:25198; appliedIndex:25197; }","duration":"222.810588ms","start":"2026-05-05T17:43:58.319178Z","end":"2026-05-05T17:43:58.541989Z","steps":["trace[1443908973] 'read index received' (duration: 107.71988ms)","trace[1443908973] 'applied index is now lower than readState.Index' (duration: 115.089678ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:43:58.54215Z","caller":"traceutil/trace.go:171","msg":"trace[1676323005] transaction","detail":"{read_only:false; response_revision:24404; number_of_response:1; }","duration":"328.385668ms","start":"2026-05-05T17:43:58.213745Z","end":"2026-05-05T17:43:58.54213Z","steps":["trace[1676323005] 'process raft request' (duration: 213.116126ms)","trace[1676323005] 'compare' (duration: 114.875364ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:43:58.542219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:43:58.213722Z","time spent":"328.4654ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:43:58.542205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.020553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:43:58.542301Z","caller":"traceutil/trace.go:171","msg":"trace[2124210703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24404; }","duration":"223.171765ms","start":"2026-05-05T17:43:58.319111Z","end":"2026-05-05T17:43:58.542282Z","steps":["trace[2124210703] 'agreement among raft nodes before linearized reading' (duration: 223.018332ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:43:58.845968Z","caller":"traceutil/trace.go:171","msg":"trace[1456817619] linearizableReadLoop","detail":"{readStateIndex:25199; appliedIndex:25198; }","duration":"303.917315ms","start":"2026-05-05T17:43:58.542021Z","end":"2026-05-05T17:43:58.845938Z","steps":["trace[1456817619] 'read index received' (duration: 284.607511ms)","trace[1456817619] 'applied index is now lower than readState.Index' (duration: 19.308444ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:43:58.846006Z","caller":"traceutil/trace.go:171","msg":"trace[959397026] transaction","detail":"{read_only:false; response_revision:24405; number_of_response:1; }","duration":"414.463608ms","start":"2026-05-05T17:43:58.43149Z","end":"2026-05-05T17:43:58.845954Z","steps":["trace[959397026] 'process raft request' (duration: 395.251096ms)","trace[959397026] 'compare' (duration: 19.050519ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:43:58.846197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.28456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-05T17:43:58.84625Z","caller":"traceutil/trace.go:171","msg":"trace[95838477] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24405; }","duration":"324.401102ms","start":"2026-05-05T17:43:58.521836Z","end":"2026-05-05T17:43:58.846237Z","steps":["trace[95838477] 'agreement among raft nodes before linearized reading' (duration: 324.230519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:43:58.846267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.641482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T17:43:58.84625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:43:58.431454Z","time spent":"414.660202ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:43:58.846299Z","caller":"traceutil/trace.go:171","msg":"trace[543547147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24405; }","duration":"302.703473ms","start":"2026-05-05T17:43:58.543586Z","end":"2026-05-05T17:43:58.846289Z","steps":["trace[543547147] 'agreement among raft nodes before linearized reading' (duration: 302.653332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:43:58.846334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:43:58.543569Z","time spent":"302.757714ms","remote":"127.0.0.1:50692","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-05-05T17:43:58.846296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.094968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-05T17:43:58.846398Z","caller":"traceutil/trace.go:171","msg":"trace[1794752792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24405; }","duration":"322.236751ms","start":"2026-05-05T17:43:58.524134Z","end":"2026-05-05T17:43:58.846371Z","steps":["trace[1794752792] 'agreement among raft nodes before linearized reading' (duration: 321.911965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:43:58.846449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:43:58.524123Z","time spent":"322.316872ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T17:43:58.846283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:43:58.521813Z","time spent":"324.462293ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T17:43:58.846532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.637748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18613"} {"level":"info","ts":"2026-05-05T17:43:58.846557Z","caller":"traceutil/trace.go:171","msg":"trace[1484169745] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24405; }","duration":"305.673378ms","start":"2026-05-05T17:43:58.540876Z","end":"2026-05-05T17:43:58.84655Z","steps":["trace[1484169745] 'agreement among raft nodes before linearized reading' (duration: 305.590387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:43:58.846576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:43:58.540867Z","time spent":"305.7042ms","remote":"127.0.0.1:50830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18635,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-05T17:43:58.8462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.142659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T17:43:58.846645Z","caller":"traceutil/trace.go:171","msg":"trace[1675669390] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:24405; }","duration":"520.625428ms","start":"2026-05-05T17:43:58.325998Z","end":"2026-05-05T17:43:58.846623Z","steps":["trace[1675669390] 'agreement among raft nodes before linearized reading' (duration: 520.088688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:43:58.846711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:43:58.325984Z","time spent":"520.703379ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":10,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-05-05T17:43:58.846746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.242824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-05T17:43:58.846859Z","caller":"traceutil/trace.go:171","msg":"trace[136381886] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24405; }","duration":"215.395178ms","start":"2026-05-05T17:43:58.631438Z","end":"2026-05-05T17:43:58.846833Z","steps":["trace[136381886] 'agreement among raft nodes before linearized reading' (duration: 215.065602ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:43:58.960592Z","caller":"traceutil/trace.go:171","msg":"trace[1531048718] transaction","detail":"{read_only:false; response_revision:24406; number_of_response:1; }","duration":"106.012837ms","start":"2026-05-05T17:43:58.854547Z","end":"2026-05-05T17:43:58.96056Z","steps":["trace[1531048718] 'process raft request' (duration: 104.989828ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:43:58.961081Z","caller":"traceutil/trace.go:171","msg":"trace[356973069] linearizableReadLoop","detail":"{readStateIndex:25201; appliedIndex:25199; }","duration":"106.277732ms","start":"2026-05-05T17:43:58.85477Z","end":"2026-05-05T17:43:58.961048Z","steps":["trace[356973069] 'read index received' (duration: 104.800705ms)","trace[356973069] 'applied index is now lower than readState.Index' (duration: 1.475707ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:43:58.961134Z","caller":"traceutil/trace.go:171","msg":"trace[870838211] transaction","detail":"{read_only:false; response_revision:24407; number_of_response:1; }","duration":"106.411754ms","start":"2026-05-05T17:43:58.854701Z","end":"2026-05-05T17:43:58.961113Z","steps":["trace[870838211] 'process raft request' (duration: 105.806113ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:43:58.961255Z","caller":"traceutil/trace.go:171","msg":"trace[2018538473] transaction","detail":"{read_only:false; response_revision:24408; number_of_response:1; }","duration":"106.456895ms","start":"2026-05-05T17:43:58.854768Z","end":"2026-05-05T17:43:58.961225Z","steps":["trace[2018538473] 'process raft request' (duration: 106.068668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:43:58.961346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.546557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18613"} {"level":"info","ts":"2026-05-05T17:43:58.961391Z","caller":"traceutil/trace.go:171","msg":"trace[2024312881] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24408; }","duration":"106.639719ms","start":"2026-05-05T17:43:58.85474Z","end":"2026-05-05T17:43:58.96138Z","steps":["trace[2024312881] 'agreement among raft nodes before linearized reading' (duration: 106.439345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:03.655331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.975952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T17:44:03.655478Z","caller":"traceutil/trace.go:171","msg":"trace[1189659435] transaction","detail":"{read_only:false; response_revision:24441; number_of_response:1; }","duration":"206.828427ms","start":"2026-05-05T17:44:03.44862Z","end":"2026-05-05T17:44:03.655448Z","steps":["trace[1189659435] 'process raft request' (duration: 87.608911ms)","trace[1189659435] 'compare' (duration: 118.720407ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:44:03.831731Z","caller":"traceutil/trace.go:171","msg":"trace[846780028] transaction","detail":"{read_only:false; response_revision:24442; number_of_response:1; }","duration":"162.755316ms","start":"2026-05-05T17:44:03.668913Z","end":"2026-05-05T17:44:03.831668Z","steps":["trace[846780028] 'process raft request' (duration: 140.001667ms)","trace[846780028] 'compare' (duration: 22.2524ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:44:04.096158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.683854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T17:44:04.09651Z","caller":"traceutil/trace.go:171","msg":"trace[1711243310] linearizableReadLoop","detail":"{readStateIndex:25239; appliedIndex:25237; }","duration":"180.490851ms","start":"2026-05-05T17:44:03.915996Z","end":"2026-05-05T17:44:04.096487Z","steps":["trace[1711243310] 'read index received' (duration: 51.380338ms)","trace[1711243310] 'applied index is now lower than readState.Index' (duration: 129.108373ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:44:04.096704Z","caller":"traceutil/trace.go:171","msg":"trace[120401439] transaction","detail":"{read_only:false; response_revision:24444; number_of_response:1; }","duration":"217.484297ms","start":"2026-05-05T17:44:03.87918Z","end":"2026-05-05T17:44:04.096664Z","steps":["trace[120401439] 'process raft request' (duration: 217.09772ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:04.09682Z","caller":"traceutil/trace.go:171","msg":"trace[1049243391] transaction","detail":"{read_only:false; response_revision:24443; number_of_response:1; }","duration":"358.132717ms","start":"2026-05-05T17:44:03.738466Z","end":"2026-05-05T17:44:04.096599Z","steps":["trace[1049243391] 'process raft request' (duration: 228.888372ms)","trace[1049243391] 'compare' (duration: 128.44545ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:44:04.09686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.818747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T17:44:04.097001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:03.738443Z","time spent":"358.442303ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:44:04.096989Z","caller":"traceutil/trace.go:171","msg":"trace[518435817] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:24444; }","duration":"181.027981ms","start":"2026-05-05T17:44:03.915932Z","end":"2026-05-05T17:44:04.09696Z","steps":["trace[518435817] 'agreement among raft nodes before linearized reading' (duration: 180.739905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:04.457805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.991183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:44:04.457889Z","caller":"traceutil/trace.go:171","msg":"trace[545587432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24444; }","duration":"144.111494ms","start":"2026-05-05T17:44:04.313758Z","end":"2026-05-05T17:44:04.45787Z","steps":["trace[545587432] 'range keys from in-memory index tree' (duration: 143.918112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:04.457845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.163426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-05T17:44:04.458136Z","caller":"traceutil/trace.go:171","msg":"trace[1279139004] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24444; }","duration":"144.526663ms","start":"2026-05-05T17:44:04.313589Z","end":"2026-05-05T17:44:04.458116Z","steps":["trace[1279139004] 'range keys from in-memory index tree' (duration: 143.84538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:04.45857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.167913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:44:04.458682Z","caller":"traceutil/trace.go:171","msg":"trace[1898729029] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24444; }","duration":"130.343736ms","start":"2026-05-05T17:44:04.328312Z","end":"2026-05-05T17:44:04.458655Z","steps":["trace[1898729029] 'range keys from in-memory index tree' (duration: 130.092032ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:04.695364Z","caller":"traceutil/trace.go:171","msg":"trace[196476011] transaction","detail":"{read_only:false; response_revision:24446; number_of_response:1; }","duration":"133.658439ms","start":"2026-05-05T17:44:04.561679Z","end":"2026-05-05T17:44:04.695337Z","steps":["trace[196476011] 'process raft request' (duration: 121.246756ms)","trace[196476011] 'compare' (duration: 12.318271ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:44:05.177871Z","caller":"traceutil/trace.go:171","msg":"trace[991661429] transaction","detail":"{read_only:false; response_revision:24447; number_of_response:1; }","duration":"297.887822ms","start":"2026-05-05T17:44:04.879954Z","end":"2026-05-05T17:44:05.177842Z","steps":["trace[991661429] 'process raft request' (duration: 297.679618ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:05.177954Z","caller":"traceutil/trace.go:171","msg":"trace[1992759936] linearizableReadLoop","detail":"{readStateIndex:25242; appliedIndex:25242; }","duration":"190.432388ms","start":"2026-05-05T17:44:04.987487Z","end":"2026-05-05T17:44:05.17792Z","steps":["trace[1992759936] 'read index received' (duration: 190.419588ms)","trace[1992759936] 'applied index is now lower than readState.Index' (duration: 10.23µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:44:05.178293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.788135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-05T17:44:05.178396Z","caller":"traceutil/trace.go:171","msg":"trace[1476132203] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24447; }","duration":"190.934668ms","start":"2026-05-05T17:44:04.987426Z","end":"2026-05-05T17:44:05.178361Z","steps":["trace[1476132203] 'agreement among raft nodes before linearized reading' (duration: 190.637203ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:05.206238Z","caller":"traceutil/trace.go:171","msg":"trace[509524314] transaction","detail":"{read_only:false; response_revision:24448; number_of_response:1; }","duration":"173.824365ms","start":"2026-05-05T17:44:05.032392Z","end":"2026-05-05T17:44:05.206217Z","steps":["trace[509524314] 'process raft request' (duration: 173.648431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:05.206328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.272454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-05T17:44:05.206399Z","caller":"traceutil/trace.go:171","msg":"trace[320054476] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24448; }","duration":"217.392356ms","start":"2026-05-05T17:44:04.988986Z","end":"2026-05-05T17:44:05.206379Z","steps":["trace[320054476] 'agreement among raft nodes before linearized reading' (duration: 217.207013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:05.206513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.744164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-05T17:44:05.206567Z","caller":"traceutil/trace.go:171","msg":"trace[1190336681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24448; }","duration":"216.831747ms","start":"2026-05-05T17:44:04.989724Z","end":"2026-05-05T17:44:05.206556Z","steps":["trace[1190336681] 'agreement among raft nodes before linearized reading' (duration: 216.640353ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:05.413331Z","caller":"traceutil/trace.go:171","msg":"trace[951335901] transaction","detail":"{read_only:false; response_revision:24449; number_of_response:1; }","duration":"229.80952ms","start":"2026-05-05T17:44:05.183489Z","end":"2026-05-05T17:44:05.413299Z","steps":["trace[951335901] 'process raft request' (duration: 159.000906ms)","trace[951335901] 'compare' (duration: 70.695372ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:44:05.414049Z","caller":"traceutil/trace.go:171","msg":"trace[1012491103] linearizableReadLoop","detail":"{readStateIndex:25248; appliedIndex:25243; }","duration":"105.774724ms","start":"2026-05-05T17:44:05.30826Z","end":"2026-05-05T17:44:05.414034Z","steps":["trace[1012491103] 'read index received' (duration: 34.246036ms)","trace[1012491103] 'applied index is now lower than readState.Index' (duration: 71.527848ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:44:05.414056Z","caller":"traceutil/trace.go:171","msg":"trace[48416043] transaction","detail":"{read_only:false; response_revision:24450; number_of_response:1; }","duration":"203.344572ms","start":"2026-05-05T17:44:05.210696Z","end":"2026-05-05T17:44:05.41404Z","steps":["trace[48416043] 'process raft request' (duration: 203.160838ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:05.414116Z","caller":"traceutil/trace.go:171","msg":"trace[240780236] transaction","detail":"{read_only:false; response_revision:24451; number_of_response:1; }","duration":"202.294921ms","start":"2026-05-05T17:44:05.211801Z","end":"2026-05-05T17:44:05.414096Z","steps":["trace[240780236] 'process raft request' (duration: 202.130818ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:05.414127Z","caller":"traceutil/trace.go:171","msg":"trace[133466647] transaction","detail":"{read_only:false; response_revision:24453; number_of_response:1; }","duration":"154.775436ms","start":"2026-05-05T17:44:05.259322Z","end":"2026-05-05T17:44:05.414097Z","steps":["trace[133466647] 'process raft request' (duration: 154.678404ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:05.414154Z","caller":"traceutil/trace.go:171","msg":"trace[981797353] transaction","detail":"{read_only:false; response_revision:24452; number_of_response:1; }","duration":"161.424852ms","start":"2026-05-05T17:44:05.252685Z","end":"2026-05-05T17:44:05.41411Z","steps":["trace[981797353] 'process raft request' (duration: 161.283699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:05.414258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.988497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/glance-api-69c6d67568-nzbct.18acbad6f56cd674\" ","response":"range_response_count:1 size:1979"} {"level":"info","ts":"2026-05-05T17:44:05.414335Z","caller":"traceutil/trace.go:171","msg":"trace[252826730] range","detail":"{range_begin:/registry/events/openstack/glance-api-69c6d67568-nzbct.18acbad6f56cd674; range_end:; response_count:1; response_revision:24453; }","duration":"106.10797ms","start":"2026-05-05T17:44:05.308206Z","end":"2026-05-05T17:44:05.414314Z","steps":["trace[252826730] 'agreement among raft nodes before linearized reading' (duration: 105.892646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:05.714022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.504973ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T17:44:05.714136Z","caller":"traceutil/trace.go:171","msg":"trace[1496366290] transaction","detail":"{read_only:false; response_revision:24455; number_of_response:1; }","duration":"204.744827ms","start":"2026-05-05T17:44:05.509373Z","end":"2026-05-05T17:44:05.714118Z","steps":["trace[1496366290] 'process raft request' (duration: 77.047801ms)","trace[1496366290] 'compare' (duration: 127.270008ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:44:06.046421Z","caller":"traceutil/trace.go:171","msg":"trace[329817501] linearizableReadLoop","detail":"{readStateIndex:25251; appliedIndex:25250; }","duration":"359.695807ms","start":"2026-05-05T17:44:05.68669Z","end":"2026-05-05T17:44:06.046386Z","steps":["trace[329817501] 'read index received' (duration: 352.530903ms)","trace[329817501] 'applied index is now lower than readState.Index' (duration: 7.163284ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:44:06.046605Z","caller":"traceutil/trace.go:171","msg":"trace[1474880745] transaction","detail":"{read_only:false; response_revision:24456; number_of_response:1; }","duration":"385.238729ms","start":"2026-05-05T17:44:05.661349Z","end":"2026-05-05T17:44:06.046588Z","steps":["trace[1474880745] 'process raft request' (duration: 377.953761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:06.046766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:05.661322Z","time spent":"385.327051ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:44:06.046847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.388215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-05T17:44:06.046931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.566391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:44:06.046958Z","caller":"traceutil/trace.go:171","msg":"trace[120602164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24456; }","duration":"209.551238ms","start":"2026-05-05T17:44:05.83738Z","end":"2026-05-05T17:44:06.046931Z","steps":["trace[120602164] 'agreement among raft nodes before linearized reading' (duration: 209.292553ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:06.047033Z","caller":"traceutil/trace.go:171","msg":"trace[176310170] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:24456; }","duration":"103.738695ms","start":"2026-05-05T17:44:05.943269Z","end":"2026-05-05T17:44:06.047008Z","steps":["trace[176310170] 'agreement among raft nodes before linearized reading' (duration: 103.545031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:06.046928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.233348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T17:44:06.047186Z","caller":"traceutil/trace.go:171","msg":"trace[133504649] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:24456; }","duration":"360.530173ms","start":"2026-05-05T17:44:05.686608Z","end":"2026-05-05T17:44:06.047139Z","steps":["trace[133504649] 'agreement among raft nodes before linearized reading' (duration: 360.203647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:06.047385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:05.686549Z","time spent":"360.791158ms","remote":"127.0.0.1:51142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-05-05T17:44:06.373116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.302133ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T17:44:06.373333Z","caller":"traceutil/trace.go:171","msg":"trace[1907216904] transaction","detail":"{read_only:false; response_revision:24458; number_of_response:1; }","duration":"271.392683ms","start":"2026-05-05T17:44:06.101918Z","end":"2026-05-05T17:44:06.373311Z","steps":["trace[1907216904] 'process raft request' (duration: 130.768524ms)","trace[1907216904] 'compare' (duration: 140.170501ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:44:06.373731Z","caller":"traceutil/trace.go:171","msg":"trace[1175595746] transaction","detail":"{read_only:false; response_revision:24459; number_of_response:1; }","duration":"271.643278ms","start":"2026-05-05T17:44:06.102054Z","end":"2026-05-05T17:44:06.373697Z","steps":["trace[1175595746] 'process raft request' (duration: 271.18782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:06.640004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.341507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-05T17:44:06.640104Z","caller":"traceutil/trace.go:171","msg":"trace[2068222784] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:24459; }","duration":"112.480559ms","start":"2026-05-05T17:44:06.527602Z","end":"2026-05-05T17:44:06.640082Z","steps":["trace[2068222784] 'range keys from in-memory index tree' (duration: 112.098932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:06.640384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.778653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-05T17:44:06.640511Z","caller":"traceutil/trace.go:171","msg":"trace[2101755173] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24459; }","duration":"165.022899ms","start":"2026-05-05T17:44:06.475459Z","end":"2026-05-05T17:44:06.640482Z","steps":["trace[2101755173] 'range keys from in-memory index tree' (duration: 164.533479ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:06.969991Z","caller":"traceutil/trace.go:171","msg":"trace[329398358] transaction","detail":"{read_only:false; response_revision:24462; number_of_response:1; }","duration":"306.611687ms","start":"2026-05-05T17:44:06.663349Z","end":"2026-05-05T17:44:06.969961Z","steps":["trace[329398358] 'process raft request' (duration: 276.093602ms)","trace[329398358] 'compare' (duration: 30.347812ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:44:06.970135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:06.663336Z","time spent":"306.725919ms","remote":"127.0.0.1:50728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:44:06.980039Z","caller":"traceutil/trace.go:171","msg":"trace[261217841] transaction","detail":"{read_only:false; response_revision:24463; number_of_response:1; }","duration":"280.027986ms","start":"2026-05-05T17:44:06.699983Z","end":"2026-05-05T17:44:06.980011Z","steps":["trace[261217841] 'process raft request' (duration: 279.804482ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:07.703058Z","caller":"traceutil/trace.go:171","msg":"trace[55764242] transaction","detail":"{read_only:false; response_revision:24467; number_of_response:1; }","duration":"283.469751ms","start":"2026-05-05T17:44:07.419558Z","end":"2026-05-05T17:44:07.703028Z","steps":["trace[55764242] 'process raft request' (duration: 283.283977ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:07.798411Z","caller":"traceutil/trace.go:171","msg":"trace[1061051210] transaction","detail":"{read_only:false; response_revision:24468; number_of_response:1; }","duration":"375.157618ms","start":"2026-05-05T17:44:07.423223Z","end":"2026-05-05T17:44:07.79838Z","steps":["trace[1061051210] 'process raft request' (duration: 374.931054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:07.798591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:07.423201Z","time spent":"375.29557ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:44:07.799928Z","caller":"traceutil/trace.go:171","msg":"trace[1721608822] transaction","detail":"{read_only:false; response_revision:24469; number_of_response:1; }","duration":"375.80103ms","start":"2026-05-05T17:44:07.423325Z","end":"2026-05-05T17:44:07.799126Z","steps":["trace[1721608822] 'process raft request' (duration: 375.007115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:07.800178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:07.423318Z","time spent":"376.745737ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:44:07.83372Z","caller":"traceutil/trace.go:171","msg":"trace[34229074] linearizableReadLoop","detail":"{readStateIndex:25266; appliedIndex:25263; }","duration":"114.333444ms","start":"2026-05-05T17:44:07.719349Z","end":"2026-05-05T17:44:07.833683Z","steps":["trace[34229074] 'read index received' (duration: 78.854876ms)","trace[34229074] 'applied index is now lower than readState.Index' (duration: 35.476448ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:44:07.833757Z","caller":"traceutil/trace.go:171","msg":"trace[2117693563] transaction","detail":"{read_only:false; response_revision:24470; number_of_response:1; }","duration":"410.055146ms","start":"2026-05-05T17:44:07.423659Z","end":"2026-05-05T17:44:07.833714Z","steps":["trace[2117693563] 'process raft request' (duration: 374.852363ms)","trace[2117693563] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; req_size:536; } (duration: 34.739524ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:44:07.834091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.704302ms","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-05-05T17:44:07.83413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:07.423647Z","time spent":"410.174919ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:44:07.834142Z","caller":"traceutil/trace.go:171","msg":"trace[425844733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24470; }","duration":"114.837444ms","start":"2026-05-05T17:44:07.719292Z","end":"2026-05-05T17:44:07.834129Z","steps":["trace[425844733] 'agreement among raft nodes before linearized reading' (duration: 114.520558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:08.129514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.483139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T17:44:08.129707Z","caller":"traceutil/trace.go:171","msg":"trace[930962819] transaction","detail":"{read_only:false; response_revision:24471; number_of_response:1; }","duration":"290.144496ms","start":"2026-05-05T17:44:07.839492Z","end":"2026-05-05T17:44:08.129636Z","steps":["trace[930962819] 'process raft request' (duration: 116.433354ms)","trace[930962819] 'compare' (duration: 173.314445ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:44:08.447223Z","caller":"traceutil/trace.go:171","msg":"trace[260644172] linearizableReadLoop","detail":"{readStateIndex:25269; appliedIndex:25268; }","duration":"260.315644ms","start":"2026-05-05T17:44:08.186874Z","end":"2026-05-05T17:44:08.44719Z","steps":["trace[260644172] 'read index received' (duration: 259.920207ms)","trace[260644172] 'applied index is now lower than readState.Index' (duration: 393.677µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:44:08.447299Z","caller":"traceutil/trace.go:171","msg":"trace[1350910398] transaction","detail":"{read_only:false; response_revision:24473; number_of_response:1; }","duration":"296.085628ms","start":"2026-05-05T17:44:08.151168Z","end":"2026-05-05T17:44:08.447254Z","steps":["trace[1350910398] 'process raft request' (duration: 295.521137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:08.447562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.664321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-05T17:44:08.447648Z","caller":"traceutil/trace.go:171","msg":"trace[210563940] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24473; }","duration":"260.810764ms","start":"2026-05-05T17:44:08.186819Z","end":"2026-05-05T17:44:08.447629Z","steps":["trace[210563940] 'agreement among raft nodes before linearized reading' (duration: 260.546619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:08.464781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.295649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:44:08.46487Z","caller":"traceutil/trace.go:171","msg":"trace[856144121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24475; }","duration":"153.437951ms","start":"2026-05-05T17:44:08.311412Z","end":"2026-05-05T17:44:08.46485Z","steps":["trace[856144121] 'agreement among raft nodes before linearized reading' (duration: 153.278038ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:08.804681Z","caller":"traceutil/trace.go:171","msg":"trace[1921442592] transaction","detail":"{read_only:false; response_revision:24477; number_of_response:1; }","duration":"131.053459ms","start":"2026-05-05T17:44:08.673592Z","end":"2026-05-05T17:44:08.804645Z","steps":["trace[1921442592] 'process raft request' (duration: 130.866046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:09.082747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.771078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:44:09.082851Z","caller":"traceutil/trace.go:171","msg":"trace[861899821] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:24477; }","duration":"247.950902ms","start":"2026-05-05T17:44:08.834878Z","end":"2026-05-05T17:44:09.082829Z","steps":["trace[861899821] 'count revisions from in-memory index tree' (duration: 247.645436ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:09.106178Z","caller":"traceutil/trace.go:171","msg":"trace[680432157] transaction","detail":"{read_only:false; response_revision:24478; number_of_response:1; }","duration":"120.034782ms","start":"2026-05-05T17:44:08.986127Z","end":"2026-05-05T17:44:09.106162Z","steps":["trace[680432157] 'process raft request' (duration: 119.846568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:18.75491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.105304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:44:18.755027Z","caller":"traceutil/trace.go:171","msg":"trace[1141379595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24548; }","duration":"437.281946ms","start":"2026-05-05T17:44:18.31772Z","end":"2026-05-05T17:44:18.755002Z","steps":["trace[1141379595] 'range keys from in-memory index tree' (duration: 437.016092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:18.755074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:18.317699Z","time spent":"437.365399ms","remote":"127.0.0.1:50684","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-05-05T17:44:18.756451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.387633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T17:44:18.75654Z","caller":"traceutil/trace.go:171","msg":"trace[490954200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24548; }","duration":"259.507694ms","start":"2026-05-05T17:44:18.497011Z","end":"2026-05-05T17:44:18.756519Z","steps":["trace[490954200] 'range keys from in-memory index tree' (duration: 259.150628ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:18.757036Z","caller":"traceutil/trace.go:171","msg":"trace[1532668523] transaction","detail":"{read_only:false; response_revision:24549; number_of_response:1; }","duration":"254.341077ms","start":"2026-05-05T17:44:18.502658Z","end":"2026-05-05T17:44:18.756999Z","steps":["trace[1532668523] 'process raft request' (duration: 254.081473ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:18.757723Z","caller":"traceutil/trace.go:171","msg":"trace[1655853694] linearizableReadLoop","detail":"{readStateIndex:25348; appliedIndex:25346; }","duration":"191.918649ms","start":"2026-05-05T17:44:18.565774Z","end":"2026-05-05T17:44:18.757693Z","steps":["trace[1655853694] 'read index received' (duration: 190.89692ms)","trace[1655853694] 'applied index is now lower than readState.Index' (duration: 1.020459ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:44:18.757935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.127333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:44:18.757977Z","caller":"traceutil/trace.go:171","msg":"trace[721301078] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:24550; }","duration":"192.249726ms","start":"2026-05-05T17:44:18.565713Z","end":"2026-05-05T17:44:18.757963Z","steps":["trace[721301078] 'agreement among raft nodes before linearized reading' (duration: 192.147254ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:18.75835Z","caller":"traceutil/trace.go:171","msg":"trace[948284498] transaction","detail":"{read_only:false; response_revision:24550; number_of_response:1; }","duration":"250.27534ms","start":"2026-05-05T17:44:18.508045Z","end":"2026-05-05T17:44:18.75832Z","steps":["trace[948284498] 'process raft request' (duration: 249.488715ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:19.06808Z","caller":"traceutil/trace.go:171","msg":"trace[1445125172] linearizableReadLoop","detail":"{readStateIndex:25349; appliedIndex:25348; }","duration":"263.094792ms","start":"2026-05-05T17:44:18.804957Z","end":"2026-05-05T17:44:19.068051Z","steps":["trace[1445125172] 'read index received' (duration: 218.873078ms)","trace[1445125172] 'applied index is now lower than readState.Index' (duration: 44.220364ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:44:19.068136Z","caller":"traceutil/trace.go:171","msg":"trace[1707638591] transaction","detail":"{read_only:false; response_revision:24551; number_of_response:1; }","duration":"307.319886ms","start":"2026-05-05T17:44:18.760763Z","end":"2026-05-05T17:44:19.068083Z","steps":["trace[1707638591] 'process raft request' (duration: 263.070602ms)","trace[1707638591] 'compare' (duration: 44.076761ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:44:19.068232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.237254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:44:19.068284Z","caller":"traceutil/trace.go:171","msg":"trace[982701847] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:24551; }","duration":"263.319676ms","start":"2026-05-05T17:44:18.804948Z","end":"2026-05-05T17:44:19.068267Z","steps":["trace[982701847] 'agreement among raft nodes before linearized reading' (duration: 263.194193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:19.068315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:18.760746Z","time spent":"307.477609ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:44:19.068341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.429816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-05T17:44:19.068373Z","caller":"traceutil/trace.go:171","msg":"trace[1479366839] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24551; }","duration":"221.460617ms","start":"2026-05-05T17:44:18.8469Z","end":"2026-05-05T17:44:19.06836Z","steps":["trace[1479366839] 'agreement among raft nodes before linearized reading' (duration: 221.304614ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:19.594742Z","caller":"traceutil/trace.go:171","msg":"trace[1971125499] transaction","detail":"{read_only:false; response_revision:24552; number_of_response:1; }","duration":"522.162899ms","start":"2026-05-05T17:44:19.072546Z","end":"2026-05-05T17:44:19.594709Z","steps":["trace[1971125499] 'process raft request' (duration: 521.989326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:19.595041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:19.072521Z","time spent":"522.411053ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:44:19.605432Z","caller":"traceutil/trace.go:171","msg":"trace[1135206681] linearizableReadLoop","detail":"{readStateIndex:25353; appliedIndex:25349; }","duration":"282.331355ms","start":"2026-05-05T17:44:19.323069Z","end":"2026-05-05T17:44:19.605401Z","steps":["trace[1135206681] 'read index received' (duration: 271.384619ms)","trace[1135206681] 'applied index is now lower than readState.Index' (duration: 10.945446ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:44:19.605835Z","caller":"traceutil/trace.go:171","msg":"trace[1899241031] transaction","detail":"{read_only:false; response_revision:24553; number_of_response:1; }","duration":"451.364032ms","start":"2026-05-05T17:44:19.154453Z","end":"2026-05-05T17:44:19.605817Z","steps":["trace[1899241031] 'process raft request' (duration: 450.69552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:19.605976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:19.154425Z","time spent":"451.454354ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:44:19.606195Z","caller":"traceutil/trace.go:171","msg":"trace[775350820] transaction","detail":"{read_only:false; response_revision:24554; number_of_response:1; }","duration":"342.230514ms","start":"2026-05-05T17:44:19.263947Z","end":"2026-05-05T17:44:19.606177Z","steps":["trace[775350820] 'process raft request' (duration: 341.359008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:19.606268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:19.263918Z","time spent":"342.313246ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:44:19.606429Z","caller":"traceutil/trace.go:171","msg":"trace[1179795308] transaction","detail":"{read_only:false; response_revision:24555; number_of_response:1; }","duration":"318.707751ms","start":"2026-05-05T17:44:19.287711Z","end":"2026-05-05T17:44:19.606419Z","steps":["trace[1179795308] 'process raft request' (duration: 317.642461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:19.606492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:19.287672Z","time spent":"318.789972ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:44:19.606688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.612739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:44:19.606793Z","caller":"traceutil/trace.go:171","msg":"trace[253214206] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:24555; }","duration":"283.763212ms","start":"2026-05-05T17:44:19.323007Z","end":"2026-05-05T17:44:19.60677Z","steps":["trace[253214206] 'agreement among raft nodes before linearized reading' (duration: 283.612259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:19.606858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.818731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-05-05T17:44:19.606946Z","caller":"traceutil/trace.go:171","msg":"trace[938815180] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:24555; }","duration":"214.979074ms","start":"2026-05-05T17:44:19.391946Z","end":"2026-05-05T17:44:19.606925Z","steps":["trace[938815180] 'agreement among raft nodes before linearized reading' (duration: 214.702329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:20.196777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.692637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T17:44:20.196892Z","caller":"traceutil/trace.go:171","msg":"trace[332418077] linearizableReadLoop","detail":"{readStateIndex:25354; appliedIndex:25353; }","duration":"419.44002ms","start":"2026-05-05T17:44:19.777434Z","end":"2026-05-05T17:44:20.196874Z","steps":["trace[332418077] 'read index received' (duration: 141.506829ms)","trace[332418077] 'applied index is now lower than readState.Index' (duration: 277.932061ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:44:20.196961Z","caller":"traceutil/trace.go:171","msg":"trace[494117975] transaction","detail":"{read_only:false; response_revision:24556; number_of_response:1; }","duration":"445.267058ms","start":"2026-05-05T17:44:19.751682Z","end":"2026-05-05T17:44:20.196949Z","steps":["trace[494117975] 'process raft request' (duration: 167.306646ms)","trace[494117975] 'compare' (duration: 277.554134ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:44:20.197014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:19.751655Z","time spent":"445.33089ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:44:20.197058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.598093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:44:20.197176Z","caller":"traceutil/trace.go:171","msg":"trace[2012500604] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:24556; }","duration":"419.754497ms","start":"2026-05-05T17:44:19.777392Z","end":"2026-05-05T17:44:20.197146Z","steps":["trace[2012500604] 'agreement among raft nodes before linearized reading' (duration: 419.590804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:20.197246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:19.777374Z","time spent":"419.852508ms","remote":"127.0.0.1:46168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"warn","ts":"2026-05-05T17:44:20.69719Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020207242501892,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T17:44:20.74618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.2379ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:44:20.74635Z","caller":"traceutil/trace.go:171","msg":"trace[1051325703] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24556; }","duration":"293.425714ms","start":"2026-05-05T17:44:20.452896Z","end":"2026-05-05T17:44:20.746322Z","steps":["trace[1051325703] 'range keys from in-memory index tree' (duration: 293.21526ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:20.747055Z","caller":"traceutil/trace.go:171","msg":"trace[1408118406] transaction","detail":"{read_only:false; response_revision:24557; number_of_response:1; }","duration":"564.349092ms","start":"2026-05-05T17:44:20.182658Z","end":"2026-05-05T17:44:20.747007Z","steps":["trace[1408118406] 'process raft request' (duration: 541.41283ms)","trace[1408118406] 'compare' (duration: 21.891233ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:44:20.747727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:20.182612Z","time spent":"564.653138ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:44:20.748448Z","caller":"traceutil/trace.go:171","msg":"trace[257125430] linearizableReadLoop","detail":"{readStateIndex:25356; appliedIndex:25354; }","duration":"551.505832ms","start":"2026-05-05T17:44:20.196919Z","end":"2026-05-05T17:44:20.748425Z","steps":["trace[257125430] 'read index received' (duration: 527.073881ms)","trace[257125430] 'applied index is now lower than readState.Index' (duration: 24.430541ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:44:20.748648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.737796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T17:44:20.748711Z","caller":"traceutil/trace.go:171","msg":"trace[1619842375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24558; }","duration":"568.850219ms","start":"2026-05-05T17:44:20.179845Z","end":"2026-05-05T17:44:20.748695Z","steps":["trace[1619842375] 'agreement among raft nodes before linearized reading' (duration: 568.627185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:20.748746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:20.179817Z","time spent":"568.922069ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T17:44:20.748817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.8396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-05T17:44:20.748859Z","caller":"traceutil/trace.go:171","msg":"trace[513723005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24558; }","duration":"883.932011ms","start":"2026-05-05T17:44:19.864917Z","end":"2026-05-05T17:44:20.748849Z","steps":["trace[513723005] 'agreement among raft nodes before linearized reading' (duration: 883.8493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:20.749022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:19.864889Z","time spent":"884.121114ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T17:44:20.749027Z","caller":"traceutil/trace.go:171","msg":"trace[1184044104] transaction","detail":"{read_only:false; response_revision:24558; number_of_response:1; }","duration":"319.72183ms","start":"2026-05-05T17:44:20.429292Z","end":"2026-05-05T17:44:20.749013Z","steps":["trace[1184044104] 'process raft request' (duration: 318.994696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:20.749101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:20.429256Z","time spent":"319.805751ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:44:20.748735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.93975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-05T17:44:20.749204Z","caller":"traceutil/trace.go:171","msg":"trace[1638184512] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24558; }","duration":"832.488411ms","start":"2026-05-05T17:44:19.916702Z","end":"2026-05-05T17:44:20.74919Z","steps":["trace[1638184512] 'agreement among raft nodes before linearized reading' (duration: 831.876229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:20.749244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:19.91668Z","time spent":"832.555452ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-05T17:44:20.749318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.060711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-05T17:44:20.749362Z","caller":"traceutil/trace.go:171","msg":"trace[1707780129] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24558; }","duration":"886.155463ms","start":"2026-05-05T17:44:19.863193Z","end":"2026-05-05T17:44:20.749349Z","steps":["trace[1707780129] 'agreement among raft nodes before linearized reading' (duration: 886.054441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:20.74939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:19.863162Z","time spent":"886.221783ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T17:44:20.749541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.954833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:44:20.749582Z","caller":"traceutil/trace.go:171","msg":"trace[2115208905] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24558; }","duration":"435.051165ms","start":"2026-05-05T17:44:20.314521Z","end":"2026-05-05T17:44:20.749572Z","steps":["trace[2115208905] 'agreement among raft nodes before linearized reading' (duration: 434.987994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:20.749614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:20.31447Z","time spent":"435.138517ms","remote":"127.0.0.1:50684","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-05-05T17:44:20.749783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.016087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:44:20.749838Z","caller":"traceutil/trace.go:171","msg":"trace[1784742773] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:24558; }","duration":"126.106198ms","start":"2026-05-05T17:44:20.623707Z","end":"2026-05-05T17:44:20.749813Z","steps":["trace[1784742773] 'agreement among raft nodes before linearized reading' (duration: 126.041727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:26.8538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.192858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-62fe0fad-4a22-4b65-8635-6de8ea9a85d4\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-05-05T17:44:26.853901Z","caller":"traceutil/trace.go:171","msg":"trace[2048048399] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-62fe0fad-4a22-4b65-8635-6de8ea9a85d4; range_end:; response_count:1; response_revision:24660; }","duration":"163.343ms","start":"2026-05-05T17:44:26.690535Z","end":"2026-05-05T17:44:26.853878Z","steps":["trace[2048048399] 'range keys from in-memory index tree' (duration: 162.994544ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:26.968481Z","caller":"traceutil/trace.go:171","msg":"trace[1246355345] linearizableReadLoop","detail":"{readStateIndex:25461; appliedIndex:25460; }","duration":"187.503996ms","start":"2026-05-05T17:44:26.78095Z","end":"2026-05-05T17:44:26.968454Z","steps":["trace[1246355345] 'read index received' (duration: 187.225461ms)","trace[1246355345] 'applied index is now lower than readState.Index' (duration: 277.415µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:44:26.968581Z","caller":"traceutil/trace.go:171","msg":"trace[112394963] transaction","detail":"{read_only:false; response_revision:24661; number_of_response:1; }","duration":"199.420551ms","start":"2026-05-05T17:44:26.769125Z","end":"2026-05-05T17:44:26.968546Z","steps":["trace[112394963] 'process raft request' (duration: 199.173116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:26.968733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.770141ms","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-05-05T17:44:26.968805Z","caller":"traceutil/trace.go:171","msg":"trace[776613417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24661; }","duration":"187.897923ms","start":"2026-05-05T17:44:26.780892Z","end":"2026-05-05T17:44:26.96879Z","steps":["trace[776613417] 'agreement among raft nodes before linearized reading' (duration: 187.669309ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:26.988268Z","caller":"traceutil/trace.go:171","msg":"trace[1314443981] transaction","detail":"{read_only:false; response_revision:24662; number_of_response:1; }","duration":"195.441557ms","start":"2026-05-05T17:44:26.792796Z","end":"2026-05-05T17:44:26.988237Z","steps":["trace[1314443981] 'process raft request' (duration: 195.225233ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:26.988516Z","caller":"traceutil/trace.go:171","msg":"trace[1756826509] transaction","detail":"{read_only:false; response_revision:24663; number_of_response:1; }","duration":"194.912275ms","start":"2026-05-05T17:44:26.793572Z","end":"2026-05-05T17:44:26.988484Z","steps":["trace[1756826509] 'process raft request' (duration: 194.59585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:26.988555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.658311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-05-05T17:44:26.988554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.134766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-05T17:44:26.988617Z","caller":"traceutil/trace.go:171","msg":"trace[180594898] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24663; }","duration":"207.245619ms","start":"2026-05-05T17:44:26.781358Z","end":"2026-05-05T17:44:26.988604Z","steps":["trace[180594898] 'agreement among raft nodes before linearized reading' (duration: 207.028285ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:26.988639Z","caller":"traceutil/trace.go:171","msg":"trace[1524774881] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24663; }","duration":"203.735933ms","start":"2026-05-05T17:44:26.784862Z","end":"2026-05-05T17:44:26.988598Z","steps":["trace[1524774881] 'agreement among raft nodes before linearized reading' (duration: 203.59777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:26.988646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.829502ms","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-05-05T17:44:26.988738Z","caller":"traceutil/trace.go:171","msg":"trace[1271081742] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24663; }","duration":"169.969335ms","start":"2026-05-05T17:44:26.818746Z","end":"2026-05-05T17:44:26.988715Z","steps":["trace[1271081742] 'agreement among raft nodes before linearized reading' (duration: 169.653619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:26.988934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.819175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-05T17:44:26.988988Z","caller":"traceutil/trace.go:171","msg":"trace[682818554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24663; }","duration":"202.898626ms","start":"2026-05-05T17:44:26.786068Z","end":"2026-05-05T17:44:26.988966Z","steps":["trace[682818554] 'agreement among raft nodes before linearized reading' (duration: 202.689212ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:27.135584Z","caller":"traceutil/trace.go:171","msg":"trace[238231555] linearizableReadLoop","detail":"{readStateIndex:25464; appliedIndex:25463; }","duration":"142.950416ms","start":"2026-05-05T17:44:26.992601Z","end":"2026-05-05T17:44:27.135551Z","steps":["trace[238231555] 'read index received' (duration: 84.372501ms)","trace[238231555] 'applied index is now lower than readState.Index' (duration: 58.576585ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:44:27.135756Z","caller":"traceutil/trace.go:171","msg":"trace[418355050] transaction","detail":"{read_only:false; response_revision:24664; number_of_response:1; }","duration":"161.300192ms","start":"2026-05-05T17:44:26.974405Z","end":"2026-05-05T17:44:27.135705Z","steps":["trace[418355050] 'process raft request' (duration: 102.474923ms)","trace[418355050] 'compare' (duration: 58.504344ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:44:27.135884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.246802ms","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-05-05T17:44:27.135928Z","caller":"traceutil/trace.go:171","msg":"trace[646030951] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24664; }","duration":"143.336003ms","start":"2026-05-05T17:44:26.992581Z","end":"2026-05-05T17:44:27.135917Z","steps":["trace[646030951] 'agreement among raft nodes before linearized reading' (duration: 143.13053ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:27.149736Z","caller":"traceutil/trace.go:171","msg":"trace[1897318334] transaction","detail":"{read_only:false; response_revision:24665; number_of_response:1; }","duration":"156.874779ms","start":"2026-05-05T17:44:26.992835Z","end":"2026-05-05T17:44:27.14971Z","steps":["trace[1897318334] 'process raft request' (duration: 156.628954ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:27.149741Z","caller":"traceutil/trace.go:171","msg":"trace[1363578772] transaction","detail":"{read_only:false; response_revision:24666; number_of_response:1; }","duration":"155.984752ms","start":"2026-05-05T17:44:26.993742Z","end":"2026-05-05T17:44:27.149727Z","steps":["trace[1363578772] 'process raft request' (duration: 155.88084ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:27.149905Z","caller":"traceutil/trace.go:171","msg":"trace[68712337] transaction","detail":"{read_only:false; response_revision:24667; number_of_response:1; }","duration":"155.566925ms","start":"2026-05-05T17:44:26.994327Z","end":"2026-05-05T17:44:27.149894Z","steps":["trace[68712337] 'process raft request' (duration: 155.357511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:27.150112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.174708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-62fe0fad-4a22-4b65-8635-6de8ea9a85d4\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-05-05T17:44:27.150173Z","caller":"traceutil/trace.go:171","msg":"trace[1444626933] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-62fe0fad-4a22-4b65-8635-6de8ea9a85d4; range_end:; response_count:1; response_revision:24667; }","duration":"137.243879ms","start":"2026-05-05T17:44:27.012915Z","end":"2026-05-05T17:44:27.150159Z","steps":["trace[1444626933] 'agreement among raft nodes before linearized reading' (duration: 137.109756ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:27.442099Z","caller":"traceutil/trace.go:171","msg":"trace[2032047596] transaction","detail":"{read_only:false; response_revision:24668; number_of_response:1; }","duration":"286.212168ms","start":"2026-05-05T17:44:27.155856Z","end":"2026-05-05T17:44:27.442068Z","steps":["trace[2032047596] 'process raft request' (duration: 286.036095ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:27.72647Z","caller":"traceutil/trace.go:171","msg":"trace[1734066037] transaction","detail":"{read_only:false; response_revision:24669; number_of_response:1; }","duration":"566.074096ms","start":"2026-05-05T17:44:27.160368Z","end":"2026-05-05T17:44:27.726442Z","steps":["trace[1734066037] 'process raft request' (duration: 565.843662ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:27.726598Z","caller":"traceutil/trace.go:171","msg":"trace[1866367060] linearizableReadLoop","detail":"{readStateIndex:25470; appliedIndex:25467; }","duration":"509.247074ms","start":"2026-05-05T17:44:27.217331Z","end":"2026-05-05T17:44:27.726578Z","steps":["trace[1866367060] 'read index received' (duration: 224.635406ms)","trace[1866367060] 'applied index is now lower than readState.Index' (duration: 284.609888ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:44:27.726644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:27.160341Z","time spent":"566.213419ms","remote":"127.0.0.1:50840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3745,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:44:27.726762Z","caller":"traceutil/trace.go:171","msg":"trace[952651579] transaction","detail":"{read_only:false; response_revision:24670; number_of_response:1; }","duration":"546.251262ms","start":"2026-05-05T17:44:27.180472Z","end":"2026-05-05T17:44:27.726723Z","steps":["trace[952651579] 'process raft request' (duration: 545.909176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:27.726807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.448229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:44:27.726928Z","caller":"traceutil/trace.go:171","msg":"trace[22888982] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24670; }","duration":"509.611521ms","start":"2026-05-05T17:44:27.217287Z","end":"2026-05-05T17:44:27.726898Z","steps":["trace[22888982] 'agreement among raft nodes before linearized reading' (duration: 509.418148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:27.72699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:27.217272Z","time spent":"509.706173ms","remote":"127.0.0.1:51162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-05T17:44:27.727086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:27.180453Z","time spent":"546.414016ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:44:27.875791Z","caller":"traceutil/trace.go:171","msg":"trace[162187754] transaction","detail":"{read_only:false; response_revision:24672; number_of_response:1; }","duration":"238.05956ms","start":"2026-05-05T17:44:27.637714Z","end":"2026-05-05T17:44:27.875774Z","steps":["trace[162187754] 'process raft request' (duration: 237.949778ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:27.875788Z","caller":"traceutil/trace.go:171","msg":"trace[200530618] transaction","detail":"{read_only:false; response_revision:24671; number_of_response:1; }","duration":"239.556099ms","start":"2026-05-05T17:44:27.6362Z","end":"2026-05-05T17:44:27.875756Z","steps":["trace[200530618] 'process raft request' (duration: 239.256723ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:27.875969Z","caller":"traceutil/trace.go:171","msg":"trace[783382503] linearizableReadLoop","detail":"{readStateIndex:25473; appliedIndex:25470; }","duration":"149.308256ms","start":"2026-05-05T17:44:27.726638Z","end":"2026-05-05T17:44:27.875946Z","steps":["trace[783382503] 'read index received' (duration: 148.724665ms)","trace[783382503] 'applied index is now lower than readState.Index' (duration: 581.991µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:44:27.876203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.100877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-05T17:44:27.876248Z","caller":"traceutil/trace.go:171","msg":"trace[973502836] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24673; }","duration":"232.210849ms","start":"2026-05-05T17:44:27.644026Z","end":"2026-05-05T17:44:27.876237Z","steps":["trace[973502836] 'agreement among raft nodes before linearized reading' (duration: 232.024566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:27.876325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.658387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-05-05T17:44:27.876369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.131698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:44:27.876396Z","caller":"traceutil/trace.go:171","msg":"trace[826183714] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:24673; }","duration":"478.207789ms","start":"2026-05-05T17:44:27.39818Z","end":"2026-05-05T17:44:27.876387Z","steps":["trace[826183714] 'agreement among raft nodes before linearized reading' (duration: 478.152478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:27.876426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:27.398154Z","time spent":"478.263061ms","remote":"127.0.0.1:51178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-05-05T17:44:27.87645Z","caller":"traceutil/trace.go:171","msg":"trace[718892984] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:24673; }","duration":"189.77038ms","start":"2026-05-05T17:44:27.686587Z","end":"2026-05-05T17:44:27.876357Z","steps":["trace[718892984] 'agreement among raft nodes before linearized reading' (duration: 189.453344ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:27.876706Z","caller":"traceutil/trace.go:171","msg":"trace[227739448] transaction","detail":"{read_only:false; response_revision:24673; number_of_response:1; }","duration":"234.482112ms","start":"2026-05-05T17:44:27.642196Z","end":"2026-05-05T17:44:27.876678Z","steps":["trace[227739448] 'process raft request' (duration: 233.517654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:27.876685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.588285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:44:27.876846Z","caller":"traceutil/trace.go:171","msg":"trace[1071041606] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24673; }","duration":"145.878481ms","start":"2026-05-05T17:44:27.730941Z","end":"2026-05-05T17:44:27.87682Z","steps":["trace[1071041606] 'agreement among raft nodes before linearized reading' (duration: 145.466754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:27.876919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.762733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:44:27.877044Z","caller":"traceutil/trace.go:171","msg":"trace[1181833673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24673; }","duration":"562.957857ms","start":"2026-05-05T17:44:27.314059Z","end":"2026-05-05T17:44:27.877017Z","steps":["trace[1181833673] 'agreement among raft nodes before linearized reading' (duration: 562.701142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:27.8771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:27.314029Z","time spent":"563.063139ms","remote":"127.0.0.1:50684","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-05-05T17:44:28.044372Z","caller":"traceutil/trace.go:171","msg":"trace[1372777768] linearizableReadLoop","detail":"{readStateIndex:25474; appliedIndex:25473; }","duration":"161.797832ms","start":"2026-05-05T17:44:27.882541Z","end":"2026-05-05T17:44:28.044339Z","steps":["trace[1372777768] 'read index received' (duration: 115.231343ms)","trace[1372777768] 'applied index is now lower than readState.Index' (duration: 46.565029ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:44:28.044413Z","caller":"traceutil/trace.go:171","msg":"trace[987925424] transaction","detail":"{read_only:false; response_revision:24674; number_of_response:1; }","duration":"162.965314ms","start":"2026-05-05T17:44:27.881426Z","end":"2026-05-05T17:44:28.044391Z","steps":["trace[987925424] 'process raft request' (duration: 116.762833ms)","trace[987925424] 'compare' (duration: 45.982827ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:44:28.044759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.173599ms","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-05-05T17:44:28.044868Z","caller":"traceutil/trace.go:171","msg":"trace[1494730595] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24674; }","duration":"162.330862ms","start":"2026-05-05T17:44:27.88251Z","end":"2026-05-05T17:44:28.044841Z","steps":["trace[1494730595] 'agreement among raft nodes before linearized reading' (duration: 161.955415ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:30.336419Z","caller":"traceutil/trace.go:171","msg":"trace[810970073] transaction","detail":"{read_only:false; response_revision:24690; number_of_response:1; }","duration":"100.495935ms","start":"2026-05-05T17:44:30.235887Z","end":"2026-05-05T17:44:30.336382Z","steps":["trace[810970073] 'process raft request' (duration: 100.22117ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:34.301032Z","caller":"traceutil/trace.go:171","msg":"trace[1474718648] linearizableReadLoop","detail":"{readStateIndex:25537; appliedIndex:25535; }","duration":"230.04621ms","start":"2026-05-05T17:44:34.070961Z","end":"2026-05-05T17:44:34.301007Z","steps":["trace[1474718648] 'read index received' (duration: 229.740194ms)","trace[1474718648] 'applied index is now lower than readState.Index' (duration: 301.686µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:44:34.301056Z","caller":"traceutil/trace.go:171","msg":"trace[853488363] transaction","detail":"{read_only:false; response_revision:24734; number_of_response:1; }","duration":"402.606323ms","start":"2026-05-05T17:44:33.898411Z","end":"2026-05-05T17:44:34.301017Z","steps":["trace[853488363] 'process raft request' (duration: 402.384688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:34.301237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:33.898394Z","time spent":"402.746956ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:44:34.3013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.212114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10358"} {"level":"info","ts":"2026-05-05T17:44:34.301056Z","caller":"traceutil/trace.go:171","msg":"trace[712995425] transaction","detail":"{read_only:false; response_revision:24735; number_of_response:1; }","duration":"401.559814ms","start":"2026-05-05T17:44:33.899471Z","end":"2026-05-05T17:44:34.301031Z","steps":["trace[712995425] 'process raft request' (duration: 401.478971ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:34.301346Z","caller":"traceutil/trace.go:171","msg":"trace[490953867] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24735; }","duration":"115.320556ms","start":"2026-05-05T17:44:34.186012Z","end":"2026-05-05T17:44:34.301333Z","steps":["trace[490953867] 'agreement among raft nodes before linearized reading' (duration: 115.164843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:34.301388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.391717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T17:44:34.301461Z","caller":"traceutil/trace.go:171","msg":"trace[1445240158] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24735; }","duration":"230.482608ms","start":"2026-05-05T17:44:34.070955Z","end":"2026-05-05T17:44:34.301438Z","steps":["trace[1445240158] 'agreement among raft nodes before linearized reading' (duration: 230.182663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:34.301467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:44:33.899445Z","time spent":"401.921061ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:44:34.440004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.474716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T17:44:34.440136Z","caller":"traceutil/trace.go:171","msg":"trace[1511338143] linearizableReadLoop","detail":"{readStateIndex:25538; appliedIndex:25537; }","duration":"128.447563ms","start":"2026-05-05T17:44:34.311667Z","end":"2026-05-05T17:44:34.440115Z","steps":["trace[1511338143] 'read index received' (duration: 11.710061ms)","trace[1511338143] 'applied index is now lower than readState.Index' (duration: 116.735712ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:44:34.440214Z","caller":"traceutil/trace.go:171","msg":"trace[478145001] transaction","detail":"{read_only:false; response_revision:24736; number_of_response:1; }","duration":"130.41819ms","start":"2026-05-05T17:44:34.309784Z","end":"2026-05-05T17:44:34.440202Z","steps":["trace[478145001] 'process raft request' (duration: 13.644928ms)","trace[478145001] 'compare' (duration: 116.200881ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:44:34.440369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.687647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:44:34.440476Z","caller":"traceutil/trace.go:171","msg":"trace[821135477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24736; }","duration":"128.82561ms","start":"2026-05-05T17:44:34.311628Z","end":"2026-05-05T17:44:34.440454Z","steps":["trace[821135477] 'agreement among raft nodes before linearized reading' (duration: 128.656287ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:34.452584Z","caller":"traceutil/trace.go:171","msg":"trace[107930149] transaction","detail":"{read_only:false; response_revision:24737; number_of_response:1; }","duration":"138.255758ms","start":"2026-05-05T17:44:34.314307Z","end":"2026-05-05T17:44:34.452562Z","steps":["trace[107930149] 'process raft request' (duration: 138.141726ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:44:34.473877Z","caller":"traceutil/trace.go:171","msg":"trace[828501528] transaction","detail":"{read_only:false; response_revision:24738; number_of_response:1; }","duration":"125.17756ms","start":"2026-05-05T17:44:34.348668Z","end":"2026-05-05T17:44:34.473846Z","steps":["trace[828501528] 'process raft request' (duration: 124.981806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:44:34.473976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.694726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:44:34.47408Z","caller":"traceutil/trace.go:171","msg":"trace[1722687144] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:24738; }","duration":"120.864569ms","start":"2026-05-05T17:44:34.353189Z","end":"2026-05-05T17:44:34.474054Z","steps":["trace[1722687144] 'agreement among raft nodes before linearized reading' (duration: 120.693506ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:00.242952Z","caller":"traceutil/trace.go:171","msg":"trace[609150392] transaction","detail":"{read_only:false; response_revision:24952; number_of_response:1; }","duration":"152.948514ms","start":"2026-05-05T17:45:00.08997Z","end":"2026-05-05T17:45:00.242919Z","steps":["trace[609150392] 'process raft request' (duration: 152.742831ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:00.312929Z","caller":"traceutil/trace.go:171","msg":"trace[1484826513] transaction","detail":"{read_only:false; response_revision:24953; number_of_response:1; }","duration":"206.536835ms","start":"2026-05-05T17:45:00.106365Z","end":"2026-05-05T17:45:00.312902Z","steps":["trace[1484826513] 'process raft request' (duration: 204.570548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:03.740539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.472597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:45:03.74062Z","caller":"traceutil/trace.go:171","msg":"trace[2070806296] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:24993; }","duration":"133.61294ms","start":"2026-05-05T17:45:03.606989Z","end":"2026-05-05T17:45:03.740602Z","steps":["trace[2070806296] 'count revisions from in-memory index tree' (duration: 133.400076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:03.741089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.941539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T17:45:03.741177Z","caller":"traceutil/trace.go:171","msg":"trace[1996213650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24993; }","duration":"123.075302ms","start":"2026-05-05T17:45:03.618074Z","end":"2026-05-05T17:45:03.74115Z","steps":["trace[1996213650] 'range keys from in-memory index tree' (duration: 122.769706ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:03.877609Z","caller":"traceutil/trace.go:171","msg":"trace[1347364907] transaction","detail":"{read_only:false; response_revision:24994; number_of_response:1; }","duration":"131.647613ms","start":"2026-05-05T17:45:03.745925Z","end":"2026-05-05T17:45:03.877573Z","steps":["trace[1347364907] 'process raft request' (duration: 131.457609ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:03.941262Z","caller":"traceutil/trace.go:171","msg":"trace[1047234637] transaction","detail":"{read_only:false; response_revision:24995; number_of_response:1; }","duration":"116.841454ms","start":"2026-05-05T17:45:03.824393Z","end":"2026-05-05T17:45:03.941235Z","steps":["trace[1047234637] 'process raft request' (duration: 116.695631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:04.319462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.889669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T17:45:04.319465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.147564ms","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-05-05T17:45:04.319553Z","caller":"traceutil/trace.go:171","msg":"trace[2112814058] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:24995; }","duration":"253.048342ms","start":"2026-05-05T17:45:04.066485Z","end":"2026-05-05T17:45:04.319533Z","steps":["trace[2112814058] 'count revisions from in-memory index tree' (duration: 252.774347ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:04.319573Z","caller":"traceutil/trace.go:171","msg":"trace[2071067389] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24995; }","duration":"437.266067ms","start":"2026-05-05T17:45:03.882281Z","end":"2026-05-05T17:45:04.319547Z","steps":["trace[2071067389] 'agreement among raft nodes before linearized reading' (duration: 59.237967ms)","trace[2071067389] 'range keys from in-memory index tree' (duration: 377.773085ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:45:04.319617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:03.882211Z","time spent":"437.396919ms","remote":"127.0.0.1:50840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-05T17:45:04.569215Z","caller":"traceutil/trace.go:171","msg":"trace[1615459283] linearizableReadLoop","detail":"{readStateIndex:25806; appliedIndex:25805; }","duration":"150.300164ms","start":"2026-05-05T17:45:04.418888Z","end":"2026-05-05T17:45:04.569188Z","steps":["trace[1615459283] 'read index received' (duration: 84.671606ms)","trace[1615459283] 'applied index is now lower than readState.Index' (duration: 65.627388ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:45:04.569342Z","caller":"traceutil/trace.go:171","msg":"trace[2137392892] transaction","detail":"{read_only:false; response_revision:24997; number_of_response:1; }","duration":"152.174579ms","start":"2026-05-05T17:45:04.417155Z","end":"2026-05-05T17:45:04.569329Z","steps":["trace[2137392892] 'process raft request' (duration: 86.494421ms)","trace[2137392892] 'compare' (duration: 65.274621ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:45:04.569695Z","caller":"traceutil/trace.go:171","msg":"trace[2097741231] transaction","detail":"{read_only:false; response_revision:24998; number_of_response:1; }","duration":"150.06536ms","start":"2026-05-05T17:45:04.419389Z","end":"2026-05-05T17:45:04.569454Z","steps":["trace[2097741231] 'process raft request' (duration: 149.761194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:04.569722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.809863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:45:04.569836Z","caller":"traceutil/trace.go:171","msg":"trace[96516959] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24998; }","duration":"150.934986ms","start":"2026-05-05T17:45:04.418879Z","end":"2026-05-05T17:45:04.569814Z","steps":["trace[96516959] 'agreement among raft nodes before linearized reading' (duration: 150.414886ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:04.916597Z","caller":"traceutil/trace.go:171","msg":"trace[1594204608] linearizableReadLoop","detail":"{readStateIndex:25808; appliedIndex:25807; }","duration":"338.31989ms","start":"2026-05-05T17:45:04.578247Z","end":"2026-05-05T17:45:04.916567Z","steps":["trace[1594204608] 'read index received' (duration: 276.940232ms)","trace[1594204608] 'applied index is now lower than readState.Index' (duration: 61.378218ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:45:04.916715Z","caller":"traceutil/trace.go:171","msg":"trace[1125421033] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"338.670058ms","start":"2026-05-05T17:45:04.578034Z","end":"2026-05-05T17:45:04.916704Z","steps":["trace[1125421033] 'process raft request' (duration: 277.120407ms)","trace[1125421033] 'compare' (duration: 61.153344ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:45:04.916804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:04.578022Z","time spent":"338.713029ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:45:04.916906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.056367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-05T17:45:04.916984Z","caller":"traceutil/trace.go:171","msg":"trace[25295283] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24999; }","duration":"319.19725ms","start":"2026-05-05T17:45:04.597769Z","end":"2026-05-05T17:45:04.916967Z","steps":["trace[25295283] 'agreement among raft nodes before linearized reading' (duration: 319.000566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:04.917021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:04.597746Z","time spent":"319.268201ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T17:45:04.917169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.928602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18613"} {"level":"info","ts":"2026-05-05T17:45:04.91721Z","caller":"traceutil/trace.go:171","msg":"trace[1615324417] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24999; }","duration":"339.020304ms","start":"2026-05-05T17:45:04.57818Z","end":"2026-05-05T17:45:04.9172Z","steps":["trace[1615324417] 'agreement among raft nodes before linearized reading' (duration: 338.86605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:04.917236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:04.578164Z","time spent":"339.063995ms","remote":"127.0.0.1:50830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18635,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-05T17:45:04.917181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.831979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:45:04.917287Z","caller":"traceutil/trace.go:171","msg":"trace[1491655119] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24999; }","duration":"191.935281ms","start":"2026-05-05T17:45:04.725341Z","end":"2026-05-05T17:45:04.917276Z","steps":["trace[1491655119] 'agreement among raft nodes before linearized reading' (duration: 191.812488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:05.190615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.84013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T17:45:05.190748Z","caller":"traceutil/trace.go:171","msg":"trace[459152332] linearizableReadLoop","detail":"{readStateIndex:25809; appliedIndex:25808; }","duration":"266.273642ms","start":"2026-05-05T17:45:04.924456Z","end":"2026-05-05T17:45:05.19073Z","steps":["trace[459152332] 'read index received' (duration: 137.221688ms)","trace[459152332] 'applied index is now lower than readState.Index' (duration: 129.024674ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:45:05.190799Z","caller":"traceutil/trace.go:171","msg":"trace[544645094] transaction","detail":"{read_only:false; response_revision:25000; number_of_response:1; }","duration":"268.437743ms","start":"2026-05-05T17:45:04.922321Z","end":"2026-05-05T17:45:05.190759Z","steps":["trace[544645094] 'process raft request' (duration: 139.359838ms)","trace[544645094] 'compare' (duration: 128.711498ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:45:05.190852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.403984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:45:05.19089Z","caller":"traceutil/trace.go:171","msg":"trace[1032035867] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25000; }","duration":"266.479796ms","start":"2026-05-05T17:45:04.924398Z","end":"2026-05-05T17:45:05.190878Z","steps":["trace[1032035867] 'agreement among raft nodes before linearized reading' (duration: 266.406605ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:05.346415Z","caller":"traceutil/trace.go:171","msg":"trace[525521630] transaction","detail":"{read_only:false; response_revision:25002; number_of_response:1; }","duration":"209.939459ms","start":"2026-05-05T17:45:05.136448Z","end":"2026-05-05T17:45:05.346388Z","steps":["trace[525521630] 'process raft request' (duration: 202.538009ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:05.346529Z","caller":"traceutil/trace.go:171","msg":"trace[1927518194] linearizableReadLoop","detail":"{readStateIndex:25812; appliedIndex:25810; }","duration":"155.735837ms","start":"2026-05-05T17:45:05.190775Z","end":"2026-05-05T17:45:05.346511Z","steps":["trace[1927518194] 'read index received' (duration: 10.857965ms)","trace[1927518194] 'applied index is now lower than readState.Index' (duration: 144.876992ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:45:05.346677Z","caller":"traceutil/trace.go:171","msg":"trace[2001253689] transaction","detail":"{read_only:false; response_revision:25003; number_of_response:1; }","duration":"206.198099ms","start":"2026-05-05T17:45:05.14047Z","end":"2026-05-05T17:45:05.346668Z","steps":["trace[2001253689] 'process raft request' (duration: 205.861253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:05.346847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.724811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:45:05.346876Z","caller":"traceutil/trace.go:171","msg":"trace[2106025632] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25003; }","duration":"230.832543ms","start":"2026-05-05T17:45:05.116035Z","end":"2026-05-05T17:45:05.346867Z","steps":["trace[2106025632] 'agreement among raft nodes before linearized reading' (duration: 230.762762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:05.34705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.056584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-05-05T17:45:05.347073Z","caller":"traceutil/trace.go:171","msg":"trace[679980025] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25003; }","duration":"418.109706ms","start":"2026-05-05T17:45:04.928956Z","end":"2026-05-05T17:45:05.347066Z","steps":["trace[679980025] 'agreement among raft nodes before linearized reading' (duration: 418.033624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:05.347095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:04.928941Z","time spent":"418.147306ms","remote":"127.0.0.1:48024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5706,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-05T17:45:05.347228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.984601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:45:05.347304Z","caller":"traceutil/trace.go:171","msg":"trace[265445389] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25003; }","duration":"148.122454ms","start":"2026-05-05T17:45:05.199165Z","end":"2026-05-05T17:45:05.347287Z","steps":["trace[265445389] 'agreement among raft nodes before linearized reading' (duration: 147.981851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:05.347685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.860135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T17:45:05.34772Z","caller":"traceutil/trace.go:171","msg":"trace[625118109] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:25003; }","duration":"124.937586ms","start":"2026-05-05T17:45:05.222774Z","end":"2026-05-05T17:45:05.347712Z","steps":["trace[625118109] 'agreement among raft nodes before linearized reading' (duration: 124.854244ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:05.631761Z","caller":"traceutil/trace.go:171","msg":"trace[954832493] transaction","detail":"{read_only:false; response_revision:25004; number_of_response:1; }","duration":"280.670695ms","start":"2026-05-05T17:45:05.351062Z","end":"2026-05-05T17:45:05.631733Z","steps":["trace[954832493] 'process raft request' (duration: 280.43307ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:05.664635Z","caller":"traceutil/trace.go:171","msg":"trace[147732359] transaction","detail":"{read_only:false; response_revision:25005; number_of_response:1; }","duration":"313.392101ms","start":"2026-05-05T17:45:05.351198Z","end":"2026-05-05T17:45:05.66459Z","steps":["trace[147732359] 'process raft request' (duration: 313.087405ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:05.664695Z","caller":"traceutil/trace.go:171","msg":"trace[1092938385] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"310.950195ms","start":"2026-05-05T17:45:05.353713Z","end":"2026-05-05T17:45:05.664663Z","steps":["trace[1092938385] 'process raft request' (duration: 310.767042ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:05.66483Z","caller":"traceutil/trace.go:171","msg":"trace[1533981745] linearizableReadLoop","detail":"{readStateIndex:25815; appliedIndex:25813; }","duration":"310.104798ms","start":"2026-05-05T17:45:05.354698Z","end":"2026-05-05T17:45:05.664803Z","steps":["trace[1533981745] 'read index received' (duration: 277.094766ms)","trace[1533981745] 'applied index is now lower than readState.Index' (duration: 33.008682ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:45:05.664846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:05.351177Z","time spent":"313.561754ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:45:05.664964Z","caller":"traceutil/trace.go:171","msg":"trace[1558630117] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"308.785803ms","start":"2026-05-05T17:45:05.356144Z","end":"2026-05-05T17:45:05.66493Z","steps":["trace[1558630117] 'process raft request' (duration: 308.419616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:05.66498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:05.353702Z","time spent":"311.155069ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:45:05.665061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.365744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T17:45:05.665083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:05.356113Z","time spent":"308.879675ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:45:05.665108Z","caller":"traceutil/trace.go:171","msg":"trace[1936648008] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25007; }","duration":"310.427675ms","start":"2026-05-05T17:45:05.354665Z","end":"2026-05-05T17:45:05.665093Z","steps":["trace[1936648008] 'agreement among raft nodes before linearized reading' (duration: 310.339042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:05.665078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.150556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T17:45:05.665153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:05.354658Z","time spent":"310.486516ms","remote":"127.0.0.1:50938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-05-05T17:45:05.665179Z","caller":"traceutil/trace.go:171","msg":"trace[617258660] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25007; }","duration":"128.32967ms","start":"2026-05-05T17:45:05.536824Z","end":"2026-05-05T17:45:05.665154Z","steps":["trace[617258660] 'agreement among raft nodes before linearized reading' (duration: 128.127786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:06.699226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.874583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T17:45:06.699364Z","caller":"traceutil/trace.go:171","msg":"trace[662692901] linearizableReadLoop","detail":"{readStateIndex:25818; appliedIndex:25817; }","duration":"700.335717ms","start":"2026-05-05T17:45:05.999008Z","end":"2026-05-05T17:45:06.699344Z","steps":["trace[662692901] 'read index received' (duration: 161.195789ms)","trace[662692901] 'applied index is now lower than readState.Index' (duration: 539.138118ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:45:06.699403Z","caller":"traceutil/trace.go:171","msg":"trace[45395580] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"808.755623ms","start":"2026-05-05T17:45:05.89061Z","end":"2026-05-05T17:45:06.699366Z","steps":["trace[45395580] 'process raft request' (duration: 269.562674ms)","trace[45395580] 'compare' (duration: 537.847743ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:45:06.699489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.477411ms","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-05-05T17:45:06.699541Z","caller":"traceutil/trace.go:171","msg":"trace[1138613804] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:25009; }","duration":"700.568493ms","start":"2026-05-05T17:45:05.998952Z","end":"2026-05-05T17:45:06.69952Z","steps":["trace[1138613804] 'agreement among raft nodes before linearized reading' (duration: 700.476011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:06.699596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:05.998897Z","time spent":"700.683755ms","remote":"127.0.0.1:42630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"warn","ts":"2026-05-05T17:45:06.6996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:05.890596Z","time spent":"808.898725ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:45:06.76026Z","caller":"traceutil/trace.go:171","msg":"trace[1345146409] transaction","detail":"{read_only:false; response_revision:25010; number_of_response:1; }","duration":"404.675171ms","start":"2026-05-05T17:45:06.355552Z","end":"2026-05-05T17:45:06.760227Z","steps":["trace[1345146409] 'process raft request' (duration: 404.516088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:06.760464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:06.355526Z","time spent":"404.825685ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:45:07.240904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T17:45:07.241035Z","caller":"traceutil/trace.go:171","msg":"trace[1780818756] transaction","detail":"{read_only:false; response_revision:25011; number_of_response:1; }","duration":"662.957453ms","start":"2026-05-05T17:45:06.578061Z","end":"2026-05-05T17:45:07.241019Z","steps":["trace[1780818756] 'process raft request' (duration: 396.102221ms)","trace[1780818756] 'compare' (duration: 266.567067ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:45:07.241078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:06.578034Z","time spent":"663.027275ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:45:07.241594Z","caller":"traceutil/trace.go:171","msg":"trace[862550750] linearizableReadLoop","detail":"{readStateIndex:25821; appliedIndex:25818; }","duration":"542.168556ms","start":"2026-05-05T17:45:06.699394Z","end":"2026-05-05T17:45:07.241563Z","steps":["trace[862550750] 'read index received' (duration: 60.664764ms)","trace[862550750] 'applied index is now lower than readState.Index' (duration: 481.502261ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:45:07.242023Z","caller":"traceutil/trace.go:171","msg":"trace[1452295738] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"663.83228ms","start":"2026-05-05T17:45:06.578156Z","end":"2026-05-05T17:45:07.241989Z","steps":["trace[1452295738] 'process raft request' (duration: 662.829392ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:07.242146Z","caller":"traceutil/trace.go:171","msg":"trace[1842686970] transaction","detail":"{read_only:false; response_revision:25013; number_of_response:1; }","duration":"317.720212ms","start":"2026-05-05T17:45:06.924404Z","end":"2026-05-05T17:45:07.242124Z","steps":["trace[1842686970] 'process raft request' (duration: 317.001909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:07.242185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:06.578149Z","time spent":"663.952722ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:45:07.242207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.776204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-05T17:45:07.242271Z","caller":"traceutil/trace.go:171","msg":"trace[1320010404] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:25013; }","duration":"711.880397ms","start":"2026-05-05T17:45:06.530373Z","end":"2026-05-05T17:45:07.242253Z","steps":["trace[1320010404] 'agreement among raft nodes before linearized reading' (duration: 711.609101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:07.242303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:06.924375Z","time spent":"317.819263ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:45:07.242317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:06.53035Z","time spent":"711.952348ms","remote":"127.0.0.1:50728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"warn","ts":"2026-05-05T17:45:07.242344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.865914ms","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-05-05T17:45:07.242378Z","caller":"traceutil/trace.go:171","msg":"trace[647325769] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:25013; }","duration":"836.933554ms","start":"2026-05-05T17:45:06.405434Z","end":"2026-05-05T17:45:07.242368Z","steps":["trace[647325769] 'agreement among raft nodes before linearized reading' (duration: 836.583657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:07.242403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:06.405413Z","time spent":"836.982646ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1882,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" "} {"level":"warn","ts":"2026-05-05T17:45:07.242395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.799837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:45:07.242478Z","caller":"traceutil/trace.go:171","msg":"trace[131718438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25013; }","duration":"928.92558ms","start":"2026-05-05T17:45:06.313531Z","end":"2026-05-05T17:45:07.242457Z","steps":["trace[131718438] 'agreement among raft nodes before linearized reading' (duration: 928.784627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:07.242531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:06.313511Z","time spent":"929.010541ms","remote":"127.0.0.1:50684","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-05-05T17:45:07.244004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.345432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T17:45:07.244139Z","caller":"traceutil/trace.go:171","msg":"trace[744410756] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:25014; }","duration":"416.530936ms","start":"2026-05-05T17:45:06.827593Z","end":"2026-05-05T17:45:07.244124Z","steps":["trace[744410756] 'agreement among raft nodes before linearized reading' (duration: 416.310722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:07.244204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:06.827568Z","time spent":"416.623358ms","remote":"127.0.0.1:51162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":53,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-05-05T17:45:07.244078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.123613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-05-05T17:45:07.244308Z","caller":"traceutil/trace.go:171","msg":"trace[561035822] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25014; }","duration":"535.379147ms","start":"2026-05-05T17:45:06.708906Z","end":"2026-05-05T17:45:07.244285Z","steps":["trace[561035822] 'agreement among raft nodes before linearized reading' (duration: 535.117812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:07.244346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:06.708885Z","time spent":"535.449789ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-05-05T17:45:07.62317Z","caller":"traceutil/trace.go:171","msg":"trace[1504924112] transaction","detail":"{read_only:false; response_revision:25015; number_of_response:1; }","duration":"374.929111ms","start":"2026-05-05T17:45:07.248203Z","end":"2026-05-05T17:45:07.623132Z","steps":["trace[1504924112] 'process raft request' (duration: 374.476292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:07.62329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:07.248173Z","time spent":"375.057394ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:45:07.62336Z","caller":"traceutil/trace.go:171","msg":"trace[824263326] linearizableReadLoop","detail":"{readStateIndex:25826; appliedIndex:25824; }","duration":"358.356648ms","start":"2026-05-05T17:45:07.264974Z","end":"2026-05-05T17:45:07.623331Z","steps":["trace[824263326] 'read index received' (duration: 357.732776ms)","trace[824263326] 'applied index is now lower than readState.Index' (duration: 622.782µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:45:07.623403Z","caller":"traceutil/trace.go:171","msg":"trace[584445272] transaction","detail":"{read_only:false; response_revision:25016; number_of_response:1; }","duration":"363.564967ms","start":"2026-05-05T17:45:07.259821Z","end":"2026-05-05T17:45:07.623386Z","steps":["trace[584445272] 'process raft request' (duration: 363.249851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:07.62359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.901139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-00448f66-515a-4ac8-9873-40e6f18fa835\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-05T17:45:07.623634Z","caller":"traceutil/trace.go:171","msg":"trace[2065323346] range","detail":"{range_begin:/registry/persistentvolumes/pvc-00448f66-515a-4ac8-9873-40e6f18fa835; range_end:; response_count:1; response_revision:25016; }","duration":"376.985291ms","start":"2026-05-05T17:45:07.246636Z","end":"2026-05-05T17:45:07.623621Z","steps":["trace[2065323346] 'agreement among raft nodes before linearized reading' (duration: 376.827168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:07.623664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:07.246619Z","time spent":"377.038172ms","remote":"127.0.0.1:50792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1344,"request content":"key:\"/registry/persistentvolumes/pvc-00448f66-515a-4ac8-9873-40e6f18fa835\" "} {"level":"warn","ts":"2026-05-05T17:45:07.623596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:07.259789Z","time spent":"363.72642ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:45:07.635028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.313031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:45:07.635092Z","caller":"traceutil/trace.go:171","msg":"trace[1557342302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25019; }","duration":"320.447674ms","start":"2026-05-05T17:45:07.314636Z","end":"2026-05-05T17:45:07.635084Z","steps":["trace[1557342302] 'agreement among raft nodes before linearized reading' (duration: 320.334762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:07.635146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:07.314616Z","time spent":"320.523876ms","remote":"127.0.0.1:50684","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-05-05T17:45:07.635168Z","caller":"traceutil/trace.go:171","msg":"trace[1980915767] transaction","detail":"{read_only:false; response_revision:25018; number_of_response:1; }","duration":"277.084606ms","start":"2026-05-05T17:45:07.358057Z","end":"2026-05-05T17:45:07.635142Z","steps":["trace[1980915767] 'process raft request' (duration: 276.830171ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:07.635251Z","caller":"traceutil/trace.go:171","msg":"trace[1981004046] transaction","detail":"{read_only:false; response_revision:25019; number_of_response:1; }","duration":"277.047836ms","start":"2026-05-05T17:45:07.358143Z","end":"2026-05-05T17:45:07.635191Z","steps":["trace[1981004046] 'process raft request' (duration: 276.798841ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:07.635263Z","caller":"traceutil/trace.go:171","msg":"trace[615347315] transaction","detail":"{read_only:false; response_revision:25017; number_of_response:1; }","duration":"369.05681ms","start":"2026-05-05T17:45:07.266164Z","end":"2026-05-05T17:45:07.635221Z","steps":["trace[615347315] 'process raft request' (duration: 368.663323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:07.635467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:07.266139Z","time spent":"369.226614ms","remote":"127.0.0.1:50728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:45:08.084873Z","caller":"traceutil/trace.go:171","msg":"trace[1522582644] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"410.91166ms","start":"2026-05-05T17:45:07.673933Z","end":"2026-05-05T17:45:08.084845Z","steps":["trace[1522582644] 'process raft request' (duration: 410.681475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:08.085261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:07.673903Z","time spent":"411.052512ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:45:08.359773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.78592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T17:45:08.36008Z","caller":"traceutil/trace.go:171","msg":"trace[458265367] linearizableReadLoop","detail":"{readStateIndex:25833; appliedIndex:25830; }","duration":"623.152593ms","start":"2026-05-05T17:45:07.736906Z","end":"2026-05-05T17:45:08.360059Z","steps":["trace[458265367] 'read index received' (duration: 347.595295ms)","trace[458265367] 'applied index is now lower than readState.Index' (duration: 275.555758ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:45:08.360074Z","caller":"traceutil/trace.go:171","msg":"trace[1520480384] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"684.560992ms","start":"2026-05-05T17:45:07.675445Z","end":"2026-05-05T17:45:08.360006Z","steps":["trace[1520480384] 'process raft request' (duration: 574.445225ms)","trace[1520480384] 'compare' (duration: 109.452114ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:45:08.360151Z","caller":"traceutil/trace.go:171","msg":"trace[623046226] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"671.2265ms","start":"2026-05-05T17:45:07.688875Z","end":"2026-05-05T17:45:08.360102Z","steps":["trace[623046226] 'process raft request' (duration: 671.025335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:08.360324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:07.688849Z","time spent":"671.383452ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:45:08.360331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:07.675427Z","time spent":"684.752375ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:45:08.360413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.504789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-05T17:45:08.360447Z","caller":"traceutil/trace.go:171","msg":"trace[128120811] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:25024; }","duration":"623.590291ms","start":"2026-05-05T17:45:07.736848Z","end":"2026-05-05T17:45:08.360439Z","steps":["trace[128120811] 'agreement among raft nodes before linearized reading' (duration: 623.366617ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:08.360193Z","caller":"traceutil/trace.go:171","msg":"trace[311584135] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"569.917219ms","start":"2026-05-05T17:45:07.790261Z","end":"2026-05-05T17:45:08.360178Z","steps":["trace[311584135] 'process raft request' (duration: 569.741286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:08.360482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:07.736826Z","time spent":"623.647573ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":183,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-05T17:45:08.360476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.19852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:45:08.360565Z","caller":"traceutil/trace.go:171","msg":"trace[1524286319] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:25024; }","duration":"373.336952ms","start":"2026-05-05T17:45:07.987206Z","end":"2026-05-05T17:45:08.360543Z","steps":["trace[1524286319] 'agreement among raft nodes before linearized reading' (duration: 373.175989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:08.36061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:07.98719Z","time spent":"373.408803ms","remote":"127.0.0.1:53776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T17:45:08.360622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:07.790238Z","time spent":"570.263025ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:45:08.724504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.951153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-05T17:45:08.72459Z","caller":"traceutil/trace.go:171","msg":"trace[1198107601] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25024; }","duration":"207.091186ms","start":"2026-05-05T17:45:08.51748Z","end":"2026-05-05T17:45:08.724571Z","steps":["trace[1198107601] 'range keys from in-memory index tree' (duration: 206.767379ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:09.308788Z","caller":"traceutil/trace.go:171","msg":"trace[1671792197] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"542.703367ms","start":"2026-05-05T17:45:08.766062Z","end":"2026-05-05T17:45:09.308766Z","steps":["trace[1671792197] 'process raft request' (duration: 542.651396ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:09.308848Z","caller":"traceutil/trace.go:171","msg":"trace[603156789] linearizableReadLoop","detail":"{readStateIndex:25837; appliedIndex:25837; }","duration":"452.182908ms","start":"2026-05-05T17:45:08.856629Z","end":"2026-05-05T17:45:09.308811Z","steps":["trace[603156789] 'read index received' (duration: 452.155227ms)","trace[603156789] 'applied index is now lower than readState.Index' (duration: 15.65µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:45:09.308946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:08.766044Z","time spent":"542.819319ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:45:09.308996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.337091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T17:45:09.308846Z","caller":"traceutil/trace.go:171","msg":"trace[1668116928] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"576.373121ms","start":"2026-05-05T17:45:08.732431Z","end":"2026-05-05T17:45:09.308804Z","steps":["trace[1668116928] 'process raft request' (duration: 576.141917ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:09.309064Z","caller":"traceutil/trace.go:171","msg":"trace[1754560950] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25027; }","duration":"452.475283ms","start":"2026-05-05T17:45:08.856573Z","end":"2026-05-05T17:45:09.309048Z","steps":["trace[1754560950] 'agreement among raft nodes before linearized reading' (duration: 452.31513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:09.309122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:08.856554Z","time spent":"452.557445ms","remote":"127.0.0.1:47974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-05T17:45:09.309231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:08.732411Z","time spent":"576.717547ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:45:09.351354Z","caller":"traceutil/trace.go:171","msg":"trace[1079342226] transaction","detail":"{read_only:false; response_revision:25028; number_of_response:1; }","duration":"104.687774ms","start":"2026-05-05T17:45:09.246649Z","end":"2026-05-05T17:45:09.351337Z","steps":["trace[1079342226] 'process raft request' (duration: 104.544861ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:09.351695Z","caller":"traceutil/trace.go:171","msg":"trace[732294286] transaction","detail":"{read_only:false; response_revision:25029; number_of_response:1; }","duration":"103.524402ms","start":"2026-05-05T17:45:09.248149Z","end":"2026-05-05T17:45:09.351673Z","steps":["trace[732294286] 'process raft request' (duration: 103.146034ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:09.351802Z","caller":"traceutil/trace.go:171","msg":"trace[353065639] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"101.751519ms","start":"2026-05-05T17:45:09.250035Z","end":"2026-05-05T17:45:09.351786Z","steps":["trace[353065639] 'process raft request' (duration: 101.510134ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:11.976209Z","caller":"traceutil/trace.go:171","msg":"trace[1571864827] linearizableReadLoop","detail":"{readStateIndex:25862; appliedIndex:25859; }","duration":"315.726734ms","start":"2026-05-05T17:45:11.660454Z","end":"2026-05-05T17:45:11.976181Z","steps":["trace[1571864827] 'read index received' (duration: 82.72821ms)","trace[1571864827] 'applied index is now lower than readState.Index' (duration: 232.997554ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:45:11.976372Z","caller":"traceutil/trace.go:171","msg":"trace[1874283235] transaction","detail":"{read_only:false; response_revision:25051; number_of_response:1; }","duration":"326.325745ms","start":"2026-05-05T17:45:11.64999Z","end":"2026-05-05T17:45:11.976316Z","steps":["trace[1874283235] 'process raft request' (duration: 272.301356ms)","trace[1874283235] 'compare' (duration: 53.698133ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:45:11.976383Z","caller":"traceutil/trace.go:171","msg":"trace[1753746461] transaction","detail":"{read_only:false; response_revision:25052; number_of_response:1; }","duration":"325.686762ms","start":"2026-05-05T17:45:11.65065Z","end":"2026-05-05T17:45:11.976337Z","steps":["trace[1753746461] 'process raft request' (duration: 325.476658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:11.976482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.010199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-05T17:45:11.97656Z","caller":"traceutil/trace.go:171","msg":"trace[871486151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25052; }","duration":"316.131603ms","start":"2026-05-05T17:45:11.660414Z","end":"2026-05-05T17:45:11.976546Z","steps":["trace[871486151] 'agreement among raft nodes before linearized reading' (duration: 315.926188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:11.976585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:11.660397Z","time spent":"316.183944ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T17:45:11.976613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:11.65064Z","time spent":"325.845045ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:45:11.97662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:11.649968Z","time spent":"326.513058ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:45:12.323095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.332578ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T17:45:12.324031Z","caller":"traceutil/trace.go:171","msg":"trace[1545629096] linearizableReadLoop","detail":"{readStateIndex:25864; appliedIndex:25862; }","duration":"347.717609ms","start":"2026-05-05T17:45:11.976294Z","end":"2026-05-05T17:45:12.324011Z","steps":["trace[1545629096] 'read index received' (duration: 102.446293ms)","trace[1545629096] 'applied index is now lower than readState.Index' (duration: 245.270146ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:45:12.324185Z","caller":"traceutil/trace.go:171","msg":"trace[454247154] transaction","detail":"{read_only:false; response_revision:25053; number_of_response:1; }","duration":"402.120704ms","start":"2026-05-05T17:45:11.92205Z","end":"2026-05-05T17:45:12.324171Z","steps":["trace[454247154] 'process raft request' (duration: 401.185976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:12.324347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:11.922029Z","time spent":"402.220167ms","remote":"127.0.0.1:50890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5599,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:45:12.403953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.198124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:45:12.404036Z","caller":"traceutil/trace.go:171","msg":"trace[1297187631] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:25053; }","duration":"456.289916ms","start":"2026-05-05T17:45:11.947725Z","end":"2026-05-05T17:45:12.404015Z","steps":["trace[1297187631] 'agreement among raft nodes before linearized reading' (duration: 376.850648ms)","trace[1297187631] 'count revisions from in-memory index tree' (duration: 79.315916ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:45:12.404071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:11.947687Z","time spent":"456.376378ms","remote":"127.0.0.1:42608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true "} {"level":"warn","ts":"2026-05-05T17:45:12.404225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.051472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T17:45:12.404264Z","caller":"traceutil/trace.go:171","msg":"trace[1772114437] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:25053; }","duration":"613.132304ms","start":"2026-05-05T17:45:11.791123Z","end":"2026-05-05T17:45:12.404255Z","steps":["trace[1772114437] 'agreement among raft nodes before linearized reading' (duration: 533.465032ms)","trace[1772114437] 'count revisions from in-memory index tree' (duration: 79.613031ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:45:12.404286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:11.791055Z","time spent":"613.224636ms","remote":"127.0.0.1:32832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true "} {"level":"info","ts":"2026-05-05T17:45:12.404653Z","caller":"traceutil/trace.go:171","msg":"trace[754017079] transaction","detail":"{read_only:false; response_revision:25054; number_of_response:1; }","duration":"423.65719ms","start":"2026-05-05T17:45:11.980986Z","end":"2026-05-05T17:45:12.404643Z","steps":["trace[754017079] 'process raft request' (duration: 343.01796ms)","trace[754017079] 'compare' (duration: 79.725353ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:45:12.404728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:11.980967Z","time spent":"423.713011ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:45:12.40499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.311451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T17:45:12.405016Z","caller":"traceutil/trace.go:171","msg":"trace[990680166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25054; }","duration":"302.389662ms","start":"2026-05-05T17:45:12.10262Z","end":"2026-05-05T17:45:12.40501Z","steps":["trace[990680166] 'agreement among raft nodes before linearized reading' (duration: 302.29729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:45:12.405036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:45:12.102592Z","time spent":"302.437383ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T17:45:12.555822Z","caller":"traceutil/trace.go:171","msg":"trace[1054180203] transaction","detail":"{read_only:false; response_revision:25055; number_of_response:1; }","duration":"145.948733ms","start":"2026-05-05T17:45:12.409835Z","end":"2026-05-05T17:45:12.555784Z","steps":["trace[1054180203] 'process raft request' (duration: 113.889678ms)","trace[1054180203] 'compare' (duration: 31.534635ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:45:12.555953Z","caller":"traceutil/trace.go:171","msg":"trace[353983408] transaction","detail":"{read_only:false; response_revision:25056; number_of_response:1; }","duration":"145.390052ms","start":"2026-05-05T17:45:12.410534Z","end":"2026-05-05T17:45:12.555924Z","steps":["trace[353983408] 'process raft request' (duration: 145.174978ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:45:12.555996Z","caller":"traceutil/trace.go:171","msg":"trace[1906808115] transaction","detail":"{read_only:false; response_revision:25057; number_of_response:1; }","duration":"135.646337ms","start":"2026-05-05T17:45:12.420328Z","end":"2026-05-05T17:45:12.555975Z","steps":["trace[1906808115] 'process raft request' (duration: 135.512945ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:47:23.357372Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23476} {"level":"info","ts":"2026-05-05T17:47:23.496459Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23476,"took":"134.734141ms","hash":3238793668,"current-db-size-bytes":60473344,"current-db-size":"60 MB","current-db-size-in-use-bytes":36691968,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-05T17:47:23.496645Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3238793668,"revision":23476,"compact-revision":20230} {"level":"info","ts":"2026-05-05T17:47:38.622908Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006acf.wal"} {"level":"info","ts":"2026-05-05T17:52:23.36363Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26335} {"level":"info","ts":"2026-05-05T17:52:23.510846Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26335,"took":"142.474826ms","hash":4191801630,"current-db-size-bytes":60473344,"current-db-size":"60 MB","current-db-size-in-use-bytes":32837632,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-05T17:52:23.51093Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4191801630,"revision":26335,"compact-revision":23476} {"level":"info","ts":"2026-05-05T17:52:49.089921Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T17:52:49.095372Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-05T17:52:49.095506Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-05T17:57:23.370117Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28900} {"level":"info","ts":"2026-05-05T17:57:23.506238Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28900,"took":"131.4594ms","hash":2063946233,"current-db-size-bytes":60473344,"current-db-size":"60 MB","current-db-size-in-use-bytes":29917184,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-05T17:57:23.506292Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2063946233,"revision":28900,"compact-revision":26335} {"level":"info","ts":"2026-05-05T18:00:45.321744Z","caller":"traceutil/trace.go:171","msg":"trace[2124710629] transaction","detail":"{read_only:false; response_revision:32727; number_of_response:1; }","duration":"265.458156ms","start":"2026-05-05T18:00:45.056263Z","end":"2026-05-05T18:00:45.321721Z","steps":["trace[2124710629] 'process raft request' (duration: 263.906637ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:00:45.32203Z","caller":"traceutil/trace.go:171","msg":"trace[1620713534] transaction","detail":"{read_only:false; response_revision:32728; number_of_response:1; }","duration":"241.519725ms","start":"2026-05-05T18:00:45.080484Z","end":"2026-05-05T18:00:45.322004Z","steps":["trace[1620713534] 'process raft request' (duration: 241.174148ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:00:45.322395Z","caller":"traceutil/trace.go:171","msg":"trace[1659016391] linearizableReadLoop","detail":"{readStateIndex:33752; appliedIndex:33748; }","duration":"114.121862ms","start":"2026-05-05T18:00:45.20824Z","end":"2026-05-05T18:00:45.322361Z","steps":["trace[1659016391] 'read index received' (duration: 111.91116ms)","trace[1659016391] 'applied index is now lower than readState.Index' (duration: 2.209642ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:00:45.322541Z","caller":"traceutil/trace.go:171","msg":"trace[114134392] transaction","detail":"{read_only:false; response_revision:32729; number_of_response:1; }","duration":"240.972474ms","start":"2026-05-05T18:00:45.081556Z","end":"2026-05-05T18:00:45.322528Z","steps":["trace[114134392] 'process raft request' (duration: 240.386394ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:00:45.322801Z","caller":"traceutil/trace.go:171","msg":"trace[644246364] transaction","detail":"{read_only:false; response_revision:32730; number_of_response:1; }","duration":"239.279562ms","start":"2026-05-05T18:00:45.083514Z","end":"2026-05-05T18:00:45.322794Z","steps":["trace[644246364] 'process raft request' (duration: 238.743842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:00:45.322941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.683402ms","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-05-05T18:00:45.322975Z","caller":"traceutil/trace.go:171","msg":"trace[826619306] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:32730; }","duration":"114.772264ms","start":"2026-05-05T18:00:45.208192Z","end":"2026-05-05T18:00:45.322964Z","steps":["trace[826619306] 'agreement among raft nodes before linearized reading' (duration: 114.693503ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:02:23.376424Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31118} {"level":"info","ts":"2026-05-05T18:02:23.498237Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31118,"took":"113.151798ms","hash":2114602077,"current-db-size-bytes":60473344,"current-db-size":"60 MB","current-db-size-in-use-bytes":31326208,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-05T18:02:23.498297Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2114602077,"revision":31118,"compact-revision":28900} {"level":"info","ts":"2026-05-05T18:07:23.384968Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33473} {"level":"info","ts":"2026-05-05T18:07:23.549467Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33473,"took":"144.701665ms","hash":1917372884,"current-db-size-bytes":61517824,"current-db-size":"62 MB","current-db-size-in-use-bytes":32600064,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-05T18:07:23.549544Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1917372884,"revision":33473,"compact-revision":31118} {"level":"info","ts":"2026-05-05T18:12:23.390699Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35769} {"level":"info","ts":"2026-05-05T18:12:23.536308Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35769,"took":"138.130401ms","hash":4210738380,"current-db-size-bytes":63627264,"current-db-size":"64 MB","current-db-size-in-use-bytes":31891456,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-05T18:12:23.536367Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4210738380,"revision":35769,"compact-revision":33473} {"level":"info","ts":"2026-05-05T18:14:06.522579Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T18:14:06.527588Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-05T18:14:06.52766Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}