{"level":"warn","ts":"2026-05-05T22:15:20.289459Z","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-05T22:15:20.289528Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-05T22:15:20.289584Z","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-05T22:15:20.289596Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-05-05T22:15:20.289613Z","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-05T22:15:20.290181Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-05-05T22:15:20.29026Z","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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-05T22:15:20.294179Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.721177ms"} {"level":"info","ts":"2026-05-05T22:15:20.301234Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-05-05T22:15:20.301319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T22:15:20.301347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-05-05T22:15:20.3014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T22:15:20.301418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-05-05T22:15:20.301456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-05-05T22:15:20.312937Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T22:15:20.314836Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T22:15:20.31601Z","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-05T22:15:20.319138Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T22:15:20.31926Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T22:15:20.320099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-05-05T22:15:20.320216Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-05-05T22:15:20.320264Z","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-05T22:15:20.3204Z","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-05T22:15:20.320418Z","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-05T22:15:20.326819Z","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-05T22:15:20.326911Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-05-05T22:15:20.326963Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-05-05T22:15:20.3272Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T22:15:20.32725Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T22:15:21.001849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T22:15:21.001943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T22:15:21.00198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-05-05T22:15:21.002009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-05-05T22:15:21.002028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-05-05T22:15:21.002049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-05-05T22:15:21.002079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-05-05T22:15:21.005943Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T22:15:21.005946Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:15:21.006209Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T22:15:21.006295Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T22:15:21.007036Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:15:21.007152Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:15:21.007188Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:15:21.009441Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-05-05T22:15:21.009519Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T22:15:21.009663Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T22:15:21.009706Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T22:15:24.867029Z","caller":"traceutil/trace.go:171","msg":"trace[1730874408] linearizableReadLoop","detail":"{readStateIndex:229; appliedIndex:228; }","duration":"111.967307ms","start":"2026-05-05T22:15:24.755033Z","end":"2026-05-05T22:15:24.867Z","steps":["trace[1730874408] 'read index received' (duration: 111.688062ms)","trace[1730874408] 'applied index is now lower than readState.Index' (duration: 278.125µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:15:24.867101Z","caller":"traceutil/trace.go:171","msg":"trace[229263298] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"136.701493ms","start":"2026-05-05T22:15:24.730367Z","end":"2026-05-05T22:15:24.867069Z","steps":["trace[229263298] 'process raft request' (duration: 136.470948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:24.867194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.141463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:24.867264Z","caller":"traceutil/trace.go:171","msg":"trace[1872944424] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:0; response_revision:223; }","duration":"112.280986ms","start":"2026-05-05T22:15:24.754971Z","end":"2026-05-05T22:15:24.867252Z","steps":["trace[1872944424] 'agreement among raft nodes before linearized reading' (duration: 112.158653ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:30.367703Z","caller":"traceutil/trace.go:171","msg":"trace[1384765405] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"131.836672ms","start":"2026-05-05T22:15:30.235846Z","end":"2026-05-05T22:15:30.367682Z","steps":["trace[1384765405] 'process raft request' (duration: 131.735279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:30.652271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.922657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-05T22:15:30.652308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.753534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-05T22:15:30.652347Z","caller":"traceutil/trace.go:171","msg":"trace[1947489972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:383; }","duration":"216.05753ms","start":"2026-05-05T22:15:30.436272Z","end":"2026-05-05T22:15:30.652329Z","steps":["trace[1947489972] 'range keys from in-memory index tree' (duration: 215.787675ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:30.652426Z","caller":"traceutil/trace.go:171","msg":"trace[778252409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:383; }","duration":"215.911188ms","start":"2026-05-05T22:15:30.436493Z","end":"2026-05-05T22:15:30.652404Z","steps":["trace[778252409] 'range keys from in-memory index tree' (duration: 215.58416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:30.652574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.44946ms","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-05T22:15:30.652694Z","caller":"traceutil/trace.go:171","msg":"trace[1667084760] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:383; }","duration":"173.623344ms","start":"2026-05-05T22:15:30.479042Z","end":"2026-05-05T22:15:30.652666Z","steps":["trace[1667084760] 'range keys from in-memory index tree' (duration: 173.044582ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:30.864066Z","caller":"traceutil/trace.go:171","msg":"trace[1665936225] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"207.514112ms","start":"2026-05-05T22:15:30.656527Z","end":"2026-05-05T22:15:30.864041Z","steps":["trace[1665936225] 'process raft request' (duration: 207.362939ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:30.866551Z","caller":"traceutil/trace.go:171","msg":"trace[2083191171] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"209.632666ms","start":"2026-05-05T22:15:30.656888Z","end":"2026-05-05T22:15:30.866521Z","steps":["trace[2083191171] 'process raft request' (duration: 209.592735ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:30.866602Z","caller":"traceutil/trace.go:171","msg":"trace[1918079211] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"209.7874ms","start":"2026-05-05T22:15:30.656776Z","end":"2026-05-05T22:15:30.866563Z","steps":["trace[1918079211] 'process raft request' (duration: 209.591086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:59.316146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.518958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-05T22:15:59.316179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.597322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:15:59.316229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.158963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:59.316235Z","caller":"traceutil/trace.go:171","msg":"trace[1873140674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"226.696022ms","start":"2026-05-05T22:15:59.089522Z","end":"2026-05-05T22:15:59.316218Z","steps":["trace[1873140674] 'range keys from in-memory index tree' (duration: 226.308335ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:59.316265Z","caller":"traceutil/trace.go:171","msg":"trace[39120594] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:630; }","duration":"159.731165ms","start":"2026-05-05T22:15:59.156514Z","end":"2026-05-05T22:15:59.316245Z","steps":["trace[39120594] 'count revisions from in-memory index tree' (duration: 159.537641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:59.316227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.119402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-05-05T22:15:59.316292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.696674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:15:59.316317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.514656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:15:59.316152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.365857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:59.316371Z","caller":"traceutil/trace.go:171","msg":"trace[633390461] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:630; }","duration":"159.808116ms","start":"2026-05-05T22:15:59.156542Z","end":"2026-05-05T22:15:59.31635Z","steps":["trace[633390461] 'range keys from in-memory index tree' (duration: 159.630993ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:59.316281Z","caller":"traceutil/trace.go:171","msg":"trace[1950603331] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:630; }","duration":"205.240236ms","start":"2026-05-05T22:15:59.111021Z","end":"2026-05-05T22:15:59.316261Z","steps":["trace[1950603331] 'count revisions from in-memory index tree' (duration: 205.050961ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:59.316419Z","caller":"traceutil/trace.go:171","msg":"trace[1203167940] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:630; }","duration":"108.644979ms","start":"2026-05-05T22:15:59.20775Z","end":"2026-05-05T22:15:59.316395Z","steps":["trace[1203167940] 'count revisions from in-memory index tree' (duration: 108.375483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:59.316464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.909343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:15:59.316428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.224255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:15:59.316568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.851626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:59.31665Z","caller":"traceutil/trace.go:171","msg":"trace[220169833] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:630; }","duration":"108.135038ms","start":"2026-05-05T22:15:59.208448Z","end":"2026-05-05T22:15:59.316583Z","steps":["trace[220169833] 'range keys from in-memory index tree' (duration: 107.815242ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:59.316724Z","caller":"traceutil/trace.go:171","msg":"trace[800130443] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:630; }","duration":"159.133913ms","start":"2026-05-05T22:15:59.157551Z","end":"2026-05-05T22:15:59.316685Z","steps":["trace[800130443] 'range keys from in-memory index tree' (duration: 158.679073ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:59.316491Z","caller":"traceutil/trace.go:171","msg":"trace[751118195] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:630; }","duration":"204.726784ms","start":"2026-05-05T22:15:59.111679Z","end":"2026-05-05T22:15:59.316406Z","steps":["trace[751118195] 'range keys from in-memory index tree' (duration: 204.202293ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:59.316747Z","caller":"traceutil/trace.go:171","msg":"trace[1713089777] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:630; }","duration":"205.708145ms","start":"2026-05-05T22:15:59.110952Z","end":"2026-05-05T22:15:59.316661Z","steps":["trace[1713089777] 'range keys from in-memory index tree' (duration: 205.04878ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:59.316365Z","caller":"traceutil/trace.go:171","msg":"trace[1701645042] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:630; }","duration":"250.311776ms","start":"2026-05-05T22:15:59.066029Z","end":"2026-05-05T22:15:59.316341Z","steps":["trace[1701645042] 'range keys from in-memory index tree' (duration: 249.971589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:59.317326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.589708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:59.317395Z","caller":"traceutil/trace.go:171","msg":"trace[624319925] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:630; }","duration":"109.65612ms","start":"2026-05-05T22:15:59.207722Z","end":"2026-05-05T22:15:59.317378Z","steps":["trace[624319925] 'range keys from in-memory index tree' (duration: 108.457715ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:00.228004Z","caller":"traceutil/trace.go:171","msg":"trace[1283557273] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"176.648618ms","start":"2026-05-05T22:16:00.051327Z","end":"2026-05-05T22:16:00.227976Z","steps":["trace[1283557273] 'read index received' (duration: 176.394823ms)","trace[1283557273] 'applied index is now lower than readState.Index' (duration: 252.615µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:16:00.228071Z","caller":"traceutil/trace.go:171","msg":"trace[269468756] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"183.770185ms","start":"2026-05-05T22:16:00.04427Z","end":"2026-05-05T22:16:00.22804Z","steps":["trace[269468756] 'process raft request' (duration: 183.481959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:00.228226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.865792ms","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:5"} {"level":"warn","ts":"2026-05-05T22:16:00.228322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.737982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:16:00.228363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.003437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:00.228412Z","caller":"traceutil/trace.go:171","msg":"trace[641754506] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:633; }","duration":"134.11564ms","start":"2026-05-05T22:16:00.094278Z","end":"2026-05-05T22:16:00.228393Z","steps":["trace[641754506] 'agreement among raft nodes before linearized reading' (duration: 134.038648ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:00.228402Z","caller":"traceutil/trace.go:171","msg":"trace[1364265665] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:633; }","duration":"157.885105ms","start":"2026-05-05T22:16:00.070498Z","end":"2026-05-05T22:16:00.228383Z","steps":["trace[1364265665] 'agreement among raft nodes before linearized reading' (duration: 157.723812ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:00.228272Z","caller":"traceutil/trace.go:171","msg":"trace[169899487] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:633; }","duration":"176.969994ms","start":"2026-05-05T22:16:00.051291Z","end":"2026-05-05T22:16:00.228261Z","steps":["trace[169899487] 'agreement among raft nodes before linearized reading' (duration: 176.831421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:00.228462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.019391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:00.22856Z","caller":"traceutil/trace.go:171","msg":"trace[1603776788] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:633; }","duration":"115.157474ms","start":"2026-05-05T22:16:00.113379Z","end":"2026-05-05T22:16:00.228536Z","steps":["trace[1603776788] 'agreement among raft nodes before linearized reading' (duration: 114.98576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:00.228608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.123384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:16:00.228624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.056589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:16:00.228666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.526148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:00.22872Z","caller":"traceutil/trace.go:171","msg":"trace[176916184] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:633; }","duration":"115.280717ms","start":"2026-05-05T22:16:00.113411Z","end":"2026-05-05T22:16:00.228692Z","steps":["trace[176916184] 'agreement among raft nodes before linearized reading' (duration: 115.049062ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:00.228744Z","caller":"traceutil/trace.go:171","msg":"trace[1296162002] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:633; }","duration":"157.246292ms","start":"2026-05-05T22:16:00.07146Z","end":"2026-05-05T22:16:00.228706Z","steps":["trace[1296162002] 'agreement among raft nodes before linearized reading' (duration: 157.040948ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:00.228761Z","caller":"traceutil/trace.go:171","msg":"trace[202190498] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:633; }","duration":"134.655411ms","start":"2026-05-05T22:16:00.094081Z","end":"2026-05-05T22:16:00.228736Z","steps":["trace[202190498] 'agreement among raft nodes before linearized reading' (duration: 134.488757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:00.228871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.145264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:16:00.229153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.883903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:00.229104Z","caller":"traceutil/trace.go:171","msg":"trace[583565761] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:633; }","duration":"115.719977ms","start":"2026-05-05T22:16:00.113354Z","end":"2026-05-05T22:16:00.229074Z","steps":["trace[583565761] 'agreement among raft nodes before linearized reading' (duration: 115.056662ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:00.22922Z","caller":"traceutil/trace.go:171","msg":"trace[536616452] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:633; }","duration":"177.929615ms","start":"2026-05-05T22:16:00.051275Z","end":"2026-05-05T22:16:00.229205Z","steps":["trace[536616452] 'agreement among raft nodes before linearized reading' (duration: 176.76185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:00.228776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.11053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:00.229476Z","caller":"traceutil/trace.go:171","msg":"trace[1903997446] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:633; }","duration":"158.837886ms","start":"2026-05-05T22:16:00.070591Z","end":"2026-05-05T22:16:00.229429Z","steps":["trace[1903997446] 'agreement among raft nodes before linearized reading' (duration: 158.037498ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:52.846539Z","caller":"traceutil/trace.go:171","msg":"trace[2085345842] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"182.183124ms","start":"2026-05-05T22:16:52.66433Z","end":"2026-05-05T22:16:52.846513Z","steps":["trace[2085345842] 'process raft request' (duration: 182.015791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:36.277678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.26068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:17:36.277817Z","caller":"traceutil/trace.go:171","msg":"trace[863241343] linearizableReadLoop","detail":"{readStateIndex:1959; appliedIndex:1958; }","duration":"211.145913ms","start":"2026-05-05T22:17:36.06665Z","end":"2026-05-05T22:17:36.277796Z","steps":["trace[863241343] 'read index received' (duration: 54.842µs)","trace[863241343] 'applied index is now lower than readState.Index' (duration: 211.089401ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:17:36.277911Z","caller":"traceutil/trace.go:171","msg":"trace[1761115615] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"368.017152ms","start":"2026-05-05T22:17:35.909826Z","end":"2026-05-05T22:17:36.277843Z","steps":["trace[1761115615] 'process raft request' (duration: 15.873115ms)","trace[1761115615] 'compare' (duration: 350.996004ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:36.27809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:35.909805Z","time spent":"368.188236ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:17:36.278126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.431469ms","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-05T22:17:36.278226Z","caller":"traceutil/trace.go:171","msg":"trace[1947592592] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1883; }","duration":"211.627743ms","start":"2026-05-05T22:17:36.066577Z","end":"2026-05-05T22:17:36.278204Z","steps":["trace[1947592592] 'agreement among raft nodes before linearized reading' (duration: 211.314346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:36.278345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.29793ms","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":"info","ts":"2026-05-05T22:17:36.278499Z","caller":"traceutil/trace.go:171","msg":"trace[1037911030] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1883; }","duration":"179.477123ms","start":"2026-05-05T22:17:36.099005Z","end":"2026-05-05T22:17:36.278482Z","steps":["trace[1037911030] 'agreement among raft nodes before linearized reading' (duration: 178.924242ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:36.428913Z","caller":"traceutil/trace.go:171","msg":"trace[1233787161] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"145.532037ms","start":"2026-05-05T22:17:36.283342Z","end":"2026-05-05T22:17:36.428874Z","steps":["trace[1233787161] 'process raft request' (duration: 103.376292ms)","trace[1233787161] 'compare' (duration: 41.841539ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:18:21.986741Z","caller":"traceutil/trace.go:171","msg":"trace[595984041] linearizableReadLoop","detail":"{readStateIndex:2270; appliedIndex:2269; }","duration":"107.142518ms","start":"2026-05-05T22:18:21.879571Z","end":"2026-05-05T22:18:21.986714Z","steps":["trace[595984041] 'read index received' (duration: 106.848533ms)","trace[595984041] 'applied index is now lower than readState.Index' (duration: 292.585µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:18:21.986781Z","caller":"traceutil/trace.go:171","msg":"trace[2038184177] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"110.18609ms","start":"2026-05-05T22:18:21.876563Z","end":"2026-05-05T22:18:21.986749Z","steps":["trace[2038184177] 'process raft request' (duration: 109.944415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:21.987019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.410644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-05T22:18:21.987156Z","caller":"traceutil/trace.go:171","msg":"trace[1782529976] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2185; }","duration":"107.573386ms","start":"2026-05-05T22:18:21.879537Z","end":"2026-05-05T22:18:21.98711Z","steps":["trace[1782529976] 'agreement among raft nodes before linearized reading' (duration: 107.27084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:22.185713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.650505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T22:18:22.185805Z","caller":"traceutil/trace.go:171","msg":"trace[600385751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2189; }","duration":"126.817478ms","start":"2026-05-05T22:18:22.058967Z","end":"2026-05-05T22:18:22.185785Z","steps":["trace[600385751] 'range keys from in-memory index tree' (duration: 126.41597ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:22.308469Z","caller":"traceutil/trace.go:171","msg":"trace[811938627] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"117.971148ms","start":"2026-05-05T22:18:22.190475Z","end":"2026-05-05T22:18:22.308446Z","steps":["trace[811938627] 'process raft request' (duration: 117.783794ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:15.503688Z","caller":"traceutil/trace.go:171","msg":"trace[1775879998] transaction","detail":"{read_only:false; response_revision:2649; number_of_response:1; }","duration":"140.126418ms","start":"2026-05-05T22:19:15.363541Z","end":"2026-05-05T22:19:15.503667Z","steps":["trace[1775879998] 'process raft request' (duration: 139.856822ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:23:45.831148Z","caller":"traceutil/trace.go:171","msg":"trace[2076508584] linearizableReadLoop","detail":"{readStateIndex:5094; appliedIndex:5093; }","duration":"119.507417ms","start":"2026-05-05T22:23:45.711608Z","end":"2026-05-05T22:23:45.831115Z","steps":["trace[2076508584] 'read index received' (duration: 88.784264ms)","trace[2076508584] 'applied index is now lower than readState.Index' (duration: 30.721733ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:23:45.831424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.216271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-05T22:23:45.831548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.548518ms","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-05T22:23:45.831545Z","caller":"traceutil/trace.go:171","msg":"trace[1067406607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4903; }","duration":"127.354743ms","start":"2026-05-05T22:23:45.704159Z","end":"2026-05-05T22:23:45.831514Z","steps":["trace[1067406607] 'agreement among raft nodes before linearized reading' (duration: 126.964225ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:23:45.831576Z","caller":"traceutil/trace.go:171","msg":"trace[1129477937] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4903; }","duration":"127.613838ms","start":"2026-05-05T22:23:45.703952Z","end":"2026-05-05T22:23:45.831565Z","steps":["trace[1129477937] 'agreement among raft nodes before linearized reading' (duration: 127.552677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:23:45.831933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.973675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-sf9f2-tls\" ","response":"range_response_count:1 size:2033"} {"level":"info","ts":"2026-05-05T22:23:45.831963Z","caller":"traceutil/trace.go:171","msg":"trace[1372012678] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-sf9f2-tls; range_end:; response_count:1; response_revision:4903; }","duration":"136.041237ms","start":"2026-05-05T22:23:45.695913Z","end":"2026-05-05T22:23:45.831955Z","steps":["trace[1372012678] 'agreement among raft nodes before linearized reading' (duration: 135.779271ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:23:45.832112Z","caller":"traceutil/trace.go:171","msg":"trace[1798176133] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"125.918225ms","start":"2026-05-05T22:23:45.706177Z","end":"2026-05-05T22:23:45.832095Z","steps":["trace[1798176133] 'process raft request' (duration: 94.276144ms)","trace[1798176133] 'compare' (duration: 30.485059ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:23:45.831482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.416775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T22:23:45.832309Z","caller":"traceutil/trace.go:171","msg":"trace[232771298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4903; }","duration":"128.282841ms","start":"2026-05-05T22:23:45.704012Z","end":"2026-05-05T22:23:45.832295Z","steps":["trace[232771298] 'agreement among raft nodes before linearized reading' (duration: 127.262021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:23:45.832659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.017377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:23:45.832904Z","caller":"traceutil/trace.go:171","msg":"trace[691151569] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4903; }","duration":"144.14737ms","start":"2026-05-05T22:23:45.688581Z","end":"2026-05-05T22:23:45.832729Z","steps":["trace[691151569] 'agreement among raft nodes before linearized reading' (duration: 143.71426ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:23:45.867349Z","caller":"traceutil/trace.go:171","msg":"trace[1918311127] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"142.737361ms","start":"2026-05-05T22:23:45.724544Z","end":"2026-05-05T22:23:45.867281Z","steps":["trace[1918311127] 'process raft request' (duration: 142.501976ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:23:51.425487Z","caller":"traceutil/trace.go:171","msg":"trace[1106236860] transaction","detail":"{read_only:false; response_revision:5070; number_of_response:1; }","duration":"147.890724ms","start":"2026-05-05T22:23:51.277564Z","end":"2026-05-05T22:23:51.425455Z","steps":["trace[1106236860] 'process raft request' (duration: 147.780262ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:23:51.425478Z","caller":"traceutil/trace.go:171","msg":"trace[1898365242] transaction","detail":"{read_only:false; response_revision:5069; number_of_response:1; }","duration":"160.982796ms","start":"2026-05-05T22:23:51.264449Z","end":"2026-05-05T22:23:51.425432Z","steps":["trace[1898365242] 'process raft request' (duration: 104.418576ms)","trace[1898365242] 'compare' (duration: 56.249324ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:23:51.425539Z","caller":"traceutil/trace.go:171","msg":"trace[919422529] linearizableReadLoop","detail":"{readStateIndex:5264; appliedIndex:5263; }","duration":"149.72662ms","start":"2026-05-05T22:23:51.275771Z","end":"2026-05-05T22:23:51.425498Z","steps":["trace[919422529] 'read index received' (duration: 93.12162ms)","trace[919422529] 'applied index is now lower than readState.Index' (duration: 56.60142ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:23:51.425868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.084018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f33da5d0-8444-42c0-a71a-8fa917427541\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-05T22:23:51.425912Z","caller":"traceutil/trace.go:171","msg":"trace[1407202777] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f33da5d0-8444-42c0-a71a-8fa917427541; range_end:; response_count:1; response_revision:5070; }","duration":"150.146699ms","start":"2026-05-05T22:23:51.275751Z","end":"2026-05-05T22:23:51.425898Z","steps":["trace[1407202777] 'agreement among raft nodes before linearized reading' (duration: 149.890434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:23:51.867388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.149484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f33da5d0-8444-42c0-a71a-8fa917427541\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-05T22:23:51.867514Z","caller":"traceutil/trace.go:171","msg":"trace[1039142901] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f33da5d0-8444-42c0-a71a-8fa917427541; range_end:; response_count:1; response_revision:5071; }","duration":"342.343608ms","start":"2026-05-05T22:23:51.525139Z","end":"2026-05-05T22:23:51.867483Z","steps":["trace[1039142901] 'range keys from in-memory index tree' (duration: 341.92797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:23:51.867496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.634138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413130"} {"level":"warn","ts":"2026-05-05T22:23:51.867584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:23:51.525119Z","time spent":"342.44953ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3624,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f33da5d0-8444-42c0-a71a-8fa917427541\" "} {"level":"info","ts":"2026-05-05T22:23:51.86761Z","caller":"traceutil/trace.go:171","msg":"trace[1858519785] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:5071; }","duration":"225.799331ms","start":"2026-05-05T22:23:51.641786Z","end":"2026-05-05T22:23:51.867586Z","steps":["trace[1858519785] 'range keys from in-memory index tree' (duration: 225.313021ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:23:52.353868Z","caller":"traceutil/trace.go:171","msg":"trace[1023444688] linearizableReadLoop","detail":"{readStateIndex:5276; appliedIndex:5275; }","duration":"108.913285ms","start":"2026-05-05T22:23:52.244932Z","end":"2026-05-05T22:23:52.353845Z","steps":["trace[1023444688] 'read index received' (duration: 108.360645ms)","trace[1023444688] 'applied index is now lower than readState.Index' (duration: 551.67µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:23:52.354791Z","caller":"traceutil/trace.go:171","msg":"trace[434371679] transaction","detail":"{read_only:false; response_revision:5081; number_of_response:1; }","duration":"112.62331ms","start":"2026-05-05T22:23:52.242135Z","end":"2026-05-05T22:23:52.354759Z","steps":["trace[434371679] 'process raft request' (duration: 111.357134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:23:52.354919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.986667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T22:23:52.354954Z","caller":"traceutil/trace.go:171","msg":"trace[2117327251] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5081; }","duration":"110.075129ms","start":"2026-05-05T22:23:52.244867Z","end":"2026-05-05T22:23:52.354942Z","steps":["trace[2117327251] 'agreement among raft nodes before linearized reading' (duration: 109.954507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:24:09.236266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.890955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-27xls\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-05T22:24:09.236376Z","caller":"traceutil/trace.go:171","msg":"trace[1727009719] range","detail":"{range_begin:/registry/pods/openstack/keepalived-27xls; range_end:; response_count:1; response_revision:5442; }","duration":"111.088229ms","start":"2026-05-05T22:24:09.125265Z","end":"2026-05-05T22:24:09.236353Z","steps":["trace[1727009719] 'range keys from in-memory index tree' (duration: 110.570149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:24:09.614332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.957285ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T22:24:09.614476Z","caller":"traceutil/trace.go:171","msg":"trace[133384833] linearizableReadLoop","detail":"{readStateIndex:5650; appliedIndex:5649; }","duration":"127.925276ms","start":"2026-05-05T22:24:09.486529Z","end":"2026-05-05T22:24:09.614454Z","steps":["trace[133384833] 'read index received' (duration: 88.842µs)","trace[133384833] 'applied index is now lower than readState.Index' (duration: 127.834134ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:24:09.614643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.10785ms","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":"info","ts":"2026-05-05T22:24:09.614686Z","caller":"traceutil/trace.go:171","msg":"trace[1688483807] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:5443; }","duration":"128.216602ms","start":"2026-05-05T22:24:09.486454Z","end":"2026-05-05T22:24:09.61467Z","steps":["trace[1688483807] 'agreement among raft nodes before linearized reading' (duration: 128.10352ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:25:21.034987Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3030} {"level":"info","ts":"2026-05-05T22:25:21.118893Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3030,"took":"81.821931ms","hash":3310780370,"current-db-size-bytes":57405440,"current-db-size":"57 MB","current-db-size-in-use-bytes":31514624,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-05T22:25:21.118945Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3310780370,"revision":3030,"compact-revision":-1} {"level":"info","ts":"2026-05-05T22:29:59.948174Z","caller":"traceutil/trace.go:171","msg":"trace[1972529076] linearizableReadLoop","detail":"{readStateIndex:8485; appliedIndex:8484; }","duration":"205.302969ms","start":"2026-05-05T22:29:59.742817Z","end":"2026-05-05T22:29:59.94812Z","steps":["trace[1972529076] 'read index received' (duration: 205.038103ms)","trace[1972529076] 'applied index is now lower than readState.Index' (duration: 263.016µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:29:59.948314Z","caller":"traceutil/trace.go:171","msg":"trace[1865847705] transaction","detail":"{read_only:false; response_revision:8194; number_of_response:1; }","duration":"208.35908ms","start":"2026-05-05T22:29:59.739912Z","end":"2026-05-05T22:29:59.948271Z","steps":["trace[1865847705] 'process raft request' (duration: 207.971852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:29:59.948469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.619475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-05T22:29:59.948545Z","caller":"traceutil/trace.go:171","msg":"trace[530662552] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8194; }","duration":"205.748927ms","start":"2026-05-05T22:29:59.742777Z","end":"2026-05-05T22:29:59.948526Z","steps":["trace[530662552] 'agreement among raft nodes before linearized reading' (duration: 205.492112ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:30:00.172758Z","caller":"traceutil/trace.go:171","msg":"trace[1085736956] linearizableReadLoop","detail":"{readStateIndex:8487; appliedIndex:8485; }","duration":"199.249237ms","start":"2026-05-05T22:29:59.973478Z","end":"2026-05-05T22:30:00.172728Z","steps":["trace[1085736956] 'read index received' (duration: 108.584959ms)","trace[1085736956] 'applied index is now lower than readState.Index' (duration: 90.662878ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:30:00.17286Z","caller":"traceutil/trace.go:171","msg":"trace[1912394136] transaction","detail":"{read_only:false; response_revision:8195; number_of_response:1; }","duration":"247.106457ms","start":"2026-05-05T22:29:59.925706Z","end":"2026-05-05T22:30:00.172812Z","steps":["trace[1912394136] 'process raft request' (duration: 156.337496ms)","trace[1912394136] 'compare' (duration: 90.435714ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:30:00.172884Z","caller":"traceutil/trace.go:171","msg":"trace[259419200] transaction","detail":"{read_only:false; response_revision:8196; number_of_response:1; }","duration":"220.468513ms","start":"2026-05-05T22:29:59.952378Z","end":"2026-05-05T22:30:00.172847Z","steps":["trace[259419200] 'process raft request' (duration: 220.293189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:30:00.172954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.445881ms","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:5"} {"level":"info","ts":"2026-05-05T22:30:00.172997Z","caller":"traceutil/trace.go:171","msg":"trace[1368341926] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:8196; }","duration":"199.571183ms","start":"2026-05-05T22:29:59.973415Z","end":"2026-05-05T22:30:00.172986Z","steps":["trace[1368341926] 'agreement among raft nodes before linearized reading' (duration: 199.458241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:30:00.173463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.225901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-05-05T22:30:00.173551Z","caller":"traceutil/trace.go:171","msg":"trace[633277789] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8196; }","duration":"114.745762ms","start":"2026-05-05T22:30:00.058791Z","end":"2026-05-05T22:30:00.173537Z","steps":["trace[633277789] 'agreement among raft nodes before linearized reading' (duration: 114.130099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:30:12.58847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.803164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:30:12.588769Z","caller":"traceutil/trace.go:171","msg":"trace[667575572] transaction","detail":"{read_only:false; response_revision:8260; number_of_response:1; }","duration":"338.874468ms","start":"2026-05-05T22:30:12.249852Z","end":"2026-05-05T22:30:12.588726Z","steps":["trace[667575572] 'process raft request' (duration: 200.667036ms)","trace[667575572] 'compare' (duration: 137.530848ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:30:12.588815Z","caller":"traceutil/trace.go:171","msg":"trace[271947882] transaction","detail":"{read_only:false; response_revision:8261; number_of_response:1; }","duration":"338.651124ms","start":"2026-05-05T22:30:12.250126Z","end":"2026-05-05T22:30:12.588777Z","steps":["trace[271947882] 'process raft request' (duration: 338.501531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:30:12.588947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:30:12.250118Z","time spent":"338.764126ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:30:12.58898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:30:12.249816Z","time spent":"339.051812ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:30:12.589248Z","caller":"traceutil/trace.go:171","msg":"trace[609507309] transaction","detail":"{read_only:false; response_revision:8262; number_of_response:1; }","duration":"339.023732ms","start":"2026-05-05T22:30:12.250198Z","end":"2026-05-05T22:30:12.589221Z","steps":["trace[609507309] 'process raft request' (duration: 338.50981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:30:12.589358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:30:12.250186Z","time spent":"339.113964ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:30:12.589378Z","caller":"traceutil/trace.go:171","msg":"trace[83950529] transaction","detail":"{read_only:false; response_revision:8263; number_of_response:1; }","duration":"339.096702ms","start":"2026-05-05T22:30:12.250238Z","end":"2026-05-05T22:30:12.589334Z","steps":["trace[83950529] 'process raft request' (duration: 338.844636ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:30:12.589427Z","caller":"traceutil/trace.go:171","msg":"trace[2102318911] linearizableReadLoop","detail":"{readStateIndex:8557; appliedIndex:8552; }","duration":"270.899375ms","start":"2026-05-05T22:30:12.318473Z","end":"2026-05-05T22:30:12.589372Z","steps":["trace[2102318911] 'read index received' (duration: 132.001288ms)","trace[2102318911] 'applied index is now lower than readState.Index' (duration: 138.896547ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:30:12.589569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:30:12.250213Z","time spent":"339.258946ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:30:12.589531Z","caller":"traceutil/trace.go:171","msg":"trace[1751795331] transaction","detail":"{read_only:false; response_revision:8264; number_of_response:1; }","duration":"338.95344ms","start":"2026-05-05T22:30:12.250485Z","end":"2026-05-05T22:30:12.589438Z","steps":["trace[1751795331] 'process raft request' (duration: 338.754656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:30:12.589645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.129889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:30:12.589754Z","caller":"traceutil/trace.go:171","msg":"trace[1610497436] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:8264; }","duration":"271.284553ms","start":"2026-05-05T22:30:12.318444Z","end":"2026-05-05T22:30:12.589729Z","steps":["trace[1610497436] 'agreement among raft nodes before linearized reading' (duration: 271.088749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:30:12.589811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:30:12.250472Z","time spent":"339.190324ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:30:21.042583Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6111} {"level":"info","ts":"2026-05-05T22:30:21.137262Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6111,"took":"92.371003ms","hash":463944513,"current-db-size-bytes":57405440,"current-db-size":"57 MB","current-db-size-in-use-bytes":23207936,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-05T22:30:21.137369Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":463944513,"revision":6111,"compact-revision":3030} {"level":"info","ts":"2026-05-05T22:30:40.576749Z","caller":"traceutil/trace.go:171","msg":"trace[761977969] linearizableReadLoop","detail":"{readStateIndex:8768; appliedIndex:8767; }","duration":"144.419656ms","start":"2026-05-05T22:30:40.432299Z","end":"2026-05-05T22:30:40.576719Z","steps":["trace[761977969] 'read index received' (duration: 143.961777ms)","trace[761977969] 'applied index is now lower than readState.Index' (duration: 456.049µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:30:40.576845Z","caller":"traceutil/trace.go:171","msg":"trace[1995161132] transaction","detail":"{read_only:false; response_revision:8465; number_of_response:1; }","duration":"217.81098ms","start":"2026-05-05T22:30:40.358989Z","end":"2026-05-05T22:30:40.5768Z","steps":["trace[1995161132] 'process raft request' (duration: 217.429242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:30:40.57694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.60105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:30:40.577024Z","caller":"traceutil/trace.go:171","msg":"trace[1925826670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8465; }","duration":"144.774994ms","start":"2026-05-05T22:30:40.43223Z","end":"2026-05-05T22:30:40.577005Z","steps":["trace[1925826670] 'agreement among raft nodes before linearized reading' (duration: 144.612931ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:30:53.510524Z","caller":"traceutil/trace.go:171","msg":"trace[708848153] linearizableReadLoop","detail":"{readStateIndex:8886; appliedIndex:8885; }","duration":"170.004811ms","start":"2026-05-05T22:30:53.340484Z","end":"2026-05-05T22:30:53.510489Z","steps":["trace[708848153] 'read index received' (duration: 169.725005ms)","trace[708848153] 'applied index is now lower than readState.Index' (duration: 278.696µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:30:53.511013Z","caller":"traceutil/trace.go:171","msg":"trace[1178740222] transaction","detail":"{read_only:false; response_revision:8578; number_of_response:1; }","duration":"247.864652ms","start":"2026-05-05T22:30:53.263133Z","end":"2026-05-05T22:30:53.510998Z","steps":["trace[1178740222] 'process raft request' (duration: 247.165098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:30:53.51138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.867717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T22:30:53.511419Z","caller":"traceutil/trace.go:171","msg":"trace[1387655089] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:8578; }","duration":"170.97783ms","start":"2026-05-05T22:30:53.340429Z","end":"2026-05-05T22:30:53.511407Z","steps":["trace[1387655089] 'agreement among raft nodes before linearized reading' (duration: 170.793536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:30:53.511468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.611219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-v28b7\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-05-05T22:30:53.511561Z","caller":"traceutil/trace.go:171","msg":"trace[99768768] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-v28b7; range_end:; response_count:1; response_revision:8578; }","duration":"118.756602ms","start":"2026-05-05T22:30:53.392784Z","end":"2026-05-05T22:30:53.511541Z","steps":["trace[99768768] 'agreement among raft nodes before linearized reading' (duration: 118.581098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:30:53.511978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.992326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T22:30:53.512016Z","caller":"traceutil/trace.go:171","msg":"trace[89342864] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:8578; }","duration":"101.047056ms","start":"2026-05-05T22:30:53.41096Z","end":"2026-05-05T22:30:53.512007Z","steps":["trace[89342864] 'agreement among raft nodes before linearized reading' (duration: 100.919914ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:30:54.282148Z","caller":"traceutil/trace.go:171","msg":"trace[1108187070] linearizableReadLoop","detail":"{readStateIndex:8890; appliedIndex:8889; }","duration":"198.060813ms","start":"2026-05-05T22:30:54.084063Z","end":"2026-05-05T22:30:54.282124Z","steps":["trace[1108187070] 'read index received' (duration: 167.827177ms)","trace[1108187070] 'applied index is now lower than readState.Index' (duration: 30.232596ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:30:54.282183Z","caller":"traceutil/trace.go:171","msg":"trace[1871882005] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"217.687947ms","start":"2026-05-05T22:30:54.064452Z","end":"2026-05-05T22:30:54.28214Z","steps":["trace[1871882005] 'process raft request' (duration: 187.420729ms)","trace[1871882005] 'compare' (duration: 30.092944ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:30:54.282567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.464801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:30:54.282726Z","caller":"traceutil/trace.go:171","msg":"trace[1894343040] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:8581; }","duration":"198.628974ms","start":"2026-05-05T22:30:54.084011Z","end":"2026-05-05T22:30:54.28264Z","steps":["trace[1894343040] 'agreement among raft nodes before linearized reading' (duration: 198.211186ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:30:55.01056Z","caller":"traceutil/trace.go:171","msg":"trace[1235410624] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"127.61139ms","start":"2026-05-05T22:30:54.882913Z","end":"2026-05-05T22:30:55.010524Z","steps":["trace[1235410624] 'process raft request' (duration: 97.69229ms)","trace[1235410624] 'compare' (duration: 29.734086ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:30:55.012632Z","caller":"traceutil/trace.go:171","msg":"trace[1009225229] linearizableReadLoop","detail":"{readStateIndex:8901; appliedIndex:8898; }","duration":"127.021648ms","start":"2026-05-05T22:30:54.885574Z","end":"2026-05-05T22:30:55.012596Z","steps":["trace[1009225229] 'read index received' (duration: 94.934935ms)","trace[1009225229] 'applied index is now lower than readState.Index' (duration: 32.085733ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:30:55.012707Z","caller":"traceutil/trace.go:171","msg":"trace[1204326306] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"129.149571ms","start":"2026-05-05T22:30:54.883503Z","end":"2026-05-05T22:30:55.012653Z","steps":["trace[1204326306] 'process raft request' (duration: 128.898796ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:30:55.012761Z","caller":"traceutil/trace.go:171","msg":"trace[1194223287] transaction","detail":"{read_only:false; response_revision:8592; number_of_response:1; }","duration":"127.248432ms","start":"2026-05-05T22:30:54.885501Z","end":"2026-05-05T22:30:55.012749Z","steps":["trace[1194223287] 'process raft request' (duration: 127.052548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:30:55.013178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.5774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-05T22:30:55.013234Z","caller":"traceutil/trace.go:171","msg":"trace[1470451771] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8592; }","duration":"127.699512ms","start":"2026-05-05T22:30:54.885518Z","end":"2026-05-05T22:30:55.013218Z","steps":["trace[1470451771] 'agreement among raft nodes before linearized reading' (duration: 127.500537ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:30:55.42392Z","caller":"traceutil/trace.go:171","msg":"trace[1819629736] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"171.935879ms","start":"2026-05-05T22:30:55.251941Z","end":"2026-05-05T22:30:55.423877Z","steps":["trace[1819629736] 'process raft request' (duration: 167.762566ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:31:42.898506Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000249c.wal"} {"level":"info","ts":"2026-05-05T22:32:06.702198Z","caller":"traceutil/trace.go:171","msg":"trace[521868192] linearizableReadLoop","detail":"{readStateIndex:9571; appliedIndex:9570; }","duration":"246.051457ms","start":"2026-05-05T22:32:06.456116Z","end":"2026-05-05T22:32:06.702168Z","steps":["trace[521868192] 'read index received' (duration: 245.945905ms)","trace[521868192] 'applied index is now lower than readState.Index' (duration: 103.722µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:32:06.702236Z","caller":"traceutil/trace.go:171","msg":"trace[1416058818] transaction","detail":"{read_only:false; response_revision:9245; number_of_response:1; }","duration":"251.754141ms","start":"2026-05-05T22:32:06.450454Z","end":"2026-05-05T22:32:06.702208Z","steps":["trace[1416058818] 'process raft request' (duration: 251.543787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:32:06.702462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.326253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-27xls\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-05T22:32:06.702531Z","caller":"traceutil/trace.go:171","msg":"trace[1773325822] range","detail":"{range_begin:/registry/pods/openstack/keepalived-27xls; range_end:; response_count:1; response_revision:9245; }","duration":"246.430965ms","start":"2026-05-05T22:32:06.456083Z","end":"2026-05-05T22:32:06.702514Z","steps":["trace[1773325822] 'agreement among raft nodes before linearized reading' (duration: 246.20703ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:32:06.721709Z","caller":"traceutil/trace.go:171","msg":"trace[906707714] transaction","detail":"{read_only:false; response_revision:9246; number_of_response:1; }","duration":"126.312664ms","start":"2026-05-05T22:32:06.595365Z","end":"2026-05-05T22:32:06.721677Z","steps":["trace[906707714] 'process raft request' (duration: 126.1224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:32:06.722032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.318763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T22:32:06.722077Z","caller":"traceutil/trace.go:171","msg":"trace[1193689205] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:9246; }","duration":"129.421886ms","start":"2026-05-05T22:32:06.592645Z","end":"2026-05-05T22:32:06.722067Z","steps":["trace[1193689205] 'agreement among raft nodes before linearized reading' (duration: 129.247522ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:32:52.607711Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1c917701ce165bac","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T22:32:52.614251Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T22:32:52.614413Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-05T22:35:21.047872Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8316} {"level":"info","ts":"2026-05-05T22:35:21.127161Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8316,"took":"76.609817ms","hash":373840478,"current-db-size-bytes":57405440,"current-db-size":"57 MB","current-db-size-in-use-bytes":26615808,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-05T22:35:21.127223Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":373840478,"revision":8316,"compact-revision":6111} {"level":"info","ts":"2026-05-05T22:35:46.842045Z","caller":"traceutil/trace.go:171","msg":"trace[1595729745] transaction","detail":"{read_only:false; response_revision:11170; number_of_response:1; }","duration":"153.32577ms","start":"2026-05-05T22:35:46.688687Z","end":"2026-05-05T22:35:46.842013Z","steps":["trace[1595729745] 'process raft request' (duration: 95.186462ms)","trace[1595729745] 'compare' (duration: 58.018175ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:35:46.890538Z","caller":"traceutil/trace.go:171","msg":"trace[2136586995] transaction","detail":"{read_only:false; response_revision:11172; number_of_response:1; }","duration":"201.658151ms","start":"2026-05-05T22:35:46.688847Z","end":"2026-05-05T22:35:46.890505Z","steps":["trace[2136586995] 'process raft request' (duration: 201.59132ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:35:46.890606Z","caller":"traceutil/trace.go:171","msg":"trace[2061387844] transaction","detail":"{read_only:false; response_revision:11171; number_of_response:1; }","duration":"201.808823ms","start":"2026-05-05T22:35:46.688767Z","end":"2026-05-05T22:35:46.890576Z","steps":["trace[2061387844] 'process raft request' (duration: 201.478548ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:35:46.935627Z","caller":"traceutil/trace.go:171","msg":"trace[719408157] transaction","detail":"{read_only:false; response_revision:11173; number_of_response:1; }","duration":"110.781447ms","start":"2026-05-05T22:35:46.824822Z","end":"2026-05-05T22:35:46.935604Z","steps":["trace[719408157] 'process raft request' (duration: 110.656395ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:40:21.06867Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10983} {"level":"info","ts":"2026-05-05T22:40:21.149589Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10983,"took":"77.942448ms","hash":4282050353,"current-db-size-bytes":57405440,"current-db-size":"57 MB","current-db-size-in-use-bytes":28160000,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-05T22:40:21.149663Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4282050353,"revision":10983,"compact-revision":8316} {"level":"info","ts":"2026-05-05T22:41:29.123763Z","caller":"traceutil/trace.go:171","msg":"trace[1444548047] transaction","detail":"{read_only:false; response_revision:13522; number_of_response:1; }","duration":"111.842655ms","start":"2026-05-05T22:41:29.011881Z","end":"2026-05-05T22:41:29.123723Z","steps":["trace[1444548047] 'process raft request' (duration: 111.665591ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:42:27.084234Z","caller":"traceutil/trace.go:171","msg":"trace[1672702655] transaction","detail":"{read_only:false; response_revision:14034; number_of_response:1; }","duration":"120.039808ms","start":"2026-05-05T22:42:26.964163Z","end":"2026-05-05T22:42:27.084203Z","steps":["trace[1672702655] 'process raft request' (duration: 119.821264ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:42:27.215358Z","caller":"traceutil/trace.go:171","msg":"trace[1717776532] linearizableReadLoop","detail":"{readStateIndex:14526; appliedIndex:14523; }","duration":"227.333972ms","start":"2026-05-05T22:42:26.987997Z","end":"2026-05-05T22:42:27.215331Z","steps":["trace[1717776532] 'read index received' (duration: 96.014938ms)","trace[1717776532] 'applied index is now lower than readState.Index' (duration: 131.317654ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:42:27.215415Z","caller":"traceutil/trace.go:171","msg":"trace[5002885] transaction","detail":"{read_only:false; response_revision:14035; number_of_response:1; }","duration":"251.036175ms","start":"2026-05-05T22:42:26.96434Z","end":"2026-05-05T22:42:27.215376Z","steps":["trace[5002885] 'process raft request' (duration: 207.648548ms)","trace[5002885] 'compare' (duration: 42.944038ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:42:27.215431Z","caller":"traceutil/trace.go:171","msg":"trace[943894191] transaction","detail":"{read_only:false; response_revision:14036; number_of_response:1; }","duration":"250.841201ms","start":"2026-05-05T22:42:26.964559Z","end":"2026-05-05T22:42:27.2154Z","steps":["trace[943894191] 'process raft request' (duration: 250.659738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:42:27.215611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.614948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T22:42:27.21561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.586377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-87fae54d-8138-42bb-994d-ab6056b9bba8\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-05-05T22:42:27.215657Z","caller":"traceutil/trace.go:171","msg":"trace[1799376595] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:14036; }","duration":"196.71036ms","start":"2026-05-05T22:42:27.018935Z","end":"2026-05-05T22:42:27.215645Z","steps":["trace[1799376595] 'agreement among raft nodes before linearized reading' (duration: 196.560927ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:42:27.215689Z","caller":"traceutil/trace.go:171","msg":"trace[2013620833] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-87fae54d-8138-42bb-994d-ab6056b9bba8; range_end:; response_count:1; response_revision:14036; }","duration":"227.672718ms","start":"2026-05-05T22:42:26.98798Z","end":"2026-05-05T22:42:27.215653Z","steps":["trace[2013620833] 'agreement among raft nodes before linearized reading' (duration: 227.450904ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:43:53.021143Z","caller":"traceutil/trace.go:171","msg":"trace[1185343932] transaction","detail":"{read_only:false; response_revision:14843; number_of_response:1; }","duration":"128.881415ms","start":"2026-05-05T22:43:52.892229Z","end":"2026-05-05T22:43:53.02111Z","steps":["trace[1185343932] 'process raft request' (duration: 128.718142ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:43:53.138911Z","caller":"traceutil/trace.go:171","msg":"trace[1384043827] transaction","detail":"{read_only:false; response_revision:14845; number_of_response:1; }","duration":"112.039859ms","start":"2026-05-05T22:43:53.026828Z","end":"2026-05-05T22:43:53.138867Z","steps":["trace[1384043827] 'process raft request' (duration: 88.175763ms)","trace[1384043827] 'compare' (duration: 23.515829ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:45:21.074223Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13040} {"level":"info","ts":"2026-05-05T22:45:21.151403Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13040,"took":"74.537955ms","hash":2485496697,"current-db-size-bytes":57405440,"current-db-size":"57 MB","current-db-size-in-use-bytes":30920704,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-05T22:45:21.151456Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2485496697,"revision":13040,"compact-revision":10983} {"level":"info","ts":"2026-05-05T22:46:28.158427Z","caller":"traceutil/trace.go:171","msg":"trace[1956672419] transaction","detail":"{read_only:false; response_revision:16173; number_of_response:1; }","duration":"140.975998ms","start":"2026-05-05T22:46:28.017419Z","end":"2026-05-05T22:46:28.158395Z","steps":["trace[1956672419] 'process raft request' (duration: 140.738653ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:48:58.601234Z","caller":"traceutil/trace.go:171","msg":"trace[808239671] linearizableReadLoop","detail":"{readStateIndex:17767; appliedIndex:17766; }","duration":"135.253223ms","start":"2026-05-05T22:48:58.465945Z","end":"2026-05-05T22:48:58.601198Z","steps":["trace[808239671] 'read index received' (duration: 102.282404ms)","trace[808239671] 'applied index is now lower than readState.Index' (duration: 32.969779ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:48:58.601345Z","caller":"traceutil/trace.go:171","msg":"trace[608102955] transaction","detail":"{read_only:false; response_revision:17177; number_of_response:1; }","duration":"188.15835ms","start":"2026-05-05T22:48:58.413153Z","end":"2026-05-05T22:48:58.601311Z","steps":["trace[608102955] 'process raft request' (duration: 155.092909ms)","trace[608102955] 'compare' (duration: 32.765625ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:48:58.601471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.516858ms","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-05T22:48:58.601514Z","caller":"traceutil/trace.go:171","msg":"trace[1449568518] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17177; }","duration":"135.61048ms","start":"2026-05-05T22:48:58.465888Z","end":"2026-05-05T22:48:58.601498Z","steps":["trace[1449568518] 'agreement among raft nodes before linearized reading' (duration: 135.465987ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:49:04.378178Z","caller":"traceutil/trace.go:171","msg":"trace[1036258511] transaction","detail":"{read_only:false; response_revision:17232; number_of_response:1; }","duration":"167.031998ms","start":"2026-05-05T22:49:04.211115Z","end":"2026-05-05T22:49:04.378147Z","steps":["trace[1036258511] 'process raft request' (duration: 138.204702ms)","trace[1036258511] 'compare' (duration: 28.226144ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:50:21.079951Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15423} {"level":"info","ts":"2026-05-05T22:50:21.17535Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15423,"took":"91.952257ms","hash":2430889275,"current-db-size-bytes":57405440,"current-db-size":"57 MB","current-db-size-in-use-bytes":34250752,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-05T22:50:21.175423Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2430889275,"revision":15423,"compact-revision":13040} {"level":"info","ts":"2026-05-05T22:53:06.472052Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1c917701ce165bac","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T22:53:06.478008Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-05T22:53:06.478133Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-05T22:54:16.180122Z","caller":"traceutil/trace.go:171","msg":"trace[1457783517] linearizableReadLoop","detail":"{readStateIndex:20403; appliedIndex:20402; }","duration":"151.151084ms","start":"2026-05-05T22:54:16.028946Z","end":"2026-05-05T22:54:16.180097Z","steps":["trace[1457783517] 'read index received' (duration: 117.551411ms)","trace[1457783517] 'applied index is now lower than readState.Index' (duration: 33.598443ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:54:16.180211Z","caller":"traceutil/trace.go:171","msg":"trace[1655693308] transaction","detail":"{read_only:false; response_revision:19733; number_of_response:1; }","duration":"189.468412ms","start":"2026-05-05T22:54:15.99071Z","end":"2026-05-05T22:54:16.180179Z","steps":["trace[1655693308] 'process raft request' (duration: 155.87211ms)","trace[1655693308] 'compare' (duration: 33.399088ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:54:16.180429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.453311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-05T22:54:16.180474Z","caller":"traceutil/trace.go:171","msg":"trace[580009183] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:19733; }","duration":"151.521982ms","start":"2026-05-05T22:54:16.02894Z","end":"2026-05-05T22:54:16.180462Z","steps":["trace[580009183] 'agreement among raft nodes before linearized reading' (duration: 151.306958ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:54:16.350255Z","caller":"traceutil/trace.go:171","msg":"trace[1087846547] linearizableReadLoop","detail":"{readStateIndex:20404; appliedIndex:20404; }","duration":"163.185675ms","start":"2026-05-05T22:54:16.187049Z","end":"2026-05-05T22:54:16.350234Z","steps":["trace[1087846547] 'read index received' (duration: 163.177745ms)","trace[1087846547] 'applied index is now lower than readState.Index' (duration: 6.46µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:54:16.350251Z","caller":"traceutil/trace.go:171","msg":"trace[2040105353] transaction","detail":"{read_only:false; response_revision:19734; number_of_response:1; }","duration":"163.832388ms","start":"2026-05-05T22:54:16.18639Z","end":"2026-05-05T22:54:16.350222Z","steps":["trace[2040105353] 'process raft request' (duration: 163.626314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:54:16.350474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.40976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T22:54:16.3505Z","caller":"traceutil/trace.go:171","msg":"trace[800240060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19734; }","duration":"163.449542ms","start":"2026-05-05T22:54:16.187044Z","end":"2026-05-05T22:54:16.350494Z","steps":["trace[800240060] 'agreement among raft nodes before linearized reading' (duration: 163.263938ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:54:16.352194Z","caller":"traceutil/trace.go:171","msg":"trace[715629204] transaction","detail":"{read_only:false; response_revision:19735; number_of_response:1; }","duration":"165.060903ms","start":"2026-05-05T22:54:16.187051Z","end":"2026-05-05T22:54:16.352112Z","steps":["trace[715629204] 'process raft request' (duration: 164.730277ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:54:27.011835Z","caller":"traceutil/trace.go:171","msg":"trace[1008385510] linearizableReadLoop","detail":"{readStateIndex:20463; appliedIndex:20462; }","duration":"118.184575ms","start":"2026-05-05T22:54:26.893623Z","end":"2026-05-05T22:54:27.011808Z","steps":["trace[1008385510] 'read index received' (duration: 118.010582ms)","trace[1008385510] 'applied index is now lower than readState.Index' (duration: 171.993µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:54:27.01188Z","caller":"traceutil/trace.go:171","msg":"trace[94075888] transaction","detail":"{read_only:false; response_revision:19791; number_of_response:1; }","duration":"131.870509ms","start":"2026-05-05T22:54:26.879973Z","end":"2026-05-05T22:54:27.011843Z","steps":["trace[94075888] 'process raft request' (duration: 131.686925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:54:27.01221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.572553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T22:54:27.012265Z","caller":"traceutil/trace.go:171","msg":"trace[1102407537] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19791; }","duration":"118.678535ms","start":"2026-05-05T22:54:26.89357Z","end":"2026-05-05T22:54:27.012249Z","steps":["trace[1102407537] 'agreement among raft nodes before linearized reading' (duration: 118.335778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:54:27.314977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.217655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T22:54:27.315087Z","caller":"traceutil/trace.go:171","msg":"trace[1122068880] linearizableReadLoop","detail":"{readStateIndex:20464; appliedIndex:20463; }","duration":"245.967732ms","start":"2026-05-05T22:54:27.069107Z","end":"2026-05-05T22:54:27.315075Z","steps":["trace[1122068880] 'read index received' (duration: 106.637815ms)","trace[1122068880] 'applied index is now lower than readState.Index' (duration: 139.328667ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:54:27.315139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.030314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T22:54:27.315157Z","caller":"traceutil/trace.go:171","msg":"trace[691355813] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:19792; }","duration":"246.087765ms","start":"2026-05-05T22:54:27.069061Z","end":"2026-05-05T22:54:27.315149Z","steps":["trace[691355813] 'agreement among raft nodes before linearized reading' (duration: 246.044294ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:54:27.315302Z","caller":"traceutil/trace.go:171","msg":"trace[357684041] transaction","detail":"{read_only:false; response_revision:19792; number_of_response:1; }","duration":"298.794469ms","start":"2026-05-05T22:54:27.016498Z","end":"2026-05-05T22:54:27.315293Z","steps":["trace[357684041] 'process raft request' (duration: 159.200706ms)","trace[357684041] 'compare' (duration: 139.129634ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:54:28.18928Z","caller":"traceutil/trace.go:171","msg":"trace[1923796407] linearizableReadLoop","detail":"{readStateIndex:20465; appliedIndex:20464; }","duration":"137.001582ms","start":"2026-05-05T22:54:28.05225Z","end":"2026-05-05T22:54:28.189252Z","steps":["trace[1923796407] 'read index received' (duration: 136.772948ms)","trace[1923796407] 'applied index is now lower than readState.Index' (duration: 227.634µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:54:28.189324Z","caller":"traceutil/trace.go:171","msg":"trace[954676783] transaction","detail":"{read_only:false; response_revision:19793; number_of_response:1; }","duration":"139.861319ms","start":"2026-05-05T22:54:28.049435Z","end":"2026-05-05T22:54:28.189296Z","steps":["trace[954676783] 'process raft request' (duration: 139.644885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:54:28.189472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.190106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T22:54:28.189518Z","caller":"traceutil/trace.go:171","msg":"trace[400058756] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:19793; }","duration":"137.319468ms","start":"2026-05-05T22:54:28.052186Z","end":"2026-05-05T22:54:28.189505Z","steps":["trace[400058756] 'agreement among raft nodes before linearized reading' (duration: 137.201676ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:55:21.08647Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18066} {"level":"info","ts":"2026-05-05T22:55:21.207817Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18066,"took":"117.572393ms","hash":432775723,"current-db-size-bytes":57405440,"current-db-size":"57 MB","current-db-size-in-use-bytes":30511104,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-05T22:55:21.207889Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":432775723,"revision":18066,"compact-revision":15423} {"level":"info","ts":"2026-05-05T22:58:02.0014Z","caller":"traceutil/trace.go:171","msg":"trace[2122452707] transaction","detail":"{read_only:false; response_revision:22125; number_of_response:1; }","duration":"145.247243ms","start":"2026-05-05T22:58:01.856125Z","end":"2026-05-05T22:58:02.001372Z","steps":["trace[2122452707] 'process raft request' (duration: 143.952586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:58:02.420017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.795314ms","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-05T22:58:02.420101Z","caller":"traceutil/trace.go:171","msg":"trace[1574817684] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22125; }","duration":"261.915746ms","start":"2026-05-05T22:58:02.158167Z","end":"2026-05-05T22:58:02.420083Z","steps":["trace[1574817684] 'range keys from in-memory index tree' (duration: 261.641431ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:58:02.420459Z","caller":"traceutil/trace.go:171","msg":"trace[586798168] linearizableReadLoop","detail":"{readStateIndex:22865; appliedIndex:22864; }","duration":"184.645091ms","start":"2026-05-05T22:58:02.235805Z","end":"2026-05-05T22:58:02.42045Z","steps":["trace[586798168] 'read index received' (duration: 155.044562ms)","trace[586798168] 'applied index is now lower than readState.Index' (duration: 29.599859ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:58:02.420528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.723652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T22:58:02.420548Z","caller":"traceutil/trace.go:171","msg":"trace[1797188621] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:22126; }","duration":"184.787264ms","start":"2026-05-05T22:58:02.235754Z","end":"2026-05-05T22:58:02.420541Z","steps":["trace[1797188621] 'agreement among raft nodes before linearized reading' (duration: 184.730023ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:58:02.420729Z","caller":"traceutil/trace.go:171","msg":"trace[336901494] transaction","detail":"{read_only:false; response_revision:22126; number_of_response:1; }","duration":"243.273499ms","start":"2026-05-05T22:58:02.177404Z","end":"2026-05-05T22:58:02.420678Z","steps":["trace[336901494] 'process raft request' (duration: 213.499186ms)","trace[336901494] 'compare' (duration: 29.415355ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:00:21.094499Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20366} {"level":"info","ts":"2026-05-05T23:00:21.198872Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20366,"took":"100.777512ms","hash":1795872907,"current-db-size-bytes":57827328,"current-db-size":"58 MB","current-db-size-in-use-bytes":43126784,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-05-05T23:00:21.19893Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1795872907,"revision":20366,"compact-revision":18066} {"level":"info","ts":"2026-05-05T23:01:06.000072Z","caller":"traceutil/trace.go:171","msg":"trace[1167561456] transaction","detail":"{read_only:false; response_revision:24095; number_of_response:1; }","duration":"116.237124ms","start":"2026-05-05T23:01:05.88381Z","end":"2026-05-05T23:01:06.000047Z","steps":["trace[1167561456] 'process raft request' (duration: 116.146972ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:06.000103Z","caller":"traceutil/trace.go:171","msg":"trace[738812721] transaction","detail":"{read_only:false; response_revision:24094; number_of_response:1; }","duration":"179.761042ms","start":"2026-05-05T23:01:05.820314Z","end":"2026-05-05T23:01:06.000075Z","steps":["trace[738812721] 'process raft request' (duration: 176.69321ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:06.662891Z","caller":"traceutil/trace.go:171","msg":"trace[879049942] linearizableReadLoop","detail":"{readStateIndex:24887; appliedIndex:24886; }","duration":"230.461929ms","start":"2026-05-05T23:01:06.432401Z","end":"2026-05-05T23:01:06.662863Z","steps":["trace[879049942] 'read index received' (duration: 229.866367ms)","trace[879049942] 'applied index is now lower than readState.Index' (duration: 594.682µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:06.662897Z","caller":"traceutil/trace.go:171","msg":"trace[251306001] transaction","detail":"{read_only:false; response_revision:24099; number_of_response:1; }","duration":"250.385233ms","start":"2026-05-05T23:01:06.412488Z","end":"2026-05-05T23:01:06.662873Z","steps":["trace[251306001] 'process raft request' (duration: 249.830081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:06.663028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.613362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:06.663052Z","caller":"traceutil/trace.go:171","msg":"trace[1095195782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24099; }","duration":"230.679994ms","start":"2026-05-05T23:01:06.432365Z","end":"2026-05-05T23:01:06.663045Z","steps":["trace[1095195782] 'agreement among raft nodes before linearized reading' (duration: 230.594792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:06.663064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.809492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:06.663144Z","caller":"traceutil/trace.go:171","msg":"trace[669798437] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:24099; }","duration":"212.898764ms","start":"2026-05-05T23:01:06.450225Z","end":"2026-05-05T23:01:06.663124Z","steps":["trace[669798437] 'agreement among raft nodes before linearized reading' (duration: 212.764931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:06.663169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.035785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:06.66326Z","caller":"traceutil/trace.go:171","msg":"trace[1147089060] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:24099; }","duration":"167.185028ms","start":"2026-05-05T23:01:06.496057Z","end":"2026-05-05T23:01:06.663242Z","steps":["trace[1147089060] 'agreement among raft nodes before linearized reading' (duration: 166.986604ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:11.126238Z","caller":"traceutil/trace.go:171","msg":"trace[2121992168] transaction","detail":"{read_only:false; response_revision:24161; number_of_response:1; }","duration":"124.671775ms","start":"2026-05-05T23:01:11.00154Z","end":"2026-05-05T23:01:11.126212Z","steps":["trace[2121992168] 'process raft request' (duration: 124.351039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:16.825795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.152563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T23:01:16.825951Z","caller":"traceutil/trace.go:171","msg":"trace[626633070] transaction","detail":"{read_only:false; response_revision:24202; number_of_response:1; }","duration":"298.980029ms","start":"2026-05-05T23:01:16.526944Z","end":"2026-05-05T23:01:16.825924Z","steps":["trace[626633070] 'process raft request' (duration: 195.584231ms)","trace[626633070] 'compare' (duration: 102.941379ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:16.916212Z","caller":"traceutil/trace.go:171","msg":"trace[634461000] transaction","detail":"{read_only:false; response_revision:24203; number_of_response:1; }","duration":"388.774453ms","start":"2026-05-05T23:01:16.52741Z","end":"2026-05-05T23:01:16.916184Z","steps":["trace[634461000] 'process raft request' (duration: 388.514138ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:16.91622Z","caller":"traceutil/trace.go:171","msg":"trace[1694346006] linearizableReadLoop","detail":"{readStateIndex:24993; appliedIndex:24991; }","duration":"213.893288ms","start":"2026-05-05T23:01:16.702178Z","end":"2026-05-05T23:01:16.916071Z","steps":["trace[1694346006] 'read index received' (duration: 20.335178ms)","trace[1694346006] 'applied index is now lower than readState.Index' (duration: 193.55676ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:16.916383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:16.527394Z","time spent":"388.901895ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:16.916424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.234144ms","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-05T23:01:16.916472Z","caller":"traceutil/trace.go:171","msg":"trace[2080272458] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24203; }","duration":"214.314446ms","start":"2026-05-05T23:01:16.702146Z","end":"2026-05-05T23:01:16.91646Z","steps":["trace[2080272458] 'agreement among raft nodes before linearized reading' (duration: 214.120923ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:16.931197Z","caller":"traceutil/trace.go:171","msg":"trace[2131067011] transaction","detail":"{read_only:false; response_revision:24204; number_of_response:1; }","duration":"169.263101ms","start":"2026-05-05T23:01:16.761905Z","end":"2026-05-05T23:01:16.931168Z","steps":["trace[2131067011] 'process raft request' (duration: 167.825472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:16.931436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.599274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T23:01:16.931544Z","caller":"traceutil/trace.go:171","msg":"trace[1696975281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24205; }","duration":"226.724606ms","start":"2026-05-05T23:01:16.704795Z","end":"2026-05-05T23:01:16.93152Z","steps":["trace[1696975281] 'agreement among raft nodes before linearized reading' (duration: 226.426759ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:17.447345Z","caller":"traceutil/trace.go:171","msg":"trace[2094399773] transaction","detail":"{read_only:false; response_revision:24207; number_of_response:1; }","duration":"303.5294ms","start":"2026-05-05T23:01:17.143694Z","end":"2026-05-05T23:01:17.447223Z","steps":["trace[2094399773] 'process raft request' (duration: 303.279045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:17.447609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:17.143663Z","time spent":"303.734624ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:17.682205Z","caller":"traceutil/trace.go:171","msg":"trace[1292272910] linearizableReadLoop","detail":"{readStateIndex:24998; appliedIndex:24996; }","duration":"514.904137ms","start":"2026-05-05T23:01:17.167277Z","end":"2026-05-05T23:01:17.682181Z","steps":["trace[1292272910] 'read index received' (duration: 279.696811ms)","trace[1292272910] 'applied index is now lower than readState.Index' (duration: 235.206176ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:17.68225Z","caller":"traceutil/trace.go:171","msg":"trace[1548054551] transaction","detail":"{read_only:false; response_revision:24208; number_of_response:1; }","duration":"537.059622ms","start":"2026-05-05T23:01:17.145161Z","end":"2026-05-05T23:01:17.68222Z","steps":["trace[1548054551] 'process raft request' (duration: 535.618363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:17.682328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.03246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T23:01:17.682376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.262679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:17.682409Z","caller":"traceutil/trace.go:171","msg":"trace[1105707702] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:24208; }","duration":"515.169692ms","start":"2026-05-05T23:01:17.167219Z","end":"2026-05-05T23:01:17.682389Z","steps":["trace[1105707702] 'agreement among raft nodes before linearized reading' (duration: 515.03481ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:17.682425Z","caller":"traceutil/trace.go:171","msg":"trace[1587227967] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:24208; }","duration":"459.394792ms","start":"2026-05-05T23:01:17.223016Z","end":"2026-05-05T23:01:17.682411Z","steps":["trace[1587227967] 'agreement among raft nodes before linearized reading' (duration: 459.29799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:17.682436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:17.145148Z","time spent":"537.161235ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:17.682453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:17.167195Z","time spent":"515.247863ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true "} {"level":"warn","ts":"2026-05-05T23:01:17.682465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:17.222987Z","time spent":"459.468523ms","remote":"127.0.0.1:41574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-05T23:01:17.682479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.253878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T23:01:17.682525Z","caller":"traceutil/trace.go:171","msg":"trace[1839755894] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24208; }","duration":"131.33957ms","start":"2026-05-05T23:01:17.55117Z","end":"2026-05-05T23:01:17.68251Z","steps":["trace[1839755894] 'agreement among raft nodes before linearized reading' (duration: 131.232878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:17.682683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.407961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-05T23:01:17.682689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.978817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:17.682746Z","caller":"traceutil/trace.go:171","msg":"trace[442704288] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24208; }","duration":"137.488053ms","start":"2026-05-05T23:01:17.545231Z","end":"2026-05-05T23:01:17.682719Z","steps":["trace[442704288] 'agreement among raft nodes before linearized reading' (duration: 137.087125ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:17.682769Z","caller":"traceutil/trace.go:171","msg":"trace[467272016] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:24208; }","duration":"284.111749ms","start":"2026-05-05T23:01:17.398635Z","end":"2026-05-05T23:01:17.682746Z","steps":["trace[467272016] 'agreement among raft nodes before linearized reading' (duration: 283.917366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:17.682807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.872355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T23:01:17.68286Z","caller":"traceutil/trace.go:171","msg":"trace[1352079172] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:24208; }","duration":"394.930696ms","start":"2026-05-05T23:01:17.287918Z","end":"2026-05-05T23:01:17.682848Z","steps":["trace[1352079172] 'agreement among raft nodes before linearized reading' (duration: 394.843705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:17.682898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:17.287864Z","time spent":"395.025908ms","remote":"127.0.0.1:43466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T23:01:17.683018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.674628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:17.683058Z","caller":"traceutil/trace.go:171","msg":"trace[399089980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24208; }","duration":"251.755959ms","start":"2026-05-05T23:01:17.431292Z","end":"2026-05-05T23:01:17.683048Z","steps":["trace[399089980] 'agreement among raft nodes before linearized reading' (duration: 251.699498ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:17.902075Z","caller":"traceutil/trace.go:171","msg":"trace[146083887] transaction","detail":"{read_only:false; response_revision:24211; number_of_response:1; }","duration":"178.614359ms","start":"2026-05-05T23:01:17.723438Z","end":"2026-05-05T23:01:17.902052Z","steps":["trace[146083887] 'process raft request' (duration: 178.568388ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:17.902284Z","caller":"traceutil/trace.go:171","msg":"trace[413084069] transaction","detail":"{read_only:false; response_revision:24210; number_of_response:1; }","duration":"214.688694ms","start":"2026-05-05T23:01:17.687568Z","end":"2026-05-05T23:01:17.902256Z","steps":["trace[413084069] 'process raft request' (duration: 161.602367ms)","trace[413084069] 'compare' (duration: 52.703779ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:18.159547Z","caller":"traceutil/trace.go:171","msg":"trace[1661884955] linearizableReadLoop","detail":"{readStateIndex:25002; appliedIndex:25001; }","duration":"118.060062ms","start":"2026-05-05T23:01:18.041466Z","end":"2026-05-05T23:01:18.159526Z","steps":["trace[1661884955] 'read index received' (duration: 117.94517ms)","trace[1661884955] 'applied index is now lower than readState.Index' (duration: 113.912µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:18.159677Z","caller":"traceutil/trace.go:171","msg":"trace[2093273583] transaction","detail":"{read_only:false; response_revision:24212; number_of_response:1; }","duration":"119.339268ms","start":"2026-05-05T23:01:18.040297Z","end":"2026-05-05T23:01:18.159637Z","steps":["trace[2093273583] 'process raft request' (duration: 118.9332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:18.159736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.248386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-05T23:01:18.159764Z","caller":"traceutil/trace.go:171","msg":"trace[648809755] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24212; }","duration":"118.324148ms","start":"2026-05-05T23:01:18.041431Z","end":"2026-05-05T23:01:18.159755Z","steps":["trace[648809755] 'agreement among raft nodes before linearized reading' (duration: 118.187375ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:18.599457Z","caller":"traceutil/trace.go:171","msg":"trace[358750816] transaction","detail":"{read_only:false; response_revision:24213; number_of_response:1; }","duration":"557.656136ms","start":"2026-05-05T23:01:18.041768Z","end":"2026-05-05T23:01:18.599425Z","steps":["trace[358750816] 'process raft request' (duration: 549.874789ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:18.599313Z","caller":"traceutil/trace.go:171","msg":"trace[2034578121] linearizableReadLoop","detail":"{readStateIndex:25003; appliedIndex:25002; }","duration":"439.542943ms","start":"2026-05-05T23:01:18.159596Z","end":"2026-05-05T23:01:18.599139Z","steps":["trace[2034578121] 'read index received' (duration: 432.042282ms)","trace[2034578121] 'applied index is now lower than readState.Index' (duration: 7.499611ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:18.59963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:18.041754Z","time spent":"557.767968ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:18.599794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.381115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-05T23:01:18.599871Z","caller":"traceutil/trace.go:171","msg":"trace[390674496] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:24213; }","duration":"489.513628ms","start":"2026-05-05T23:01:18.110341Z","end":"2026-05-05T23:01:18.599855Z","steps":["trace[390674496] 'agreement among raft nodes before linearized reading' (duration: 489.212332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:18.599914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:18.11032Z","time spent":"489.583739ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":177,"response size":32,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-05-05T23:01:18.638227Z","caller":"traceutil/trace.go:171","msg":"trace[270532504] transaction","detail":"{read_only:false; response_revision:24214; number_of_response:1; }","duration":"124.172375ms","start":"2026-05-05T23:01:18.514029Z","end":"2026-05-05T23:01:18.638201Z","steps":["trace[270532504] 'process raft request' (duration: 123.953301ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:18.638417Z","caller":"traceutil/trace.go:171","msg":"trace[1015668301] transaction","detail":"{read_only:false; response_revision:24215; number_of_response:1; }","duration":"110.364737ms","start":"2026-05-05T23:01:18.528043Z","end":"2026-05-05T23:01:18.638407Z","steps":["trace[1015668301] 'process raft request' (duration: 110.115512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:18.638475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.908358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T23:01:18.638524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.895253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:18.638539Z","caller":"traceutil/trace.go:171","msg":"trace[245048760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24215; }","duration":"207.014231ms","start":"2026-05-05T23:01:18.431507Z","end":"2026-05-05T23:01:18.638521Z","steps":["trace[245048760] 'agreement among raft nodes before linearized reading' (duration: 206.913609ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:18.638571Z","caller":"traceutil/trace.go:171","msg":"trace[800396115] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:24215; }","duration":"373.003045ms","start":"2026-05-05T23:01:18.265554Z","end":"2026-05-05T23:01:18.638557Z","steps":["trace[800396115] 'agreement among raft nodes before linearized reading' (duration: 372.883593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:18.638482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.202849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18368"} {"level":"warn","ts":"2026-05-05T23:01:18.6386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:18.265527Z","time spent":"373.065597ms","remote":"127.0.0.1:41184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"info","ts":"2026-05-05T23:01:18.638626Z","caller":"traceutil/trace.go:171","msg":"trace[1919712131] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24215; }","duration":"477.378282ms","start":"2026-05-05T23:01:18.161233Z","end":"2026-05-05T23:01:18.638611Z","steps":["trace[1919712131] 'agreement among raft nodes before linearized reading' (duration: 477.134717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:18.638676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:18.161213Z","time spent":"477.437524ms","remote":"127.0.0.1:51630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18391,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-05T23:01:18.962831Z","caller":"traceutil/trace.go:171","msg":"trace[494667481] linearizableReadLoop","detail":"{readStateIndex:25006; appliedIndex:25005; }","duration":"126.478891ms","start":"2026-05-05T23:01:18.836326Z","end":"2026-05-05T23:01:18.962805Z","steps":["trace[494667481] 'read index received' (duration: 126.356919ms)","trace[494667481] 'applied index is now lower than readState.Index' (duration: 120.202µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:18.962885Z","caller":"traceutil/trace.go:171","msg":"trace[481361629] transaction","detail":"{read_only:false; response_revision:24216; number_of_response:1; }","duration":"128.208457ms","start":"2026-05-05T23:01:18.834631Z","end":"2026-05-05T23:01:18.96284Z","steps":["trace[481361629] 'process raft request' (duration: 128.007243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:18.963145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.784477ms","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-05T23:01:18.963232Z","caller":"traceutil/trace.go:171","msg":"trace[2060254524] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24216; }","duration":"126.898639ms","start":"2026-05-05T23:01:18.83631Z","end":"2026-05-05T23:01:18.963208Z","steps":["trace[2060254524] 'agreement among raft nodes before linearized reading' (duration: 126.634444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:19.463827Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828451415028996,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T23:01:19.667001Z","caller":"traceutil/trace.go:171","msg":"trace[760962951] transaction","detail":"{read_only:false; response_revision:24218; number_of_response:1; }","duration":"730.994879ms","start":"2026-05-05T23:01:18.935987Z","end":"2026-05-05T23:01:19.666982Z","steps":["trace[760962951] 'process raft request' (duration: 730.952729ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:19.667067Z","caller":"traceutil/trace.go:171","msg":"trace[1719729641] transaction","detail":"{read_only:false; response_revision:24217; number_of_response:1; }","duration":"769.367531ms","start":"2026-05-05T23:01:18.897661Z","end":"2026-05-05T23:01:19.667028Z","steps":["trace[1719729641] 'process raft request' (duration: 690.437795ms)","trace[1719729641] 'compare' (duration: 78.730432ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:19.667137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:18.93597Z","time spent":"731.089141ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:19.667212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:18.897648Z","time spent":"769.487753ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:20.352821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.349573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T23:01:20.353083Z","caller":"traceutil/trace.go:171","msg":"trace[169471516] transaction","detail":"{read_only:false; response_revision:24219; number_of_response:1; }","duration":"1.303916222s","start":"2026-05-05T23:01:19.049126Z","end":"2026-05-05T23:01:20.353042Z","steps":["trace[169471516] 'process raft request' (duration: 1.032240642s)","trace[169471516] 'compare' (duration: 270.964396ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:20.353455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:19.049098Z","time spent":"1.30429931s","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:20.516431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.479565ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-05T23:01:20.517074Z","caller":"traceutil/trace.go:171","msg":"trace[634270697] transaction","detail":"{read_only:false; response_revision:24222; number_of_response:1; }","duration":"609.461077ms","start":"2026-05-05T23:01:19.907591Z","end":"2026-05-05T23:01:20.517052Z","steps":["trace[634270697] 'process raft request' (duration: 609.421826ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:20.517221Z","caller":"traceutil/trace.go:171","msg":"trace[1331563965] transaction","detail":"{read_only:false; response_revision:24220; number_of_response:1; }","duration":"1.064681234s","start":"2026-05-05T23:01:19.452524Z","end":"2026-05-05T23:01:20.517205Z","steps":["trace[1331563965] 'process raft request' (duration: 1.06402293s)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:20.517279Z","caller":"traceutil/trace.go:171","msg":"trace[1382373780] linearizableReadLoop","detail":"{readStateIndex:25011; appliedIndex:25006; }","duration":"1.554349215s","start":"2026-05-05T23:01:18.962901Z","end":"2026-05-05T23:01:20.51725Z","steps":["trace[1382373780] 'read index received' (duration: 625.147732ms)","trace[1382373780] 'applied index is now lower than readState.Index' (duration: 929.198613ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:20.517335Z","caller":"traceutil/trace.go:171","msg":"trace[1265848354] transaction","detail":"{read_only:false; response_revision:24221; number_of_response:1; }","duration":"829.290974ms","start":"2026-05-05T23:01:19.688012Z","end":"2026-05-05T23:01:20.517303Z","steps":["trace[1265848354] 'process raft request' (duration: 828.941696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:20.517514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:19.687975Z","time spent":"829.447947ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:20.517523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.559777664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T23:01:20.517572Z","caller":"traceutil/trace.go:171","msg":"trace[587522973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24222; }","duration":"1.559881796s","start":"2026-05-05T23:01:18.957677Z","end":"2026-05-05T23:01:20.517559Z","steps":["trace[587522973] 'agreement among raft nodes before linearized reading' (duration: 1.559695882s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:20.517615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:18.957655Z","time spent":"1.559952117s","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T23:01:20.517694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.597296647s","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-05T23:01:20.51774Z","caller":"traceutil/trace.go:171","msg":"trace[1604668772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24222; }","duration":"1.597383249s","start":"2026-05-05T23:01:18.920346Z","end":"2026-05-05T23:01:20.517729Z","steps":["trace[1604668772] 'agreement among raft nodes before linearized reading' (duration: 1.597242227s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:20.517755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.676023221s","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-05T23:01:20.517778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:18.920324Z","time spent":"1.5974467s","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T23:01:20.517787Z","caller":"traceutil/trace.go:171","msg":"trace[335532003] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:24222; }","duration":"1.676096502s","start":"2026-05-05T23:01:18.841677Z","end":"2026-05-05T23:01:20.517774Z","steps":["trace[335532003] 'agreement among raft nodes before linearized reading' (duration: 1.676045571s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:20.517815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:18.84165Z","time spent":"1.676158083s","remote":"127.0.0.1:51704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-05T23:01:20.517297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:19.452494Z","time spent":"1.064760526s","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:20.517956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:19.907546Z","time spent":"609.582429ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:20.518093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.582653863s","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-05T23:01:20.518127Z","caller":"traceutil/trace.go:171","msg":"trace[212094264] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24222; }","duration":"1.582727515s","start":"2026-05-05T23:01:18.935389Z","end":"2026-05-05T23:01:20.518117Z","steps":["trace[212094264] 'agreement among raft nodes before linearized reading' (duration: 1.582632353s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:20.518159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:18.935364Z","time spent":"1.582786386s","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T23:01:20.518902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.501978732s","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-05T23:01:20.51899Z","caller":"traceutil/trace.go:171","msg":"trace[2056954207] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:24223; }","duration":"1.502127195s","start":"2026-05-05T23:01:19.016843Z","end":"2026-05-05T23:01:20.518971Z","steps":["trace[2056954207] 'agreement among raft nodes before linearized reading' (duration: 1.501961182s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:20.519026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:19.01682Z","time spent":"1.502198966s","remote":"127.0.0.1:47544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T23:01:20.519202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.057427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"warn","ts":"2026-05-05T23:01:20.519251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086218046s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:20.519274Z","caller":"traceutil/trace.go:171","msg":"trace[267558963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24223; }","duration":"1.086280158s","start":"2026-05-05T23:01:19.432987Z","end":"2026-05-05T23:01:20.519267Z","steps":["trace[267558963] 'agreement among raft nodes before linearized reading' (duration: 1.086234047s)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:20.519278Z","caller":"traceutil/trace.go:171","msg":"trace[1856886299] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:24223; }","duration":"585.131749ms","start":"2026-05-05T23:01:19.934127Z","end":"2026-05-05T23:01:20.519258Z","steps":["trace[1856886299] 'agreement among raft nodes before linearized reading' (duration: 584.916634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:20.519324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.500268868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:20.51935Z","caller":"traceutil/trace.go:171","msg":"trace[1716127043] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:24223; }","duration":"1.500348099s","start":"2026-05-05T23:01:19.018997Z","end":"2026-05-05T23:01:20.519345Z","steps":["trace[1716127043] 'agreement among raft nodes before linearized reading' (duration: 1.500307399s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:20.519341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.041457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T23:01:20.519403Z","caller":"traceutil/trace.go:171","msg":"trace[1583153192] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24223; }","duration":"808.146629ms","start":"2026-05-05T23:01:19.711238Z","end":"2026-05-05T23:01:20.519385Z","steps":["trace[1583153192] 'agreement among raft nodes before linearized reading' (duration: 807.975966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:20.519441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:19.711216Z","time spent":"808.215281ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-05T23:01:20.519512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.396505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T23:01:20.51954Z","caller":"traceutil/trace.go:171","msg":"trace[2013020337] transaction","detail":"{read_only:false; response_revision:24223; number_of_response:1; }","duration":"354.272839ms","start":"2026-05-05T23:01:20.165254Z","end":"2026-05-05T23:01:20.519527Z","steps":["trace[2013020337] 'process raft request' (duration: 353.431402ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:20.519549Z","caller":"traceutil/trace.go:171","msg":"trace[452760058] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:24223; }","duration":"183.438176ms","start":"2026-05-05T23:01:20.336101Z","end":"2026-05-05T23:01:20.519539Z","steps":["trace[452760058] 'agreement among raft nodes before linearized reading' (duration: 183.355965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:20.519525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.627165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T23:01:20.519574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.508409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-05T23:01:20.519601Z","caller":"traceutil/trace.go:171","msg":"trace[626011796] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24223; }","duration":"613.564459ms","start":"2026-05-05T23:01:19.906029Z","end":"2026-05-05T23:01:20.519593Z","steps":["trace[626011796] 'agreement among raft nodes before linearized reading' (duration: 613.460947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:20.519608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:20.165229Z","time spent":"354.3352ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:20.519623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:19.906013Z","time spent":"613.605371ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T23:01:20.51961Z","caller":"traceutil/trace.go:171","msg":"trace[1233027444] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24223; }","duration":"665.777548ms","start":"2026-05-05T23:01:19.853811Z","end":"2026-05-05T23:01:20.519588Z","steps":["trace[1233027444] 'agreement among raft nodes before linearized reading' (duration: 665.606935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:20.519663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:19.853788Z","time spent":"665.862899ms","remote":"127.0.0.1:41332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-05T23:01:20.51967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.292818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:20.519709Z","caller":"traceutil/trace.go:171","msg":"trace[321871054] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:24223; }","duration":"764.333228ms","start":"2026-05-05T23:01:19.755363Z","end":"2026-05-05T23:01:20.519696Z","steps":["trace[321871054] 'agreement among raft nodes before linearized reading' (duration: 764.271067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:20.519733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.36314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T23:01:20.519739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:19.755301Z","time spent":"764.43014ms","remote":"127.0.0.1:41266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"info","ts":"2026-05-05T23:01:20.519764Z","caller":"traceutil/trace.go:171","msg":"trace[323498665] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:24223; }","duration":"240.43849ms","start":"2026-05-05T23:01:20.279317Z","end":"2026-05-05T23:01:20.519755Z","steps":["trace[323498665] 'agreement among raft nodes before linearized reading' (duration: 240.384539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:20.519322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:19.934063Z","time spent":"585.249491ms","remote":"127.0.0.1:51624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-05-05T23:01:20.519366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:19.01897Z","time spent":"1.50039162s","remote":"127.0.0.1:51780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-05T23:01:20.519295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:19.432964Z","time spent":"1.086327189s","remote":"127.0.0.1:51472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T23:01:20.777747Z","caller":"traceutil/trace.go:171","msg":"trace[1588483486] transaction","detail":"{read_only:false; response_revision:24229; number_of_response:1; }","duration":"173.411505ms","start":"2026-05-05T23:01:20.604312Z","end":"2026-05-05T23:01:20.777723Z","steps":["trace[1588483486] 'process raft request' (duration: 173.127329ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:20.778785Z","caller":"traceutil/trace.go:171","msg":"trace[46144312] transaction","detail":"{read_only:false; response_revision:24230; number_of_response:1; }","duration":"134.664096ms","start":"2026-05-05T23:01:20.644106Z","end":"2026-05-05T23:01:20.77877Z","steps":["trace[46144312] 'process raft request' (duration: 134.443592ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:21.081047Z","caller":"traceutil/trace.go:171","msg":"trace[1276258931] transaction","detail":"{read_only:false; response_revision:24231; number_of_response:1; }","duration":"109.891548ms","start":"2026-05-05T23:01:20.971129Z","end":"2026-05-05T23:01:21.08102Z","steps":["trace[1276258931] 'process raft request' (duration: 109.694644ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:22.813154Z","caller":"traceutil/trace.go:171","msg":"trace[263196496] transaction","detail":"{read_only:false; response_revision:24234; number_of_response:1; }","duration":"289.079158ms","start":"2026-05-05T23:01:22.524053Z","end":"2026-05-05T23:01:22.813133Z","steps":["trace[263196496] 'process raft request' (duration: 288.939045ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:23.135064Z","caller":"traceutil/trace.go:171","msg":"trace[307940005] transaction","detail":"{read_only:false; response_revision:24235; number_of_response:1; }","duration":"609.761803ms","start":"2026-05-05T23:01:22.525284Z","end":"2026-05-05T23:01:23.135046Z","steps":["trace[307940005] 'process raft request' (duration: 609.60757ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:23.13525Z","caller":"traceutil/trace.go:171","msg":"trace[866675093] transaction","detail":"{read_only:false; response_revision:24238; number_of_response:1; }","duration":"350.919691ms","start":"2026-05-05T23:01:22.784323Z","end":"2026-05-05T23:01:23.135243Z","steps":["trace[866675093] 'process raft request' (duration: 350.87603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:23.135312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:22.784288Z","time spent":"350.982073ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:23.135331Z","caller":"traceutil/trace.go:171","msg":"trace[415901843] linearizableReadLoop","detail":"{readStateIndex:25028; appliedIndex:25024; }","duration":"603.024167ms","start":"2026-05-05T23:01:22.532277Z","end":"2026-05-05T23:01:23.135301Z","steps":["trace[415901843] 'read index received' (duration: 280.743161ms)","trace[415901843] 'applied index is now lower than readState.Index' (duration: 322.279906ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:23.135461Z","caller":"traceutil/trace.go:171","msg":"trace[349580077] transaction","detail":"{read_only:false; response_revision:24236; number_of_response:1; }","duration":"609.989808ms","start":"2026-05-05T23:01:22.525466Z","end":"2026-05-05T23:01:23.135455Z","steps":["trace[349580077] 'process raft request' (duration: 609.543959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:23.135495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:22.52546Z","time spent":"610.017259ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:23.135542Z","caller":"traceutil/trace.go:171","msg":"trace[210073839] transaction","detail":"{read_only:false; response_revision:24237; number_of_response:1; }","duration":"609.926646ms","start":"2026-05-05T23:01:22.525611Z","end":"2026-05-05T23:01:23.135538Z","steps":["trace[210073839] 'process raft request' (duration: 609.559759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:23.135569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:22.525591Z","time spent":"609.963876ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:23.135715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.69691ms","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-05T23:01:23.135735Z","caller":"traceutil/trace.go:171","msg":"trace[1839009857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24239; }","duration":"594.743621ms","start":"2026-05-05T23:01:22.540986Z","end":"2026-05-05T23:01:23.13573Z","steps":["trace[1839009857] 'agreement among raft nodes before linearized reading' (duration: 594.632638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:23.135749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:22.540949Z","time spent":"594.797532ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T23:01:23.135798Z","caller":"traceutil/trace.go:171","msg":"trace[418237695] transaction","detail":"{read_only:false; response_revision:24239; number_of_response:1; }","duration":"351.257429ms","start":"2026-05-05T23:01:22.784522Z","end":"2026-05-05T23:01:23.13578Z","steps":["trace[418237695] 'process raft request' (duration: 350.700588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:23.135793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.446526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-05T23:01:23.13586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.672206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-05T23:01:23.135842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.551087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-05T23:01:23.135881Z","caller":"traceutil/trace.go:171","msg":"trace[611575727] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24239; }","duration":"552.692197ms","start":"2026-05-05T23:01:22.583183Z","end":"2026-05-05T23:01:23.135876Z","steps":["trace[611575727] 'agreement among raft nodes before linearized reading' (duration: 552.647316ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:23.135873Z","caller":"traceutil/trace.go:171","msg":"trace[1008322717] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:24239; }","duration":"501.569089ms","start":"2026-05-05T23:01:22.634282Z","end":"2026-05-05T23:01:23.135851Z","steps":["trace[1008322717] 'agreement among raft nodes before linearized reading' (duration: 501.415816ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:23.135901Z","caller":"traceutil/trace.go:171","msg":"trace[640105156] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24239; }","duration":"603.64876ms","start":"2026-05-05T23:01:22.532238Z","end":"2026-05-05T23:01:23.135887Z","steps":["trace[640105156] 'agreement among raft nodes before linearized reading' (duration: 603.481566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:23.135907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:22.784511Z","time spent":"351.33421ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:23.135932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.157285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-05T23:01:23.135932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:22.634263Z","time spent":"501.6575ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-05T23:01:23.135947Z","caller":"traceutil/trace.go:171","msg":"trace[691776865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24239; }","duration":"554.200936ms","start":"2026-05-05T23:01:22.581742Z","end":"2026-05-05T23:01:23.135943Z","steps":["trace[691776865] 'agreement among raft nodes before linearized reading' (duration: 554.162605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:23.135941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:22.532224Z","time spent":"603.708151ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-05T23:01:23.135963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:22.581727Z","time spent":"554.233226ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T23:01:23.135974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.119794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-05T23:01:23.135822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.525552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T23:01:23.136016Z","caller":"traceutil/trace.go:171","msg":"trace[545343971] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24239; }","duration":"552.716326ms","start":"2026-05-05T23:01:22.583293Z","end":"2026-05-05T23:01:23.136009Z","steps":["trace[545343971] 'agreement among raft nodes before linearized reading' (duration: 552.501022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:23.135895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:22.583142Z","time spent":"552.750227ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T23:01:23.136036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:22.583265Z","time spent":"552.763758ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-05T23:01:23.136021Z","caller":"traceutil/trace.go:171","msg":"trace[123723401] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24239; }","duration":"553.202526ms","start":"2026-05-05T23:01:22.582804Z","end":"2026-05-05T23:01:23.136007Z","steps":["trace[123723401] 'agreement among raft nodes before linearized reading' (duration: 553.089124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:23.13606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:22.582785Z","time spent":"553.267557ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T23:01:23.136081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.0718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:23.136125Z","caller":"traceutil/trace.go:171","msg":"trace[1187010481] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:24239; }","duration":"204.172672ms","start":"2026-05-05T23:01:22.931938Z","end":"2026-05-05T23:01:23.136111Z","steps":["trace[1187010481] 'agreement among raft nodes before linearized reading' (duration: 204.07777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:23.136759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:22.525263Z","time spent":"609.862526ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:31.973612Z","caller":"traceutil/trace.go:171","msg":"trace[790036616] transaction","detail":"{read_only:false; response_revision:24366; number_of_response:1; }","duration":"167.055406ms","start":"2026-05-05T23:01:31.806538Z","end":"2026-05-05T23:01:31.973594Z","steps":["trace[790036616] 'process raft request' (duration: 166.944884ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:32.422515Z","caller":"traceutil/trace.go:171","msg":"trace[191329601] transaction","detail":"{read_only:false; response_revision:24367; number_of_response:1; }","duration":"170.825443ms","start":"2026-05-05T23:01:32.251665Z","end":"2026-05-05T23:01:32.42249Z","steps":["trace[191329601] 'process raft request' (duration: 170.603879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:32.650658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.885192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T23:01:32.650745Z","caller":"traceutil/trace.go:171","msg":"trace[924962558] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:24367; }","duration":"151.026855ms","start":"2026-05-05T23:01:32.499701Z","end":"2026-05-05T23:01:32.650728Z","steps":["trace[924962558] 'count revisions from in-memory index tree' (duration: 150.797731ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:32.978917Z","caller":"traceutil/trace.go:171","msg":"trace[138830137] transaction","detail":"{read_only:false; response_revision:24368; number_of_response:1; }","duration":"121.662604ms","start":"2026-05-05T23:01:32.857225Z","end":"2026-05-05T23:01:32.978887Z","steps":["trace[138830137] 'process raft request' (duration: 121.251996ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:33.386395Z","caller":"traceutil/trace.go:171","msg":"trace[2129911595] transaction","detail":"{read_only:false; response_revision:24369; number_of_response:1; }","duration":"178.678709ms","start":"2026-05-05T23:01:33.207697Z","end":"2026-05-05T23:01:33.386375Z","steps":["trace[2129911595] 'process raft request' (duration: 178.536537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:33.715558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.055967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T23:01:33.715827Z","caller":"traceutil/trace.go:171","msg":"trace[192668810] transaction","detail":"{read_only:false; response_revision:24371; number_of_response:1; }","duration":"476.836372ms","start":"2026-05-05T23:01:33.238972Z","end":"2026-05-05T23:01:33.715808Z","steps":["trace[192668810] 'process raft request' (duration: 476.702939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:33.715896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:33.238955Z","time spent":"476.908293ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:33.715963Z","caller":"traceutil/trace.go:171","msg":"trace[1528511923] transaction","detail":"{read_only:false; response_revision:24370; number_of_response:1; }","duration":"483.042306ms","start":"2026-05-05T23:01:33.232876Z","end":"2026-05-05T23:01:33.715918Z","steps":["trace[1528511923] 'process raft request' (duration: 314.550771ms)","trace[1528511923] 'compare' (duration: 167.897293ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:33.716244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:33.232847Z","time spent":"483.19126ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:33.716579Z","caller":"traceutil/trace.go:171","msg":"trace[1783587611] linearizableReadLoop","detail":"{readStateIndex:25167; appliedIndex:25162; }","duration":"446.230567ms","start":"2026-05-05T23:01:33.270332Z","end":"2026-05-05T23:01:33.716563Z","steps":["trace[1783587611] 'read index received' (duration: 115.917489ms)","trace[1783587611] 'applied index is now lower than readState.Index' (duration: 330.311968ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:33.716578Z","caller":"traceutil/trace.go:171","msg":"trace[466415660] transaction","detail":"{read_only:false; response_revision:24372; number_of_response:1; }","duration":"476.501046ms","start":"2026-05-05T23:01:33.240046Z","end":"2026-05-05T23:01:33.716547Z","steps":["trace[466415660] 'process raft request' (duration: 475.73444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:33.716753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:33.240034Z","time spent":"476.609597ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:33.717818Z","caller":"traceutil/trace.go:171","msg":"trace[485470354] transaction","detail":"{read_only:false; response_revision:24373; number_of_response:1; }","duration":"474.459045ms","start":"2026-05-05T23:01:33.243333Z","end":"2026-05-05T23:01:33.717792Z","steps":["trace[485470354] 'process raft request' (duration: 473.144658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:33.717927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.590014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-05T23:01:33.717963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:33.243309Z","time spent":"474.579037ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:33.717991Z","caller":"traceutil/trace.go:171","msg":"trace[1416577957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24373; }","duration":"447.656366ms","start":"2026-05-05T23:01:33.270321Z","end":"2026-05-05T23:01:33.717977Z","steps":["trace[1416577957] 'agreement among raft nodes before linearized reading' (duration: 447.465082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:33.718025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:33.270284Z","time spent":"447.733617ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T23:01:33.906098Z","caller":"traceutil/trace.go:171","msg":"trace[484484216] transaction","detail":"{read_only:false; response_revision:24374; number_of_response:1; }","duration":"249.172127ms","start":"2026-05-05T23:01:33.656873Z","end":"2026-05-05T23:01:33.906046Z","steps":["trace[484484216] 'process raft request' (duration: 166.502735ms)","trace[484484216] 'compare' (duration: 82.288814ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:33.906163Z","caller":"traceutil/trace.go:171","msg":"trace[390849484] transaction","detail":"{read_only:false; response_revision:24376; number_of_response:1; }","duration":"104.932019ms","start":"2026-05-05T23:01:33.8012Z","end":"2026-05-05T23:01:33.906132Z","steps":["trace[390849484] 'process raft request' (duration: 104.885198ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:33.906397Z","caller":"traceutil/trace.go:171","msg":"trace[958183254] linearizableReadLoop","detail":"{readStateIndex:25168; appliedIndex:25167; }","duration":"189.706403ms","start":"2026-05-05T23:01:33.716623Z","end":"2026-05-05T23:01:33.906329Z","steps":["trace[958183254] 'read index received' (duration: 106.728294ms)","trace[958183254] 'applied index is now lower than readState.Index' (duration: 82.970138ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:33.906427Z","caller":"traceutil/trace.go:171","msg":"trace[1279973787] transaction","detail":"{read_only:false; response_revision:24375; number_of_response:1; }","duration":"183.399745ms","start":"2026-05-05T23:01:33.723Z","end":"2026-05-05T23:01:33.9064Z","steps":["trace[1279973787] 'process raft request' (duration: 182.983597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:33.906486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.853024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T23:01:33.906528Z","caller":"traceutil/trace.go:171","msg":"trace[1172282020] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24376; }","duration":"592.921885ms","start":"2026-05-05T23:01:33.313594Z","end":"2026-05-05T23:01:33.906516Z","steps":["trace[1172282020] 'agreement among raft nodes before linearized reading' (duration: 592.753102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:33.906559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:33.313587Z","time spent":"592.963946ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-05T23:01:33.906627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.486295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7d6961ab-9a51-4975-8793-af3471ae6abc\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-05-05T23:01:33.906692Z","caller":"traceutil/trace.go:171","msg":"trace[1935752752] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7d6961ab-9a51-4975-8793-af3471ae6abc; range_end:; response_count:1; response_revision:24376; }","duration":"476.560626ms","start":"2026-05-05T23:01:33.430115Z","end":"2026-05-05T23:01:33.906676Z","steps":["trace[1935752752] 'agreement among raft nodes before linearized reading' (duration: 476.377423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:33.906686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.886605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-05T23:01:33.906731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:33.430056Z","time spent":"476.663918ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3784,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7d6961ab-9a51-4975-8793-af3471ae6abc\" "} {"level":"info","ts":"2026-05-05T23:01:33.906745Z","caller":"traceutil/trace.go:171","msg":"trace[53837458] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24376; }","duration":"600.979356ms","start":"2026-05-05T23:01:33.305749Z","end":"2026-05-05T23:01:33.906729Z","steps":["trace[53837458] 'agreement among raft nodes before linearized reading' (duration: 600.733582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:33.906794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:33.305742Z","time spent":"601.030977ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T23:01:33.906803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.723163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-05T23:01:33.906867Z","caller":"traceutil/trace.go:171","msg":"trace[701004747] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24376; }","duration":"618.830006ms","start":"2026-05-05T23:01:33.288018Z","end":"2026-05-05T23:01:33.906848Z","steps":["trace[701004747] 'agreement among raft nodes before linearized reading' (duration: 618.52738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:33.9069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.513767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-05T23:01:33.906913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:33.288007Z","time spent":"618.893537ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T23:01:33.90694Z","caller":"traceutil/trace.go:171","msg":"trace[1342249430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24376; }","duration":"594.565678ms","start":"2026-05-05T23:01:33.312365Z","end":"2026-05-05T23:01:33.906931Z","steps":["trace[1342249430] 'agreement among raft nodes before linearized reading' (duration: 594.246772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:33.906972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:33.312361Z","time spent":"594.603429ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T23:01:33.907091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.504299ms","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-05T23:01:33.907117Z","caller":"traceutil/trace.go:171","msg":"trace[117289090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24376; }","duration":"629.556961ms","start":"2026-05-05T23:01:33.277549Z","end":"2026-05-05T23:01:33.907106Z","steps":["trace[117289090] 'agreement among raft nodes before linearized reading' (duration: 628.932529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:33.907141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:33.277537Z","time spent":"629.598171ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T23:01:33.90738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.702969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:33.907415Z","caller":"traceutil/trace.go:171","msg":"trace[1571416309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24376; }","duration":"473.770871ms","start":"2026-05-05T23:01:33.433634Z","end":"2026-05-05T23:01:33.907405Z","steps":["trace[1571416309] 'agreement among raft nodes before linearized reading' (duration: 472.842382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:33.907442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:33.43361Z","time spent":"473.826821ms","remote":"127.0.0.1:51472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T23:01:36.082177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.472101ms","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-05T23:01:36.082261Z","caller":"traceutil/trace.go:171","msg":"trace[1554295112] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24392; }","duration":"119.596464ms","start":"2026-05-05T23:01:35.962646Z","end":"2026-05-05T23:01:36.082243Z","steps":["trace[1554295112] 'range keys from in-memory index tree' (duration: 119.286748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:36.082256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.321958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-05T23:01:36.082334Z","caller":"traceutil/trace.go:171","msg":"trace[1472186417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24392; }","duration":"119.42067ms","start":"2026-05-05T23:01:35.962888Z","end":"2026-05-05T23:01:36.082309Z","steps":["trace[1472186417] 'range keys from in-memory index tree' (duration: 119.186935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:36.082495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.43041ms","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-05T23:01:36.082531Z","caller":"traceutil/trace.go:171","msg":"trace[316618767] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24392; }","duration":"126.495022ms","start":"2026-05-05T23:01:35.956027Z","end":"2026-05-05T23:01:36.082522Z","steps":["trace[316618767] 'range keys from in-memory index tree' (duration: 126.226326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:36.082617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.96476ms","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-05T23:01:36.082726Z","caller":"traceutil/trace.go:171","msg":"trace[1725370794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24392; }","duration":"121.177945ms","start":"2026-05-05T23:01:35.961529Z","end":"2026-05-05T23:01:36.082707Z","steps":["trace[1725370794] 'range keys from in-memory index tree' (duration: 120.659704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:36.082646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.661815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T23:01:36.082947Z","caller":"traceutil/trace.go:171","msg":"trace[1354839232] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24392; }","duration":"120.991961ms","start":"2026-05-05T23:01:35.961913Z","end":"2026-05-05T23:01:36.082905Z","steps":["trace[1354839232] 'range keys from in-memory index tree' (duration: 120.489972ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:36.663272Z","caller":"traceutil/trace.go:171","msg":"trace[861471648] transaction","detail":"{read_only:false; response_revision:24394; number_of_response:1; }","duration":"576.577076ms","start":"2026-05-05T23:01:36.086661Z","end":"2026-05-05T23:01:36.663238Z","steps":["trace[861471648] 'process raft request' (duration: 576.342862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:36.663448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:36.086653Z","time spent":"576.706709ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:36.663566Z","caller":"traceutil/trace.go:171","msg":"trace[824438876] transaction","detail":"{read_only:false; response_revision:24395; number_of_response:1; }","duration":"576.686258ms","start":"2026-05-05T23:01:36.086843Z","end":"2026-05-05T23:01:36.66353Z","steps":["trace[824438876] 'process raft request' (duration: 576.336981ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:36.663661Z","caller":"traceutil/trace.go:171","msg":"trace[404156772] transaction","detail":"{read_only:false; response_revision:24396; number_of_response:1; }","duration":"576.624866ms","start":"2026-05-05T23:01:36.087007Z","end":"2026-05-05T23:01:36.663631Z","steps":["trace[404156772] 'process raft request' (duration: 576.425692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:36.663736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:36.086836Z","time spent":"576.814132ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:36.663733Z","caller":"traceutil/trace.go:171","msg":"trace[1177875801] linearizableReadLoop","detail":"{readStateIndex:25192; appliedIndex:25188; }","duration":"231.730176ms","start":"2026-05-05T23:01:36.431964Z","end":"2026-05-05T23:01:36.663695Z","steps":["trace[1177875801] 'read index received' (duration: 231.075424ms)","trace[1177875801] 'applied index is now lower than readState.Index' (duration: 653.162µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:36.663797Z","caller":"traceutil/trace.go:171","msg":"trace[741408336] transaction","detail":"{read_only:false; response_revision:24397; number_of_response:1; }","duration":"575.667368ms","start":"2026-05-05T23:01:36.08809Z","end":"2026-05-05T23:01:36.663757Z","steps":["trace[741408336] 'process raft request' (duration: 575.481274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:36.663875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.886559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T23:01:36.663869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:36.086979Z","time spent":"576.784191ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:36.663904Z","caller":"traceutil/trace.go:171","msg":"trace[1920588380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24397; }","duration":"231.97044ms","start":"2026-05-05T23:01:36.431924Z","end":"2026-05-05T23:01:36.663895Z","steps":["trace[1920588380] 'agreement among raft nodes before linearized reading' (duration: 231.881689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:36.663975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:36.088074Z","time spent":"575.80877ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:39.808115Z","caller":"traceutil/trace.go:171","msg":"trace[514577512] transaction","detail":"{read_only:false; response_revision:24432; number_of_response:1; }","duration":"112.922929ms","start":"2026-05-05T23:01:39.69517Z","end":"2026-05-05T23:01:39.808093Z","steps":["trace[514577512] 'process raft request' (duration: 112.767676ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:40.113654Z","caller":"traceutil/trace.go:171","msg":"trace[205881848] transaction","detail":"{read_only:false; response_revision:24433; number_of_response:1; }","duration":"369.541586ms","start":"2026-05-05T23:01:39.744091Z","end":"2026-05-05T23:01:40.113632Z","steps":["trace[205881848] 'process raft request' (duration: 356.496214ms)","trace[205881848] 'compare' (duration: 12.815038ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:40.113672Z","caller":"traceutil/trace.go:171","msg":"trace[2056154813] transaction","detail":"{read_only:false; response_revision:24436; number_of_response:1; }","duration":"367.683879ms","start":"2026-05-05T23:01:39.745968Z","end":"2026-05-05T23:01:40.113652Z","steps":["trace[2056154813] 'process raft request' (duration: 367.639568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:40.113757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:39.744067Z","time spent":"369.637228ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:40.113806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:39.74596Z","time spent":"367.77269ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:40.113893Z","caller":"traceutil/trace.go:171","msg":"trace[1800059604] transaction","detail":"{read_only:false; response_revision:24434; number_of_response:1; }","duration":"369.25976ms","start":"2026-05-05T23:01:39.744613Z","end":"2026-05-05T23:01:40.113873Z","steps":["trace[1800059604] 'process raft request' (duration: 368.911173ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:40.113972Z","caller":"traceutil/trace.go:171","msg":"trace[1902844290] transaction","detail":"{read_only:false; response_revision:24435; number_of_response:1; }","duration":"368.555557ms","start":"2026-05-05T23:01:39.745408Z","end":"2026-05-05T23:01:40.113964Z","steps":["trace[1902844290] 'process raft request' (duration: 368.163909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:40.113989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:39.744593Z","time spent":"369.352383ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:40.114016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:39.745393Z","time spent":"368.603248ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:40.196197Z","caller":"traceutil/trace.go:171","msg":"trace[853346484] linearizableReadLoop","detail":"{readStateIndex:25234; appliedIndex:25229; }","duration":"242.247558ms","start":"2026-05-05T23:01:39.953926Z","end":"2026-05-05T23:01:40.196173Z","steps":["trace[853346484] 'read index received' (duration: 146.721648ms)","trace[853346484] 'applied index is now lower than readState.Index' (duration: 95.524739ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:40.196293Z","caller":"traceutil/trace.go:171","msg":"trace[267451674] transaction","detail":"{read_only:false; response_revision:24437; number_of_response:1; }","duration":"242.519842ms","start":"2026-05-05T23:01:39.953749Z","end":"2026-05-05T23:01:40.196269Z","steps":["trace[267451674] 'process raft request' (duration: 242.248927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:40.196433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.105871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T23:01:40.196475Z","caller":"traceutil/trace.go:171","msg":"trace[637116607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24437; }","duration":"215.187213ms","start":"2026-05-05T23:01:39.981277Z","end":"2026-05-05T23:01:40.196464Z","steps":["trace[637116607] 'agreement among raft nodes before linearized reading' (duration: 215.03466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:40.19649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.540373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10362"} {"level":"info","ts":"2026-05-05T23:01:40.196569Z","caller":"traceutil/trace.go:171","msg":"trace[54272666] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24437; }","duration":"242.632105ms","start":"2026-05-05T23:01:39.95392Z","end":"2026-05-05T23:01:40.196552Z","steps":["trace[54272666] 'agreement among raft nodes before linearized reading' (duration: 242.38124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:40.196739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.630808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-05T23:01:40.196843Z","caller":"traceutil/trace.go:171","msg":"trace[1882076854] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24437; }","duration":"154.849232ms","start":"2026-05-05T23:01:40.041974Z","end":"2026-05-05T23:01:40.196823Z","steps":["trace[1882076854] 'agreement among raft nodes before linearized reading' (duration: 154.457884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:40.196767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.955929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3237"} {"level":"info","ts":"2026-05-05T23:01:40.197244Z","caller":"traceutil/trace.go:171","msg":"trace[469566244] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24437; }","duration":"209.47439ms","start":"2026-05-05T23:01:39.987761Z","end":"2026-05-05T23:01:40.197235Z","steps":["trace[469566244] 'agreement among raft nodes before linearized reading' (duration: 208.672113ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:10.562645Z","caller":"traceutil/trace.go:171","msg":"trace[2109466682] linearizableReadLoop","detail":"{readStateIndex:25487; appliedIndex:25486; }","duration":"136.960913ms","start":"2026-05-05T23:02:10.42566Z","end":"2026-05-05T23:02:10.562621Z","steps":["trace[2109466682] 'read index received' (duration: 136.795429ms)","trace[2109466682] 'applied index is now lower than readState.Index' (duration: 164.324µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:02:10.562843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.171637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T23:02:10.562881Z","caller":"traceutil/trace.go:171","msg":"trace[1545885251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24684; }","duration":"137.263049ms","start":"2026-05-05T23:02:10.425608Z","end":"2026-05-05T23:02:10.562871Z","steps":["trace[1545885251] 'agreement among raft nodes before linearized reading' (duration: 137.118836ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:10.563002Z","caller":"traceutil/trace.go:171","msg":"trace[481495144] transaction","detail":"{read_only:false; response_revision:24684; number_of_response:1; }","duration":"138.930981ms","start":"2026-05-05T23:02:10.423809Z","end":"2026-05-05T23:02:10.56274Z","steps":["trace[481495144] 'process raft request' (duration: 138.674616ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:10.741501Z","caller":"traceutil/trace.go:171","msg":"trace[1441213811] transaction","detail":"{read_only:false; response_revision:24685; number_of_response:1; }","duration":"174.64889ms","start":"2026-05-05T23:02:10.566805Z","end":"2026-05-05T23:02:10.741454Z","steps":["trace[1441213811] 'process raft request' (duration: 132.505953ms)","trace[1441213811] 'compare' (duration: 41.529955ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:10.952937Z","caller":"traceutil/trace.go:171","msg":"trace[1573867080] linearizableReadLoop","detail":"{readStateIndex:25489; appliedIndex:25488; }","duration":"125.202176ms","start":"2026-05-05T23:02:10.827709Z","end":"2026-05-05T23:02:10.952911Z","steps":["trace[1573867080] 'read index received' (duration: 125.118205ms)","trace[1573867080] 'applied index is now lower than readState.Index' (duration: 82.501µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:10.952993Z","caller":"traceutil/trace.go:171","msg":"trace[1991250416] transaction","detail":"{read_only:false; response_revision:24686; number_of_response:1; }","duration":"127.201786ms","start":"2026-05-05T23:02:10.825759Z","end":"2026-05-05T23:02:10.952961Z","steps":["trace[1991250416] 'process raft request' (duration: 126.94997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:10.953201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.472181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-05T23:02:10.953273Z","caller":"traceutil/trace.go:171","msg":"trace[116461698] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24686; }","duration":"125.555973ms","start":"2026-05-05T23:02:10.827699Z","end":"2026-05-05T23:02:10.953255Z","steps":["trace[116461698] 'agreement among raft nodes before linearized reading' (duration: 125.347799ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:11.027661Z","caller":"traceutil/trace.go:171","msg":"trace[1027511551] transaction","detail":"{read_only:false; response_revision:24687; number_of_response:1; }","duration":"199.524889ms","start":"2026-05-05T23:02:10.828113Z","end":"2026-05-05T23:02:11.027638Z","steps":["trace[1027511551] 'process raft request' (duration: 196.796515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:11.027806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.209221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-05-05T23:02:11.027798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.741022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T23:02:11.027848Z","caller":"traceutil/trace.go:171","msg":"trace[1463721979] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24689; }","duration":"179.290953ms","start":"2026-05-05T23:02:10.848547Z","end":"2026-05-05T23:02:11.027838Z","steps":["trace[1463721979] 'agreement among raft nodes before linearized reading' (duration: 179.12551ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:11.027859Z","caller":"traceutil/trace.go:171","msg":"trace[150970752] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:24689; }","duration":"179.862304ms","start":"2026-05-05T23:02:10.847985Z","end":"2026-05-05T23:02:11.027847Z","steps":["trace[150970752] 'agreement among raft nodes before linearized reading' (duration: 179.723132ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:11.73956Z","caller":"traceutil/trace.go:171","msg":"trace[1106368062] linearizableReadLoop","detail":"{readStateIndex:25495; appliedIndex:25494; }","duration":"203.424757ms","start":"2026-05-05T23:02:11.536112Z","end":"2026-05-05T23:02:11.739537Z","steps":["trace[1106368062] 'read index received' (duration: 203.304274ms)","trace[1106368062] 'applied index is now lower than readState.Index' (duration: 119.353µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:11.739688Z","caller":"traceutil/trace.go:171","msg":"trace[1308115867] transaction","detail":"{read_only:false; response_revision:24692; number_of_response:1; }","duration":"299.783854ms","start":"2026-05-05T23:02:11.439867Z","end":"2026-05-05T23:02:11.739651Z","steps":["trace[1308115867] 'process raft request' (duration: 299.528979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:11.739786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.654951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-05T23:02:11.739831Z","caller":"traceutil/trace.go:171","msg":"trace[2008338648] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:24692; }","duration":"203.747683ms","start":"2026-05-05T23:02:11.536073Z","end":"2026-05-05T23:02:11.73982Z","steps":["trace[2008338648] 'agreement among raft nodes before linearized reading' (duration: 203.56156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:12.004663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.204389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T23:02:12.004797Z","caller":"traceutil/trace.go:171","msg":"trace[1983088399] linearizableReadLoop","detail":"{readStateIndex:25496; appliedIndex:25495; }","duration":"265.165449ms","start":"2026-05-05T23:02:11.739614Z","end":"2026-05-05T23:02:12.004779Z","steps":["trace[1983088399] 'read index received' (duration: 117.761907ms)","trace[1983088399] 'applied index is now lower than readState.Index' (duration: 147.401602ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:12.005113Z","caller":"traceutil/trace.go:171","msg":"trace[89701972] transaction","detail":"{read_only:false; response_revision:24693; number_of_response:1; }","duration":"463.243159ms","start":"2026-05-05T23:02:11.541822Z","end":"2026-05-05T23:02:12.005065Z","steps":["trace[89701972] 'process raft request' (duration: 315.54008ms)","trace[89701972] 'compare' (duration: 147.049156ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:02:12.005296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.5296ms","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-05T23:02:12.005348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:02:11.541805Z","time spent":"463.412582ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:02:12.005358Z","caller":"traceutil/trace.go:171","msg":"trace[1272046955] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:24693; }","duration":"408.625081ms","start":"2026-05-05T23:02:11.596717Z","end":"2026-05-05T23:02:12.005342Z","steps":["trace[1272046955] 'agreement among raft nodes before linearized reading' (duration: 408.366386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:12.005409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:02:11.596705Z","time spent":"408.692963ms","remote":"127.0.0.1:51548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":233,"response size":32,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-05-05T23:02:12.622371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.753907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T23:02:12.622752Z","caller":"traceutil/trace.go:171","msg":"trace[839269354] transaction","detail":"{read_only:false; response_revision:24695; number_of_response:1; }","duration":"374.841282ms","start":"2026-05-05T23:02:12.247898Z","end":"2026-05-05T23:02:12.622739Z","steps":["trace[839269354] 'process raft request' (duration: 134.653616ms)","trace[839269354] 'compare' (duration: 239.529802ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:02:12.622797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:02:12.247886Z","time spent":"374.890773ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:02:12.622869Z","caller":"traceutil/trace.go:171","msg":"trace[908903779] transaction","detail":"{read_only:false; response_revision:24696; number_of_response:1; }","duration":"371.990325ms","start":"2026-05-05T23:02:12.250873Z","end":"2026-05-05T23:02:12.622863Z","steps":["trace[908903779] 'process raft request' (duration: 371.560996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:12.623103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:02:12.250864Z","time spent":"372.21966ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:02:12.623181Z","caller":"traceutil/trace.go:171","msg":"trace[812112583] transaction","detail":"{read_only:false; response_revision:24697; number_of_response:1; }","duration":"372.23596ms","start":"2026-05-05T23:02:12.25094Z","end":"2026-05-05T23:02:12.623176Z","steps":["trace[812112583] 'process raft request' (duration: 371.522906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:12.623209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:02:12.250935Z","time spent":"372.25796ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:02:12.623269Z","caller":"traceutil/trace.go:171","msg":"trace[1968471415] transaction","detail":"{read_only:false; response_revision:24698; number_of_response:1; }","duration":"313.297056ms","start":"2026-05-05T23:02:12.309967Z","end":"2026-05-05T23:02:12.623264Z","steps":["trace[1968471415] 'process raft request' (duration: 312.51233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:12.623298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:02:12.309936Z","time spent":"313.347157ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:02:13.067871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.527841ms","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-05T23:02:13.067917Z","caller":"traceutil/trace.go:171","msg":"trace[1675762033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24700; }","duration":"108.616823ms","start":"2026-05-05T23:02:12.95929Z","end":"2026-05-05T23:02:13.067907Z","steps":["trace[1675762033] 'range keys from in-memory index tree' (duration: 108.365987ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:13.199715Z","caller":"traceutil/trace.go:171","msg":"trace[1998318546] transaction","detail":"{read_only:false; response_revision:24701; number_of_response:1; }","duration":"128.155915ms","start":"2026-05-05T23:02:13.071532Z","end":"2026-05-05T23:02:13.199688Z","steps":["trace[1998318546] 'process raft request' (duration: 128.005092ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:13.347076Z","caller":"traceutil/trace.go:171","msg":"trace[1003686275] transaction","detail":"{read_only:false; response_revision:24705; number_of_response:1; }","duration":"223.967011ms","start":"2026-05-05T23:02:13.123086Z","end":"2026-05-05T23:02:13.347053Z","steps":["trace[1003686275] 'process raft request' (duration: 223.91577ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:13.347285Z","caller":"traceutil/trace.go:171","msg":"trace[2072187003] transaction","detail":"{read_only:false; response_revision:24703; number_of_response:1; }","duration":"274.005736ms","start":"2026-05-05T23:02:13.073253Z","end":"2026-05-05T23:02:13.347259Z","steps":["trace[2072187003] 'process raft request' (duration: 273.678969ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:13.347324Z","caller":"traceutil/trace.go:171","msg":"trace[2041582254] transaction","detail":"{read_only:false; response_revision:24704; number_of_response:1; }","duration":"273.272941ms","start":"2026-05-05T23:02:13.07404Z","end":"2026-05-05T23:02:13.347313Z","steps":["trace[2041582254] 'process raft request' (duration: 272.927744ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:13.347444Z","caller":"traceutil/trace.go:171","msg":"trace[968193259] transaction","detail":"{read_only:false; response_revision:24702; number_of_response:1; }","duration":"274.312382ms","start":"2026-05-05T23:02:13.073122Z","end":"2026-05-05T23:02:13.347434Z","steps":["trace[968193259] 'process raft request' (duration: 273.68057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:14.064828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.0508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:02:14.06489Z","caller":"traceutil/trace.go:171","msg":"trace[175006063] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:24706; }","duration":"106.161652ms","start":"2026-05-05T23:02:13.958714Z","end":"2026-05-05T23:02:14.064875Z","steps":["trace[175006063] 'count revisions from in-memory index tree' (duration: 105.957689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:14.631068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.664706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T23:02:14.631311Z","caller":"traceutil/trace.go:171","msg":"trace[716524025] transaction","detail":"{read_only:false; response_revision:24709; number_of_response:1; }","duration":"268.516036ms","start":"2026-05-05T23:02:14.362775Z","end":"2026-05-05T23:02:14.631291Z","steps":["trace[716524025] 'process raft request' (duration: 268.417154ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:14.6317Z","caller":"traceutil/trace.go:171","msg":"trace[685261052] transaction","detail":"{read_only:false; response_revision:24708; number_of_response:1; }","duration":"309.307466ms","start":"2026-05-05T23:02:14.322379Z","end":"2026-05-05T23:02:14.631687Z","steps":["trace[685261052] 'process raft request' (duration: 170.904844ms)","trace[685261052] 'compare' (duration: 137.207407ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:02:14.631775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:02:14.322349Z","time spent":"309.386828ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:02:14.913291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.28457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T23:02:14.913439Z","caller":"traceutil/trace.go:171","msg":"trace[1269073107] transaction","detail":"{read_only:false; response_revision:24711; number_of_response:1; }","duration":"285.137869ms","start":"2026-05-05T23:02:14.628287Z","end":"2026-05-05T23:02:14.913425Z","steps":["trace[1269073107] 'process raft request' (duration: 285.084848ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:14.913531Z","caller":"traceutil/trace.go:171","msg":"trace[967659711] transaction","detail":"{read_only:false; response_revision:24710; number_of_response:1; }","duration":"285.736552ms","start":"2026-05-05T23:02:14.627787Z","end":"2026-05-05T23:02:14.913524Z","steps":["trace[967659711] 'process raft request' (duration: 132.158287ms)","trace[967659711] 'compare' (duration: 153.189877ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:14.913745Z","caller":"traceutil/trace.go:171","msg":"trace[1697898905] transaction","detail":"{read_only:false; response_revision:24712; number_of_response:1; }","duration":"284.982876ms","start":"2026-05-05T23:02:14.628753Z","end":"2026-05-05T23:02:14.913736Z","steps":["trace[1697898905] 'process raft request' (duration: 284.64989ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:14.913977Z","caller":"traceutil/trace.go:171","msg":"trace[704170828] linearizableReadLoop","detail":"{readStateIndex:25516; appliedIndex:25513; }","duration":"280.387435ms","start":"2026-05-05T23:02:14.633564Z","end":"2026-05-05T23:02:14.913952Z","steps":["trace[704170828] 'read index received' (duration: 126.395421ms)","trace[704170828] 'applied index is now lower than readState.Index' (duration: 153.990854ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:14.914018Z","caller":"traceutil/trace.go:171","msg":"trace[1372608375] transaction","detail":"{read_only:false; response_revision:24713; number_of_response:1; }","duration":"276.809582ms","start":"2026-05-05T23:02:14.637184Z","end":"2026-05-05T23:02:14.913994Z","steps":["trace[1372608375] 'process raft request' (duration: 276.489285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:14.9142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.61867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-05-05T23:02:14.914276Z","caller":"traceutil/trace.go:171","msg":"trace[862238988] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24713; }","duration":"280.748992ms","start":"2026-05-05T23:02:14.63351Z","end":"2026-05-05T23:02:14.914259Z","steps":["trace[862238988] 'agreement among raft nodes before linearized reading' (duration: 280.559459ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:15.153282Z","caller":"traceutil/trace.go:171","msg":"trace[1832293597] linearizableReadLoop","detail":"{readStateIndex:25518; appliedIndex:25517; }","duration":"239.205407ms","start":"2026-05-05T23:02:14.914048Z","end":"2026-05-05T23:02:15.153254Z","steps":["trace[1832293597] 'read index received' (duration: 203.621572ms)","trace[1832293597] 'applied index is now lower than readState.Index' (duration: 35.582845ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:15.153406Z","caller":"traceutil/trace.go:171","msg":"trace[1613786111] transaction","detail":"{read_only:false; response_revision:24714; number_of_response:1; }","duration":"300.09637ms","start":"2026-05-05T23:02:14.853275Z","end":"2026-05-05T23:02:15.153371Z","steps":["trace[1613786111] 'process raft request' (duration: 264.472054ms)","trace[1613786111] 'compare' (duration: 35.250009ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:02:15.153533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.12335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-05T23:02:15.153537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.82616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T23:02:15.153629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.975181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T23:02:15.153668Z","caller":"traceutil/trace.go:171","msg":"trace[1991333835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24714; }","duration":"236.044323ms","start":"2026-05-05T23:02:14.917611Z","end":"2026-05-05T23:02:15.153655Z","steps":["trace[1991333835] 'agreement among raft nodes before linearized reading' (duration: 235.986092ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:15.153643Z","caller":"traceutil/trace.go:171","msg":"trace[1297115199] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:24714; }","duration":"356.005434ms","start":"2026-05-05T23:02:14.797619Z","end":"2026-05-05T23:02:15.153624Z","steps":["trace[1297115199] 'agreement among raft nodes before linearized reading' (duration: 355.767819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:15.153564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.534143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-05T23:02:15.154201Z","caller":"traceutil/trace.go:171","msg":"trace[109052890] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:24714; }","duration":"238.202997ms","start":"2026-05-05T23:02:14.915976Z","end":"2026-05-05T23:02:15.154179Z","steps":["trace[109052890] 'agreement among raft nodes before linearized reading' (duration: 237.455942ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:15.153575Z","caller":"traceutil/trace.go:171","msg":"trace[1133930058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24714; }","duration":"498.221862ms","start":"2026-05-05T23:02:14.655343Z","end":"2026-05-05T23:02:15.153565Z","steps":["trace[1133930058] 'agreement among raft nodes before linearized reading' (duration: 498.048949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:15.154357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:02:14.655322Z","time spent":"499.014347ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T23:02:15.153563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:02:14.853249Z","time spent":"300.233993ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:02:15.15448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.391189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-05T23:02:15.15455Z","caller":"traceutil/trace.go:171","msg":"trace[244830413] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24714; }","duration":"233.510022ms","start":"2026-05-05T23:02:14.921027Z","end":"2026-05-05T23:02:15.154537Z","steps":["trace[244830413] 'agreement among raft nodes before linearized reading' (duration: 232.485752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:15.15504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:02:14.797598Z","time spent":"357.418163ms","remote":"127.0.0.1:54862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"info","ts":"2026-05-05T23:02:15.336649Z","caller":"traceutil/trace.go:171","msg":"trace[415826845] linearizableReadLoop","detail":"{readStateIndex:25520; appliedIndex:25518; }","duration":"176.083358ms","start":"2026-05-05T23:02:15.160533Z","end":"2026-05-05T23:02:15.336617Z","steps":["trace[415826845] 'read index received' (duration: 120.037452ms)","trace[415826845] 'applied index is now lower than readState.Index' (duration: 56.044906ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:15.336783Z","caller":"traceutil/trace.go:171","msg":"trace[846653439] transaction","detail":"{read_only:false; response_revision:24716; number_of_response:1; }","duration":"177.217891ms","start":"2026-05-05T23:02:15.159553Z","end":"2026-05-05T23:02:15.336771Z","steps":["trace[846653439] 'process raft request' (duration: 176.970446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:15.337009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.456956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-05T23:02:15.337014Z","caller":"traceutil/trace.go:171","msg":"trace[1861232769] transaction","detail":"{read_only:false; response_revision:24715; number_of_response:1; }","duration":"178.785943ms","start":"2026-05-05T23:02:15.158188Z","end":"2026-05-05T23:02:15.336974Z","steps":["trace[1861232769] 'process raft request' (duration: 122.44304ms)","trace[1861232769] 'compare' (duration: 55.690989ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:02:15.337111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.65563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:02:15.337051Z","caller":"traceutil/trace.go:171","msg":"trace[2134852517] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24716; }","duration":"176.538108ms","start":"2026-05-05T23:02:15.160502Z","end":"2026-05-05T23:02:15.337041Z","steps":["trace[2134852517] 'agreement among raft nodes before linearized reading' (duration: 176.394154ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:15.337146Z","caller":"traceutil/trace.go:171","msg":"trace[49415622] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:24716; }","duration":"163.7372ms","start":"2026-05-05T23:02:15.173399Z","end":"2026-05-05T23:02:15.337137Z","steps":["trace[49415622] 'agreement among raft nodes before linearized reading' (duration: 163.677659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:15.337372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.970742ms","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-05T23:02:15.337493Z","caller":"traceutil/trace.go:171","msg":"trace[258676582] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24716; }","duration":"133.096155ms","start":"2026-05-05T23:02:15.204365Z","end":"2026-05-05T23:02:15.337461Z","steps":["trace[258676582] 'agreement among raft nodes before linearized reading' (duration: 132.795759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:15.627361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.891411ms","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-05T23:02:15.627493Z","caller":"traceutil/trace.go:171","msg":"trace[1134155965] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24716; }","duration":"142.046544ms","start":"2026-05-05T23:02:15.485427Z","end":"2026-05-05T23:02:15.627474Z","steps":["trace[1134155965] 'range keys from in-memory index tree' (duration: 141.869561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:15.62811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.502444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T23:02:15.628303Z","caller":"traceutil/trace.go:171","msg":"trace[527361553] transaction","detail":"{read_only:false; response_revision:24718; number_of_response:1; }","duration":"272.656189ms","start":"2026-05-05T23:02:15.35563Z","end":"2026-05-05T23:02:15.628286Z","steps":["trace[527361553] 'process raft request' (duration: 272.600577ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:15.628626Z","caller":"traceutil/trace.go:171","msg":"trace[863601595] linearizableReadLoop","detail":"{readStateIndex:25521; appliedIndex:25520; }","duration":"276.291892ms","start":"2026-05-05T23:02:15.352299Z","end":"2026-05-05T23:02:15.628591Z","steps":["trace[863601595] 'read index received' (duration: 123.210026ms)","trace[863601595] 'applied index is now lower than readState.Index' (duration: 153.076536ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:15.628656Z","caller":"traceutil/trace.go:171","msg":"trace[991318011] transaction","detail":"{read_only:false; response_revision:24717; number_of_response:1; }","duration":"286.367375ms","start":"2026-05-05T23:02:15.342268Z","end":"2026-05-05T23:02:15.628635Z","steps":["trace[991318011] 'process raft request' (duration: 133.260698ms)","trace[991318011] 'compare' (duration: 152.399082ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:02:15.628771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.463075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-05T23:02:15.628818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.175712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:02:15.628832Z","caller":"traceutil/trace.go:171","msg":"trace[114757506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24718; }","duration":"276.531237ms","start":"2026-05-05T23:02:15.352266Z","end":"2026-05-05T23:02:15.628797Z","steps":["trace[114757506] 'agreement among raft nodes before linearized reading' (duration: 276.417195ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:15.628862Z","caller":"traceutil/trace.go:171","msg":"trace[545953010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24718; }","duration":"197.249214ms","start":"2026-05-05T23:02:15.431599Z","end":"2026-05-05T23:02:15.628848Z","steps":["trace[545953010] 'agreement among raft nodes before linearized reading' (duration: 197.174533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:15.628879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.117668ms","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-05T23:02:15.628909Z","caller":"traceutil/trace.go:171","msg":"trace[1049162646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24718; }","duration":"274.19581ms","start":"2026-05-05T23:02:15.354703Z","end":"2026-05-05T23:02:15.628898Z","steps":["trace[1049162646] 'agreement among raft nodes before linearized reading' (duration: 274.037347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:15.628953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.464721ms","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-05T23:02:15.628992Z","caller":"traceutil/trace.go:171","msg":"trace[849061667] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24718; }","duration":"222.545593ms","start":"2026-05-05T23:02:15.406439Z","end":"2026-05-05T23:02:15.628985Z","steps":["trace[849061667] 'agreement among raft nodes before linearized reading' (duration: 222.488892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:15.629042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.253939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-05-05T23:02:15.629068Z","caller":"traceutil/trace.go:171","msg":"trace[1128601340] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:24718; }","duration":"143.313929ms","start":"2026-05-05T23:02:15.485746Z","end":"2026-05-05T23:02:15.62906Z","steps":["trace[1128601340] 'agreement among raft nodes before linearized reading' (duration: 143.249778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:15.629113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.336533ms","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-05T23:02:15.62915Z","caller":"traceutil/trace.go:171","msg":"trace[1344648908] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24718; }","duration":"274.387824ms","start":"2026-05-05T23:02:15.354751Z","end":"2026-05-05T23:02:15.629138Z","steps":["trace[1344648908] 'agreement among raft nodes before linearized reading' (duration: 274.242991ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:15.754456Z","caller":"traceutil/trace.go:171","msg":"trace[270673796] linearizableReadLoop","detail":"{readStateIndex:25525; appliedIndex:25522; }","duration":"120.441441ms","start":"2026-05-05T23:02:15.633987Z","end":"2026-05-05T23:02:15.754428Z","steps":["trace[270673796] 'read index received' (duration: 118.158375ms)","trace[270673796] 'applied index is now lower than readState.Index' (duration: 2.281936ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:15.754584Z","caller":"traceutil/trace.go:171","msg":"trace[160480566] transaction","detail":"{read_only:false; response_revision:24719; number_of_response:1; }","duration":"121.908011ms","start":"2026-05-05T23:02:15.632661Z","end":"2026-05-05T23:02:15.754569Z","steps":["trace[160480566] 'process raft request' (duration: 119.511372ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:15.754754Z","caller":"traceutil/trace.go:171","msg":"trace[1940366902] transaction","detail":"{read_only:false; response_revision:24721; number_of_response:1; }","duration":"121.186546ms","start":"2026-05-05T23:02:15.633547Z","end":"2026-05-05T23:02:15.754734Z","steps":["trace[1940366902] 'process raft request' (duration: 120.825499ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:15.754751Z","caller":"traceutil/trace.go:171","msg":"trace[613503127] transaction","detail":"{read_only:false; response_revision:24720; number_of_response:1; }","duration":"121.699087ms","start":"2026-05-05T23:02:15.633029Z","end":"2026-05-05T23:02:15.754728Z","steps":["trace[613503127] 'process raft request' (duration: 121.236407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:15.754822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.824888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-05T23:02:15.75487Z","caller":"traceutil/trace.go:171","msg":"trace[1553266577] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24721; }","duration":"120.94779ms","start":"2026-05-05T23:02:15.633909Z","end":"2026-05-05T23:02:15.754857Z","steps":["trace[1553266577] 'agreement among raft nodes before linearized reading' (duration: 120.770407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:18.305319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.326891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T23:02:18.305466Z","caller":"traceutil/trace.go:171","msg":"trace[1807290180] transaction","detail":"{read_only:false; response_revision:24734; number_of_response:1; }","duration":"486.128878ms","start":"2026-05-05T23:02:17.819308Z","end":"2026-05-05T23:02:18.305437Z","steps":["trace[1807290180] 'process raft request' (duration: 334.580043ms)","trace[1807290180] 'compare' (duration: 150.636617ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:02:18.305574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:02:17.819291Z","time spent":"486.230571ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:02:18.39342Z","caller":"traceutil/trace.go:171","msg":"trace[1964744908] transaction","detail":"{read_only:false; response_revision:24735; number_of_response:1; }","duration":"309.224685ms","start":"2026-05-05T23:02:18.084168Z","end":"2026-05-05T23:02:18.393392Z","steps":["trace[1964744908] 'process raft request' (duration: 309.082012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:18.393593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:02:18.084107Z","time spent":"309.387298ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:02:18.776395Z","caller":"traceutil/trace.go:171","msg":"trace[1271539531] transaction","detail":"{read_only:false; response_revision:24736; number_of_response:1; }","duration":"135.246607ms","start":"2026-05-05T23:02:18.641128Z","end":"2026-05-05T23:02:18.776375Z","steps":["trace[1271539531] 'process raft request' (duration: 135.120995ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:19.050103Z","caller":"traceutil/trace.go:171","msg":"trace[89291967] transaction","detail":"{read_only:false; response_revision:24737; number_of_response:1; }","duration":"121.619224ms","start":"2026-05-05T23:02:18.928455Z","end":"2026-05-05T23:02:19.050074Z","steps":["trace[89291967] 'process raft request' (duration: 121.4243ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:19.052289Z","caller":"traceutil/trace.go:171","msg":"trace[1610818746] linearizableReadLoop","detail":"{readStateIndex:25544; appliedIndex:25540; }","duration":"110.732356ms","start":"2026-05-05T23:02:18.941534Z","end":"2026-05-05T23:02:19.052267Z","steps":["trace[1610818746] 'read index received' (duration: 108.44353ms)","trace[1610818746] 'applied index is now lower than readState.Index' (duration: 2.287826ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:19.052422Z","caller":"traceutil/trace.go:171","msg":"trace[544471622] transaction","detail":"{read_only:false; response_revision:24739; number_of_response:1; }","duration":"123.021753ms","start":"2026-05-05T23:02:18.92933Z","end":"2026-05-05T23:02:19.052351Z","steps":["trace[544471622] 'process raft request' (duration: 122.87807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:19.052475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.916299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-05-05T23:02:19.052511Z","caller":"traceutil/trace.go:171","msg":"trace[158086312] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:24740; }","duration":"110.997491ms","start":"2026-05-05T23:02:18.941498Z","end":"2026-05-05T23:02:19.052496Z","steps":["trace[158086312] 'agreement among raft nodes before linearized reading' (duration: 110.847288ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:19.052542Z","caller":"traceutil/trace.go:171","msg":"trace[1280617474] transaction","detail":"{read_only:false; response_revision:24740; number_of_response:1; }","duration":"122.91096ms","start":"2026-05-05T23:02:18.929608Z","end":"2026-05-05T23:02:19.052519Z","steps":["trace[1280617474] 'process raft request' (duration: 122.631134ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:19.052556Z","caller":"traceutil/trace.go:171","msg":"trace[1162696161] transaction","detail":"{read_only:false; response_revision:24738; number_of_response:1; }","duration":"123.351459ms","start":"2026-05-05T23:02:18.929164Z","end":"2026-05-05T23:02:19.052515Z","steps":["trace[1162696161] 'process raft request' (duration: 122.89285ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:19.377128Z","caller":"traceutil/trace.go:171","msg":"trace[1749091067] transaction","detail":"{read_only:false; response_revision:24741; number_of_response:1; }","duration":"131.838199ms","start":"2026-05-05T23:02:19.245262Z","end":"2026-05-05T23:02:19.3771Z","steps":["trace[1749091067] 'process raft request' (duration: 131.671246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:19.661076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.818158ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-05T23:02:19.66118Z","caller":"traceutil/trace.go:171","msg":"trace[834793200] linearizableReadLoop","detail":"{readStateIndex:25546; appliedIndex:25544; }","duration":"326.906899ms","start":"2026-05-05T23:02:19.334253Z","end":"2026-05-05T23:02:19.661159Z","steps":["trace[834793200] 'read index received' (duration: 42.646027ms)","trace[834793200] 'applied index is now lower than readState.Index' (duration: 284.259312ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:02:19.661348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.067843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:02:19.661441Z","caller":"traceutil/trace.go:171","msg":"trace[1800627633] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:24741; }","duration":"327.220975ms","start":"2026-05-05T23:02:19.334198Z","end":"2026-05-05T23:02:19.661419Z","steps":["trace[1800627633] 'agreement among raft nodes before linearized reading' (duration: 327.030892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:19.661487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:02:19.334183Z","time spent":"327.294148ms","remote":"127.0.0.1:53496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true "} {"level":"warn","ts":"2026-05-05T23:02:19.661594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.105895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T23:02:19.661641Z","caller":"traceutil/trace.go:171","msg":"trace[1965071047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24741; }","duration":"247.192647ms","start":"2026-05-05T23:02:19.414435Z","end":"2026-05-05T23:02:19.661628Z","steps":["trace[1965071047] 'agreement among raft nodes before linearized reading' (duration: 246.839731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:19.661977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.572143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-05T23:02:19.661966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.152305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:02:19.662048Z","caller":"traceutil/trace.go:171","msg":"trace[202088069] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24741; }","duration":"231.697135ms","start":"2026-05-05T23:02:19.430335Z","end":"2026-05-05T23:02:19.662032Z","steps":["trace[202088069] 'agreement among raft nodes before linearized reading' (duration: 231.477261ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:19.662177Z","caller":"traceutil/trace.go:171","msg":"trace[452167842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24741; }","duration":"231.395219ms","start":"2026-05-05T23:02:19.430722Z","end":"2026-05-05T23:02:19.662117Z","steps":["trace[452167842] 'agreement among raft nodes before linearized reading' (duration: 231.060833ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:19.852061Z","caller":"traceutil/trace.go:171","msg":"trace[1928165257] transaction","detail":"{read_only:false; response_revision:24743; number_of_response:1; }","duration":"185.314963ms","start":"2026-05-05T23:02:19.666698Z","end":"2026-05-05T23:02:19.852013Z","steps":["trace[1928165257] 'process raft request' (duration: 126.398639ms)","trace[1928165257] 'compare' (duration: 58.71915ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:19.85218Z","caller":"traceutil/trace.go:171","msg":"trace[489074946] linearizableReadLoop","detail":"{readStateIndex:25548; appliedIndex:25546; }","duration":"181.239631ms","start":"2026-05-05T23:02:19.670877Z","end":"2026-05-05T23:02:19.852117Z","steps":["trace[489074946] 'read index received' (duration: 81.003508ms)","trace[489074946] 'applied index is now lower than readState.Index' (duration: 100.233773ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:19.852216Z","caller":"traceutil/trace.go:171","msg":"trace[1551853621] transaction","detail":"{read_only:false; response_revision:24744; number_of_response:1; }","duration":"170.412294ms","start":"2026-05-05T23:02:19.681766Z","end":"2026-05-05T23:02:19.852179Z","steps":["trace[1551853621] 'process raft request' (duration: 170.19772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:19.852372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.474385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T23:02:19.852401Z","caller":"traceutil/trace.go:171","msg":"trace[1745227959] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24744; }","duration":"181.561717ms","start":"2026-05-05T23:02:19.670827Z","end":"2026-05-05T23:02:19.852389Z","steps":["trace[1745227959] 'agreement among raft nodes before linearized reading' (duration: 181.402894ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:04:12.982657Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000683e.wal"} {"level":"info","ts":"2026-05-05T23:05:21.100943Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23612} {"level":"info","ts":"2026-05-05T23:05:21.241917Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23612,"took":"136.796939ms","hash":3301919376,"current-db-size-bytes":60780544,"current-db-size":"61 MB","current-db-size-in-use-bytes":38510592,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-05-05T23:05:21.241985Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3301919376,"revision":23612,"compact-revision":20366} {"level":"info","ts":"2026-05-05T23:10:21.110901Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26654} {"level":"info","ts":"2026-05-05T23:10:21.251191Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26654,"took":"135.96362ms","hash":1081862473,"current-db-size-bytes":60780544,"current-db-size":"61 MB","current-db-size-in-use-bytes":32280576,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-05T23:10:21.251266Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1081862473,"revision":26654,"compact-revision":23612} {"level":"info","ts":"2026-05-05T23:10:30.626056Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1c917701ce165bac","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T23:10:30.630977Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-05T23:10:30.631082Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-05T23:15:21.116972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29016} {"level":"info","ts":"2026-05-05T23:15:21.246814Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29016,"took":"126.307406ms","hash":1112728743,"current-db-size-bytes":60780544,"current-db-size":"61 MB","current-db-size-in-use-bytes":30081024,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-05T23:15:21.246887Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1112728743,"revision":29016,"compact-revision":26654} {"level":"info","ts":"2026-05-05T23:20:21.126001Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31237} {"level":"info","ts":"2026-05-05T23:20:21.255819Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31237,"took":"126.924348ms","hash":1329860385,"current-db-size-bytes":60780544,"current-db-size":"61 MB","current-db-size-in-use-bytes":31490048,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-05T23:20:21.255896Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1329860385,"revision":31237,"compact-revision":29016} {"level":"info","ts":"2026-05-05T23:25:21.13296Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33564} {"level":"info","ts":"2026-05-05T23:25:21.28412Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33564,"took":"145.770788ms","hash":2957708019,"current-db-size-bytes":63516672,"current-db-size":"64 MB","current-db-size-in-use-bytes":32776192,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-05T23:25:21.28421Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2957708019,"revision":33564,"compact-revision":31237}