{"level":"warn","ts":"2026-05-11T17:59:35.967742Z","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-11T17:59:35.967825Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.62: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.19.213.62:2380","--initial-cluster=instance=https://199.19.213.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.62: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-11T17:59:35.967888Z","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-11T17:59:35.967902Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.62:2380"]} {"level":"info","ts":"2026-05-11T17:59:35.967921Z","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-11T17:59:35.968557Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.62:2379"]} {"level":"info","ts":"2026-05-11T17:59:35.968633Z","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.19.213.62:2380"],"listen-peer-urls":["https://199.19.213.62:2380"],"advertise-client-urls":["https://199.19.213.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.62: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-11T17:59:35.973107Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.270972ms"} {"level":"info","ts":"2026-05-11T17:59:35.995384Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2451868e402db19e","cluster-id":"1953d1daf63e3757"} {"level":"info","ts":"2026-05-11T17:59:35.99552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T17:59:35.995555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became follower at term 0"} {"level":"info","ts":"2026-05-11T17:59:35.99558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2451868e402db19e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T17:59:35.995597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became follower at term 1"} {"level":"info","ts":"2026-05-11T17:59:35.995655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e switched to configuration voters=(2617020803999183262)"} {"level":"warn","ts":"2026-05-11T17:59:36.002411Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T17:59:36.004919Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T17:59:36.006365Z","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-11T17:59:36.009204Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2451868e402db19e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T17:59:36.00942Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2451868e402db19e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T17:59:36.009648Z","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-11T17:59:36.00976Z","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-11T17:59:36.009772Z","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-11T17:59:36.010526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e switched to configuration voters=(2617020803999183262)"} {"level":"info","ts":"2026-05-11T17:59:36.010789Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1953d1daf63e3757","local-member-id":"2451868e402db19e","added-peer-id":"2451868e402db19e","added-peer-peer-urls":["https://199.19.213.62:2380"]} {"level":"info","ts":"2026-05-11T17:59:36.0134Z","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-11T17:59:36.013698Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2451868e402db19e","initial-advertise-peer-urls":["https://199.19.213.62:2380"],"listen-peer-urls":["https://199.19.213.62:2380"],"advertise-client-urls":["https://199.19.213.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T17:59:36.013746Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T17:59:36.013908Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.62:2380"} {"level":"info","ts":"2026-05-11T17:59:36.013931Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.62:2380"} {"level":"info","ts":"2026-05-11T17:59:36.796258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T17:59:36.796346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T17:59:36.79637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e received MsgPreVoteResp from 2451868e402db19e at term 1"} {"level":"info","ts":"2026-05-11T17:59:36.796389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became candidate at term 2"} {"level":"info","ts":"2026-05-11T17:59:36.7964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e received MsgVoteResp from 2451868e402db19e at term 2"} {"level":"info","ts":"2026-05-11T17:59:36.796416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became leader at term 2"} {"level":"info","ts":"2026-05-11T17:59:36.796429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2451868e402db19e elected leader 2451868e402db19e at term 2"} {"level":"info","ts":"2026-05-11T17:59:36.797643Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T17:59:36.798671Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T17:59:36.798632Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2451868e402db19e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.62:2379]}","request-path":"/0/members/2451868e402db19e/attributes","cluster-id":"1953d1daf63e3757","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T17:59:36.798857Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T17:59:36.799015Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1953d1daf63e3757","local-member-id":"2451868e402db19e","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T17:59:36.799168Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T17:59:36.799147Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T17:59:36.799228Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T17:59:36.799264Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T17:59:36.802185Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.62:2379"} {"level":"info","ts":"2026-05-11T17:59:36.803011Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-11T18:00:08.381472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.8122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:00:08.381589Z","caller":"traceutil/trace.go:171","msg":"trace[2093453017] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:515; }","duration":"101.986164ms","start":"2026-05-11T18:00:08.279585Z","end":"2026-05-11T18:00:08.381571Z","steps":["trace[2093453017] 'range keys from in-memory index tree' (duration: 101.650044ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:00:14.693692Z","caller":"traceutil/trace.go:171","msg":"trace[1154962677] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"118.411698ms","start":"2026-05-11T18:00:14.57521Z","end":"2026-05-11T18:00:14.693621Z","steps":["trace[1154962677] 'process raft request' (duration: 107.224781ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:00:15.220379Z","caller":"traceutil/trace.go:171","msg":"trace[401444834] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"186.815142ms","start":"2026-05-11T18:00:15.033534Z","end":"2026-05-11T18:00:15.220349Z","steps":["trace[401444834] 'process raft request' (duration: 186.572725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:00:15.848826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.599582ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-11T18:00:15.85067Z","caller":"traceutil/trace.go:171","msg":"trace[966425974] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"608.458443ms","start":"2026-05-11T18:00:15.242186Z","end":"2026-05-11T18:00:15.850644Z","steps":["trace[966425974] 'read index received' (duration: 34.852165ms)","trace[966425974] 'applied index is now lower than readState.Index' (duration: 573.604658ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:00:15.850799Z","caller":"traceutil/trace.go:171","msg":"trace[1866889796] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"745.475018ms","start":"2026-05-11T18:00:15.10531Z","end":"2026-05-11T18:00:15.850785Z","steps":["trace[1866889796] 'process raft request' (duration: 744.535992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:00:15.851121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.220894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T18:00:15.851184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.470448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:00:15.851268Z","caller":"traceutil/trace.go:171","msg":"trace[172639538] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:624; }","duration":"229.604782ms","start":"2026-05-11T18:00:15.621639Z","end":"2026-05-11T18:00:15.851244Z","steps":["trace[172639538] 'agreement among raft nodes before linearized reading' (duration: 229.470188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:00:15.851286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.129453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:00:15.851381Z","caller":"traceutil/trace.go:171","msg":"trace[2069228934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"609.233496ms","start":"2026-05-11T18:00:15.242102Z","end":"2026-05-11T18:00:15.851335Z","steps":["trace[2069228934] 'agreement among raft nodes before linearized reading' (duration: 609.138093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:00:15.851501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.58726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:00:15.851612Z","caller":"traceutil/trace.go:171","msg":"trace[1334467652] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:624; }","duration":"230.732394ms","start":"2026-05-11T18:00:15.620851Z","end":"2026-05-11T18:00:15.851584Z","steps":["trace[1334467652] 'agreement among raft nodes before linearized reading' (duration: 230.483407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:00:15.851631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.789255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:00:15.851679Z","caller":"traceutil/trace.go:171","msg":"trace[627960695] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:624; }","duration":"230.867107ms","start":"2026-05-11T18:00:15.620801Z","end":"2026-05-11T18:00:15.851668Z","steps":["trace[627960695] 'agreement among raft nodes before linearized reading' (duration: 230.799605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:00:15.851795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.096585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:00:15.85183Z","caller":"traceutil/trace.go:171","msg":"trace[1005910013] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:624; }","duration":"184.162207ms","start":"2026-05-11T18:00:15.667659Z","end":"2026-05-11T18:00:15.851822Z","steps":["trace[1005910013] 'agreement among raft nodes before linearized reading' (duration: 184.106255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:00:15.851868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.596602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:00:15.851906Z","caller":"traceutil/trace.go:171","msg":"trace[2068575112] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:624; }","duration":"183.664843ms","start":"2026-05-11T18:00:15.668233Z","end":"2026-05-11T18:00:15.851898Z","steps":["trace[2068575112] 'agreement among raft nodes before linearized reading' (duration: 183.606392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:00:15.851791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.738399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:00:15.85229Z","caller":"traceutil/trace.go:171","msg":"trace[361218303] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:624; }","duration":"205.412199ms","start":"2026-05-11T18:00:15.646793Z","end":"2026-05-11T18:00:15.852205Z","steps":["trace[361218303] 'agreement among raft nodes before linearized reading' (duration: 204.343728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:00:15.85202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.350292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:00:15.853611Z","caller":"traceutil/trace.go:171","msg":"trace[2104220336] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:624; }","duration":"185.942837ms","start":"2026-05-11T18:00:15.667654Z","end":"2026-05-11T18:00:15.853597Z","steps":["trace[2104220336] 'agreement among raft nodes before linearized reading' (duration: 184.332022ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:00:15.853006Z","caller":"traceutil/trace.go:171","msg":"trace[401825643] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:624; }","duration":"204.344407ms","start":"2026-05-11T18:00:15.646843Z","end":"2026-05-11T18:00:15.851187Z","steps":["trace[401825643] 'agreement among raft nodes before linearized reading' (duration: 204.186623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:00:15.85204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.40876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:00:15.853745Z","caller":"traceutil/trace.go:171","msg":"trace[1319812738] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:624; }","duration":"206.130258ms","start":"2026-05-11T18:00:15.647589Z","end":"2026-05-11T18:00:15.853719Z","steps":["trace[1319812738] 'agreement among raft nodes before linearized reading' (duration: 204.432251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:00:15.852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.991822ms","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-11T18:00:15.853898Z","caller":"traceutil/trace.go:171","msg":"trace[599728893] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"249.941407ms","start":"2026-05-11T18:00:15.603914Z","end":"2026-05-11T18:00:15.853855Z","steps":["trace[599728893] 'agreement among raft nodes before linearized reading' (duration: 247.088796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:00:15.851428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:00:15.242083Z","time spent":"609.335198ms","remote":"127.0.0.1:51390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T18:00:15.85153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:00:15.105276Z","time spent":"745.569921ms","remote":"127.0.0.1:51508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:02:20.950939Z","caller":"traceutil/trace.go:171","msg":"trace[2004094090] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"203.328993ms","start":"2026-05-11T18:02:20.747571Z","end":"2026-05-11T18:02:20.9509Z","steps":["trace[2004094090] 'process raft request' (duration: 200.188464ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:02:34.263692Z","caller":"traceutil/trace.go:171","msg":"trace[587678783] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"129.353939ms","start":"2026-05-11T18:02:34.134315Z","end":"2026-05-11T18:02:34.263669Z","steps":["trace[587678783] 'process raft request' (duration: 128.494815ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:03:04.274653Z","caller":"traceutil/trace.go:171","msg":"trace[1886180946] transaction","detail":"{read_only:false; response_revision:2251; number_of_response:1; }","duration":"133.862356ms","start":"2026-05-11T18:03:04.140765Z","end":"2026-05-11T18:03:04.274627Z","steps":["trace[1886180946] 'process raft request' (duration: 133.63901ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:03:04.415055Z","caller":"traceutil/trace.go:171","msg":"trace[1679276958] transaction","detail":"{read_only:false; response_revision:2252; number_of_response:1; }","duration":"134.735691ms","start":"2026-05-11T18:03:04.280284Z","end":"2026-05-11T18:03:04.415019Z","steps":["trace[1679276958] 'process raft request' (duration: 103.774022ms)","trace[1679276958] 'compare' (duration: 30.489706ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:03:11.224374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.703398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T18:03:11.224575Z","caller":"traceutil/trace.go:171","msg":"trace[1292422419] transaction","detail":"{read_only:false; response_revision:2288; number_of_response:1; }","duration":"320.473803ms","start":"2026-05-11T18:03:10.90408Z","end":"2026-05-11T18:03:11.224554Z","steps":["trace[1292422419] 'process raft request' (duration: 164.499966ms)","trace[1292422419] 'compare' (duration: 155.582095ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:03:11.224642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:03:10.904046Z","time spent":"320.565205ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:03:16.730214Z","caller":"traceutil/trace.go:171","msg":"trace[1740607206] transaction","detail":"{read_only:false; response_revision:2317; number_of_response:1; }","duration":"127.381395ms","start":"2026-05-11T18:03:16.602806Z","end":"2026-05-11T18:03:16.730187Z","steps":["trace[1740607206] 'process raft request' (duration: 96.675483ms)","trace[1740607206] 'compare' (duration: 30.591638ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:04:37.775934Z","caller":"traceutil/trace.go:171","msg":"trace[643578457] transaction","detail":"{read_only:false; response_revision:2949; number_of_response:1; }","duration":"100.536728ms","start":"2026-05-11T18:04:37.675364Z","end":"2026-05-11T18:04:37.775901Z","steps":["trace[643578457] 'process raft request' (duration: 100.230549ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:04:43.930048Z","caller":"traceutil/trace.go:171","msg":"trace[394560494] transaction","detail":"{read_only:false; response_revision:2984; number_of_response:1; }","duration":"109.086047ms","start":"2026-05-11T18:04:43.820919Z","end":"2026-05-11T18:04:43.930005Z","steps":["trace[394560494] 'process raft request' (duration: 14.040973ms)","trace[394560494] 'compare' (duration: 94.695045ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:04:43.930092Z","caller":"traceutil/trace.go:171","msg":"trace[349302432] transaction","detail":"{read_only:false; response_revision:2985; number_of_response:1; }","duration":"109.120759ms","start":"2026-05-11T18:04:43.820926Z","end":"2026-05-11T18:04:43.930047Z","steps":["trace[349302432] 'process raft request' (duration: 109.029636ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:08:41.703277Z","caller":"traceutil/trace.go:171","msg":"trace[394368248] transaction","detail":"{read_only:false; response_revision:5154; number_of_response:1; }","duration":"128.803465ms","start":"2026-05-11T18:08:41.574431Z","end":"2026-05-11T18:08:41.703234Z","steps":["trace[394368248] 'process raft request' (duration: 128.528607ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:08:41.900049Z","caller":"traceutil/trace.go:171","msg":"trace[22445754] transaction","detail":"{read_only:false; response_revision:5156; number_of_response:1; }","duration":"188.301009ms","start":"2026-05-11T18:08:41.711714Z","end":"2026-05-11T18:08:41.900015Z","steps":["trace[22445754] 'process raft request' (duration: 178.706929ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:08:44.103078Z","caller":"traceutil/trace.go:171","msg":"trace[1319296273] linearizableReadLoop","detail":"{readStateIndex:5385; appliedIndex:5384; }","duration":"181.500568ms","start":"2026-05-11T18:08:43.92154Z","end":"2026-05-11T18:08:44.103041Z","steps":["trace[1319296273] 'read index received' (duration: 181.281392ms)","trace[1319296273] 'applied index is now lower than readState.Index' (duration: 215.876µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:08:44.103177Z","caller":"traceutil/trace.go:171","msg":"trace[423381021] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"192.377785ms","start":"2026-05-11T18:08:43.910764Z","end":"2026-05-11T18:08:44.103142Z","steps":["trace[423381021] 'process raft request' (duration: 192.097057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:08:44.103486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.92933ms","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-11T18:08:44.103561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.212607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:779106"} {"level":"info","ts":"2026-05-11T18:08:44.103558Z","caller":"traceutil/trace.go:171","msg":"trace[1193155553] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:5189; }","duration":"182.051084ms","start":"2026-05-11T18:08:43.921489Z","end":"2026-05-11T18:08:44.10354Z","steps":["trace[1193155553] 'agreement among raft nodes before linearized reading' (duration: 181.738055ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:08:44.103586Z","caller":"traceutil/trace.go:171","msg":"trace[1544457988] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5189; }","duration":"118.282139ms","start":"2026-05-11T18:08:43.985297Z","end":"2026-05-11T18:08:44.103579Z","steps":["trace[1544457988] 'agreement among raft nodes before linearized reading' (duration: 117.9634ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:09:13.163235Z","caller":"traceutil/trace.go:171","msg":"trace[231844995] transaction","detail":"{read_only:false; response_revision:5725; number_of_response:1; }","duration":"117.640068ms","start":"2026-05-11T18:09:13.045549Z","end":"2026-05-11T18:09:13.163189Z","steps":["trace[231844995] 'process raft request' (duration: 112.968715ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:09:13.163316Z","caller":"traceutil/trace.go:171","msg":"trace[817920540] transaction","detail":"{read_only:false; response_revision:5726; number_of_response:1; }","duration":"116.134025ms","start":"2026-05-11T18:09:13.04714Z","end":"2026-05-11T18:09:13.163274Z","steps":["trace[817920540] 'process raft request' (duration: 115.97443ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:09:13.163479Z","caller":"traceutil/trace.go:171","msg":"trace[1227940640] transaction","detail":"{read_only:false; response_revision:5727; number_of_response:1; }","duration":"116.235247ms","start":"2026-05-11T18:09:13.04721Z","end":"2026-05-11T18:09:13.163445Z","steps":["trace[1227940640] 'process raft request' (duration: 116.000301ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:09:36.833294Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2943} {"level":"info","ts":"2026-05-11T18:09:36.912271Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2943,"took":"76.697185ms","hash":221421648,"current-db-size-bytes":56279040,"current-db-size":"56 MB","current-db-size-in-use-bytes":32251904,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T18:09:36.912341Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":221421648,"revision":2943,"compact-revision":-1} {"level":"info","ts":"2026-05-11T18:10:50.250104Z","caller":"traceutil/trace.go:171","msg":"trace[2041581311] linearizableReadLoop","detail":"{readStateIndex:6725; appliedIndex:6724; }","duration":"497.071085ms","start":"2026-05-11T18:10:49.753011Z","end":"2026-05-11T18:10:50.250082Z","steps":["trace[2041581311] 'read index received' (duration: 496.88736ms)","trace[2041581311] 'applied index is now lower than readState.Index' (duration: 182.645µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:10:50.250357Z","caller":"traceutil/trace.go:171","msg":"trace[345222290] transaction","detail":"{read_only:false; response_revision:6493; number_of_response:1; }","duration":"517.851775ms","start":"2026-05-11T18:10:49.732491Z","end":"2026-05-11T18:10:50.250343Z","steps":["trace[345222290] 'process raft request' (duration: 517.455594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:10:50.250464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:10:49.732452Z","time spent":"517.941287ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:10:50.25073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.723212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-11T18:10:50.250771Z","caller":"traceutil/trace.go:171","msg":"trace[383198992] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6493; }","duration":"497.834735ms","start":"2026-05-11T18:10:49.752926Z","end":"2026-05-11T18:10:50.250761Z","steps":["trace[383198992] 'agreement among raft nodes before linearized reading' (duration: 497.723662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:10:50.250799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:10:49.752885Z","time spent":"497.903787ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T18:10:50.250962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.564038ms","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-11T18:10:50.250986Z","caller":"traceutil/trace.go:171","msg":"trace[1412660796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6493; }","duration":"322.630279ms","start":"2026-05-11T18:10:49.928348Z","end":"2026-05-11T18:10:50.250979Z","steps":["trace[1412660796] 'agreement among raft nodes before linearized reading' (duration: 322.559927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:10:50.251009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:10:49.928318Z","time spent":"322.684731ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T18:10:50.251512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.090323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-11T18:10:50.251542Z","caller":"traceutil/trace.go:171","msg":"trace[824659718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6493; }","duration":"323.130334ms","start":"2026-05-11T18:10:49.928403Z","end":"2026-05-11T18:10:50.251533Z","steps":["trace[824659718] 'agreement among raft nodes before linearized reading' (duration: 323.044591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:10:50.251567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:10:49.9284Z","time spent":"323.161114ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-11T18:14:36.8386Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5873} {"level":"info","ts":"2026-05-11T18:14:36.922733Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5873,"took":"81.726449ms","hash":3447763536,"current-db-size-bytes":56279040,"current-db-size":"56 MB","current-db-size-in-use-bytes":26456064,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-11T18:14:36.922813Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3447763536,"revision":5873,"compact-revision":2943} {"level":"info","ts":"2026-05-11T18:15:25.541495Z","caller":"traceutil/trace.go:171","msg":"trace[154022411] transaction","detail":"{read_only:false; response_revision:8486; number_of_response:1; }","duration":"106.429988ms","start":"2026-05-11T18:15:25.435016Z","end":"2026-05-11T18:15:25.541446Z","steps":["trace[154022411] 'process raft request' (duration: 106.075308ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:15:25.925827Z","caller":"traceutil/trace.go:171","msg":"trace[1385791437] linearizableReadLoop","detail":"{readStateIndex:8793; appliedIndex:8790; }","duration":"181.09124ms","start":"2026-05-11T18:15:25.744696Z","end":"2026-05-11T18:15:25.925787Z","steps":["trace[1385791437] 'read index received' (duration: 2.195671ms)","trace[1385791437] 'applied index is now lower than readState.Index' (duration: 178.894499ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:15:25.926041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.332907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-11T18:15:25.926083Z","caller":"traceutil/trace.go:171","msg":"trace[224581036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8490; }","duration":"181.422139ms","start":"2026-05-11T18:15:25.744645Z","end":"2026-05-11T18:15:25.926067Z","steps":["trace[224581036] 'agreement among raft nodes before linearized reading' (duration: 181.251074ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:15:25.926397Z","caller":"traceutil/trace.go:171","msg":"trace[1188984192] transaction","detail":"{read_only:false; response_revision:8489; number_of_response:1; }","duration":"183.031473ms","start":"2026-05-11T18:15:25.743355Z","end":"2026-05-11T18:15:25.926386Z","steps":["trace[1188984192] 'process raft request' (duration: 182.221831ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:15:25.926604Z","caller":"traceutil/trace.go:171","msg":"trace[225590576] transaction","detail":"{read_only:false; response_revision:8490; number_of_response:1; }","duration":"183.158226ms","start":"2026-05-11T18:15:25.743435Z","end":"2026-05-11T18:15:25.926594Z","steps":["trace[225590576] 'process raft request' (duration: 182.302013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:15:26.113269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.494003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T18:15:26.113457Z","caller":"traceutil/trace.go:171","msg":"trace[1972004933] linearizableReadLoop","detail":"{readStateIndex:8795; appliedIndex:8793; }","duration":"141.689741ms","start":"2026-05-11T18:15:25.971747Z","end":"2026-05-11T18:15:26.113437Z","steps":["trace[1972004933] 'read index received' (duration: 13.536893ms)","trace[1972004933] 'applied index is now lower than readState.Index' (duration: 128.151198ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:15:26.113513Z","caller":"traceutil/trace.go:171","msg":"trace[1987115318] transaction","detail":"{read_only:false; response_revision:8492; number_of_response:1; }","duration":"182.59564ms","start":"2026-05-11T18:15:25.930876Z","end":"2026-05-11T18:15:26.113471Z","steps":["trace[1987115318] 'process raft request' (duration: 72.793659ms)","trace[1987115318] 'compare' (duration: 109.332939ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:15:26.113591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.851666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6pfhp\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-11T18:15:26.113629Z","caller":"traceutil/trace.go:171","msg":"trace[1906200180] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6pfhp; range_end:; response_count:1; response_revision:8492; }","duration":"141.946558ms","start":"2026-05-11T18:15:25.971671Z","end":"2026-05-11T18:15:26.113617Z","steps":["trace[1906200180] 'agreement among raft nodes before linearized reading' (duration: 141.822475ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:15:28.258722Z","caller":"traceutil/trace.go:171","msg":"trace[781205461] linearizableReadLoop","detail":"{readStateIndex:8805; appliedIndex:8804; }","duration":"132.124648ms","start":"2026-05-11T18:15:28.126561Z","end":"2026-05-11T18:15:28.258686Z","steps":["trace[781205461] 'read index received' (duration: 131.82249ms)","trace[781205461] 'applied index is now lower than readState.Index' (duration: 300.468µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:15:28.258816Z","caller":"traceutil/trace.go:171","msg":"trace[302201887] transaction","detail":"{read_only:false; response_revision:8502; number_of_response:1; }","duration":"137.28641ms","start":"2026-05-11T18:15:28.121492Z","end":"2026-05-11T18:15:28.258778Z","steps":["trace[302201887] 'process raft request' (duration: 136.873949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:15:28.25901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.431706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6pfhp\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-11T18:15:28.259112Z","caller":"traceutil/trace.go:171","msg":"trace[513091821] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6pfhp; range_end:; response_count:1; response_revision:8502; }","duration":"132.606811ms","start":"2026-05-11T18:15:28.126483Z","end":"2026-05-11T18:15:28.25909Z","steps":["trace[513091821] 'agreement among raft nodes before linearized reading' (duration: 132.390505ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:15:56.28121Z","caller":"traceutil/trace.go:171","msg":"trace[793801690] transaction","detail":"{read_only:false; response_revision:8704; number_of_response:1; }","duration":"130.010659ms","start":"2026-05-11T18:15:56.151166Z","end":"2026-05-11T18:15:56.281177Z","steps":["trace[793801690] 'process raft request' (duration: 129.65686ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:15:56.290862Z","caller":"traceutil/trace.go:171","msg":"trace[502960796] linearizableReadLoop","detail":"{readStateIndex:9016; appliedIndex:9014; }","duration":"102.405787ms","start":"2026-05-11T18:15:56.188437Z","end":"2026-05-11T18:15:56.290843Z","steps":["trace[502960796] 'read index received' (duration: 92.657938ms)","trace[502960796] 'applied index is now lower than readState.Index' (duration: 9.746579ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:15:56.291095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.634863ms","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-11T18:15:56.291137Z","caller":"traceutil/trace.go:171","msg":"trace[1530484152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8705; }","duration":"102.746615ms","start":"2026-05-11T18:15:56.18838Z","end":"2026-05-11T18:15:56.291127Z","steps":["trace[1530484152] 'agreement among raft nodes before linearized reading' (duration: 102.536ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:15:56.291329Z","caller":"traceutil/trace.go:171","msg":"trace[1601883302] transaction","detail":"{read_only:false; response_revision:8705; number_of_response:1; }","duration":"138.142643ms","start":"2026-05-11T18:15:56.153157Z","end":"2026-05-11T18:15:56.2913Z","steps":["trace[1601883302] 'process raft request' (duration: 137.526246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:16:09.308495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.968477ms","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-11T18:16:09.308585Z","caller":"traceutil/trace.go:171","msg":"trace[910636179] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8815; }","duration":"172.128301ms","start":"2026-05-11T18:16:09.136439Z","end":"2026-05-11T18:16:09.308568Z","steps":["trace[910636179] 'range keys from in-memory index tree' (duration: 171.671169ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:16:09.515036Z","caller":"traceutil/trace.go:171","msg":"trace[620217050] transaction","detail":"{read_only:false; response_revision:8816; number_of_response:1; }","duration":"200.274449ms","start":"2026-05-11T18:16:09.314731Z","end":"2026-05-11T18:16:09.515006Z","steps":["trace[620217050] 'process raft request' (duration: 199.903749ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:16:26.358382Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002459.wal"} {"level":"info","ts":"2026-05-11T18:17:14.575004Z","caller":"traceutil/trace.go:171","msg":"trace[281112178] transaction","detail":"{read_only:false; response_revision:9499; number_of_response:1; }","duration":"119.730622ms","start":"2026-05-11T18:17:14.455247Z","end":"2026-05-11T18:17:14.574978Z","steps":["trace[281112178] 'process raft request' (duration: 119.526876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:17:27.38101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.648986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:17:27.381108Z","caller":"traceutil/trace.go:171","msg":"trace[406587090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9574; }","duration":"139.816651ms","start":"2026-05-11T18:17:27.24127Z","end":"2026-05-11T18:17:27.381086Z","steps":["trace[406587090] 'range keys from in-memory index tree' (duration: 139.533442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:17:27.38101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.393686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6pfhp\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-11T18:17:27.381259Z","caller":"traceutil/trace.go:171","msg":"trace[1218265798] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6pfhp; range_end:; response_count:1; response_revision:9574; }","duration":"179.675423ms","start":"2026-05-11T18:17:27.201537Z","end":"2026-05-11T18:17:27.381212Z","steps":["trace[1218265798] 'range keys from in-memory index tree' (duration: 179.263901ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:17:38.731371Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2451868e402db19e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T18:17:38.736584Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-11T18:17:38.736671Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-11T18:19:36.84547Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8051} {"level":"info","ts":"2026-05-11T18:19:36.914111Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8051,"took":"66.156575ms","hash":3295137226,"current-db-size-bytes":56279040,"current-db-size":"56 MB","current-db-size-in-use-bytes":28647424,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-11T18:19:36.914204Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3295137226,"revision":8051,"compact-revision":5873} {"level":"info","ts":"2026-05-11T18:22:47.609117Z","caller":"traceutil/trace.go:171","msg":"trace[717041484] transaction","detail":"{read_only:false; response_revision:11906; number_of_response:1; }","duration":"109.121141ms","start":"2026-05-11T18:22:47.499971Z","end":"2026-05-11T18:22:47.609092Z","steps":["trace[717041484] 'process raft request' (duration: 108.910215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:22:47.863468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.898522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T18:22:47.863655Z","caller":"traceutil/trace.go:171","msg":"trace[1506745542] transaction","detail":"{read_only:false; response_revision:11908; number_of_response:1; }","duration":"219.117415ms","start":"2026-05-11T18:22:47.644514Z","end":"2026-05-11T18:22:47.863632Z","steps":["trace[1506745542] 'process raft request' (duration: 50.932855ms)","trace[1506745542] 'compare' (duration: 167.780739ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:23:05.398468Z","caller":"traceutil/trace.go:171","msg":"trace[1407023849] transaction","detail":"{read_only:false; response_revision:12006; number_of_response:1; }","duration":"135.139639ms","start":"2026-05-11T18:23:05.263304Z","end":"2026-05-11T18:23:05.398444Z","steps":["trace[1407023849] 'process raft request' (duration: 134.995575ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:23:05.398472Z","caller":"traceutil/trace.go:171","msg":"trace[1791397494] linearizableReadLoop","detail":"{readStateIndex:12422; appliedIndex:12421; }","duration":"156.264532ms","start":"2026-05-11T18:23:05.242176Z","end":"2026-05-11T18:23:05.39844Z","steps":["trace[1791397494] 'read index received' (duration: 88.659417ms)","trace[1791397494] 'applied index is now lower than readState.Index' (duration: 67.601645ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:23:05.398644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.451148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:23:05.398788Z","caller":"traceutil/trace.go:171","msg":"trace[1914485315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12006; }","duration":"156.582211ms","start":"2026-05-11T18:23:05.242156Z","end":"2026-05-11T18:23:05.398738Z","steps":["trace[1914485315] 'agreement among raft nodes before linearized reading' (duration: 156.387236ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:23:06.93415Z","caller":"traceutil/trace.go:171","msg":"trace[1600766475] transaction","detail":"{read_only:false; response_revision:12012; number_of_response:1; }","duration":"211.152646ms","start":"2026-05-11T18:23:06.722959Z","end":"2026-05-11T18:23:06.934111Z","steps":["trace[1600766475] 'process raft request' (duration: 210.807327ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:23:06.954843Z","caller":"traceutil/trace.go:171","msg":"trace[1014954995] transaction","detail":"{read_only:false; response_revision:12013; number_of_response:1; }","duration":"231.52431ms","start":"2026-05-11T18:23:06.723275Z","end":"2026-05-11T18:23:06.954799Z","steps":["trace[1014954995] 'process raft request' (duration: 231.240582ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:24:36.852337Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10458} {"level":"info","ts":"2026-05-11T18:24:36.934248Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10458,"took":"79.187525ms","hash":293123740,"current-db-size-bytes":56279040,"current-db-size":"56 MB","current-db-size-in-use-bytes":25923584,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-11T18:24:36.934311Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":293123740,"revision":10458,"compact-revision":8051} {"level":"info","ts":"2026-05-11T18:29:36.863591Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12621} {"level":"info","ts":"2026-05-11T18:29:36.958394Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12621,"took":"90.635172ms","hash":525338703,"current-db-size-bytes":56279040,"current-db-size":"56 MB","current-db-size-in-use-bytes":27463680,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-11T18:29:36.958448Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":525338703,"revision":12621,"compact-revision":10458} {"level":"warn","ts":"2026-05-11T18:29:44.731402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.622445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/openvswitch-server\" ","response":"range_response_count:1 size:543"} {"level":"info","ts":"2026-05-11T18:29:44.731503Z","caller":"traceutil/trace.go:171","msg":"trace[1363804861] range","detail":"{range_begin:/registry/serviceaccounts/openstack/openvswitch-server; range_end:; response_count:1; response_revision:14669; }","duration":"129.776829ms","start":"2026-05-11T18:29:44.601707Z","end":"2026-05-11T18:29:44.731484Z","steps":["trace[1363804861] 'range keys from in-memory index tree' (duration: 129.44741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:29:44.732164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.515142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:86 size:946022"} {"level":"info","ts":"2026-05-11T18:29:44.732259Z","caller":"traceutil/trace.go:171","msg":"trace[1800083034] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:86; response_revision:14669; }","duration":"102.673457ms","start":"2026-05-11T18:29:44.629567Z","end":"2026-05-11T18:29:44.73224Z","steps":["trace[1800083034] 'range keys from in-memory index tree' (duration: 101.638507ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:29:44.972931Z","caller":"traceutil/trace.go:171","msg":"trace[1074873583] linearizableReadLoop","detail":"{readStateIndex:15183; appliedIndex:15182; }","duration":"153.065728ms","start":"2026-05-11T18:29:44.819831Z","end":"2026-05-11T18:29:44.972897Z","steps":["trace[1074873583] 'read index received' (duration: 152.916564ms)","trace[1074873583] 'applied index is now lower than readState.Index' (duration: 147.344µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:29:44.973024Z","caller":"traceutil/trace.go:171","msg":"trace[1090109339] transaction","detail":"{read_only:false; response_revision:14670; number_of_response:1; }","duration":"236.909171ms","start":"2026-05-11T18:29:44.736094Z","end":"2026-05-11T18:29:44.973003Z","steps":["trace[1090109339] 'process raft request' (duration: 236.622613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:29:44.973258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.395727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18953"} {"level":"info","ts":"2026-05-11T18:29:44.973326Z","caller":"traceutil/trace.go:171","msg":"trace[1079057638] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:14670; }","duration":"153.542091ms","start":"2026-05-11T18:29:44.819765Z","end":"2026-05-11T18:29:44.973307Z","steps":["trace[1079057638] 'agreement among raft nodes before linearized reading' (duration: 153.278204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:31:37.680236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.970983ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T18:31:37.680365Z","caller":"traceutil/trace.go:171","msg":"trace[1709662875] linearizableReadLoop","detail":"{readStateIndex:16496; appliedIndex:16495; }","duration":"112.984141ms","start":"2026-05-11T18:31:37.567364Z","end":"2026-05-11T18:31:37.680348Z","steps":["trace[1709662875] 'read index received' (duration: 78.602µs)","trace[1709662875] 'applied index is now lower than readState.Index' (duration: 112.904159ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:31:37.68074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.343891ms","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-11T18:31:37.680911Z","caller":"traceutil/trace.go:171","msg":"trace[40680674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15944; }","duration":"113.60942ms","start":"2026-05-11T18:31:37.567267Z","end":"2026-05-11T18:31:37.680876Z","steps":["trace[40680674] 'agreement among raft nodes before linearized reading' (duration: 113.184547ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:31:37.68038Z","caller":"traceutil/trace.go:171","msg":"trace[1472679029] transaction","detail":"{read_only:false; response_revision:15944; number_of_response:1; }","duration":"195.661907ms","start":"2026-05-11T18:31:37.48469Z","end":"2026-05-11T18:31:37.680352Z","steps":["trace[1472679029] 'process raft request' (duration: 59.470269ms)","trace[1472679029] 'compare' (duration: 135.758526ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:31:37.875393Z","caller":"traceutil/trace.go:171","msg":"trace[465922755] transaction","detail":"{read_only:false; response_revision:15945; number_of_response:1; }","duration":"189.870907ms","start":"2026-05-11T18:31:37.685491Z","end":"2026-05-11T18:31:37.875361Z","steps":["trace[465922755] 'process raft request' (duration: 119.70555ms)","trace[465922755] 'compare' (duration: 70.033253ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:31:37.923033Z","caller":"traceutil/trace.go:171","msg":"trace[198878449] transaction","detail":"{read_only:false; response_revision:15946; number_of_response:1; }","duration":"236.699653ms","start":"2026-05-11T18:31:37.68628Z","end":"2026-05-11T18:31:37.92298Z","steps":["trace[198878449] 'process raft request' (duration: 236.415146ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:31:37.923089Z","caller":"traceutil/trace.go:171","msg":"trace[1113584085] transaction","detail":"{read_only:false; response_revision:15947; number_of_response:1; }","duration":"236.314432ms","start":"2026-05-11T18:31:37.686723Z","end":"2026-05-11T18:31:37.923037Z","steps":["trace[1113584085] 'process raft request' (duration: 236.118987ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:31:37.923133Z","caller":"traceutil/trace.go:171","msg":"trace[320281932] transaction","detail":"{read_only:false; response_revision:15948; number_of_response:1; }","duration":"235.415248ms","start":"2026-05-11T18:31:37.68768Z","end":"2026-05-11T18:31:37.923095Z","steps":["trace[320281932] 'process raft request' (duration: 235.282494ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:31:37.923414Z","caller":"traceutil/trace.go:171","msg":"trace[1382691731] transaction","detail":"{read_only:false; response_revision:15949; number_of_response:1; }","duration":"225.674665ms","start":"2026-05-11T18:31:37.697728Z","end":"2026-05-11T18:31:37.923402Z","steps":["trace[1382691731] 'process raft request' (duration: 225.273334ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:31:37.924167Z","caller":"traceutil/trace.go:171","msg":"trace[1384557154] linearizableReadLoop","detail":"{readStateIndex:16502; appliedIndex:16496; }","duration":"174.346513ms","start":"2026-05-11T18:31:37.749796Z","end":"2026-05-11T18:31:37.924142Z","steps":["trace[1384557154] 'read index received' (duration: 55.484748ms)","trace[1384557154] 'applied index is now lower than readState.Index' (duration: 118.859635ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:31:37.924282Z","caller":"traceutil/trace.go:171","msg":"trace[1185452137] transaction","detail":"{read_only:false; response_revision:15950; number_of_response:1; }","duration":"222.97279ms","start":"2026-05-11T18:31:37.701297Z","end":"2026-05-11T18:31:37.92427Z","steps":["trace[1185452137] 'process raft request' (duration: 221.746196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:31:37.924499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.685003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:31:37.92455Z","caller":"traceutil/trace.go:171","msg":"trace[84561574] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15950; }","duration":"174.782666ms","start":"2026-05-11T18:31:37.749749Z","end":"2026-05-11T18:31:37.924532Z","steps":["trace[84561574] 'agreement among raft nodes before linearized reading' (duration: 174.688203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:31:37.924802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.94182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:31:37.924841Z","caller":"traceutil/trace.go:171","msg":"trace[516432527] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15950; }","duration":"174.990622ms","start":"2026-05-11T18:31:37.74984Z","end":"2026-05-11T18:31:37.924831Z","steps":["trace[516432527] 'agreement among raft nodes before linearized reading' (duration: 174.932921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:31:37.925793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.130265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-05-11T18:31:37.92584Z","caller":"traceutil/trace.go:171","msg":"trace[2078233012] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15950; }","duration":"147.192107ms","start":"2026-05-11T18:31:37.778638Z","end":"2026-05-11T18:31:37.92583Z","steps":["trace[2078233012] 'agreement among raft nodes before linearized reading' (duration: 147.093974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:31:37.925936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.447925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:31:37.925971Z","caller":"traceutil/trace.go:171","msg":"trace[949953372] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:15950; }","duration":"151.492487ms","start":"2026-05-11T18:31:37.774468Z","end":"2026-05-11T18:31:37.92596Z","steps":["trace[949953372] 'agreement among raft nodes before linearized reading' (duration: 151.439845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:31:37.926116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.681252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:31:37.926606Z","caller":"traceutil/trace.go:171","msg":"trace[1770746945] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15950; }","duration":"152.154304ms","start":"2026-05-11T18:31:37.774415Z","end":"2026-05-11T18:31:37.92657Z","steps":["trace[1770746945] 'agreement among raft nodes before linearized reading' (duration: 151.679381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:31:37.92665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.955842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-05-11T18:31:37.926697Z","caller":"traceutil/trace.go:171","msg":"trace[1859860964] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15950; }","duration":"174.009294ms","start":"2026-05-11T18:31:37.752671Z","end":"2026-05-11T18:31:37.92668Z","steps":["trace[1859860964] 'agreement among raft nodes before linearized reading' (duration: 173.801759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:31:37.926765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.884664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:31:37.926802Z","caller":"traceutil/trace.go:171","msg":"trace[1379340929] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15950; }","duration":"176.933615ms","start":"2026-05-11T18:31:37.74986Z","end":"2026-05-11T18:31:37.926794Z","steps":["trace[1379340929] 'agreement among raft nodes before linearized reading' (duration: 176.870144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:31:37.926362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.02323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:31:37.926887Z","caller":"traceutil/trace.go:171","msg":"trace[337573356] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15950; }","duration":"152.586826ms","start":"2026-05-11T18:31:37.774287Z","end":"2026-05-11T18:31:37.926874Z","steps":["trace[337573356] 'agreement among raft nodes before linearized reading' (duration: 151.958748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:31:37.926444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.164125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:31:37.927015Z","caller":"traceutil/trace.go:171","msg":"trace[1539636934] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15950; }","duration":"152.759271ms","start":"2026-05-11T18:31:37.774248Z","end":"2026-05-11T18:31:37.927007Z","steps":["trace[1539636934] 'agreement among raft nodes before linearized reading' (duration: 152.162825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:42.699983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12798841017785115511,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T18:32:43.200693Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12798841017785115511,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T18:32:43.702606Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12798841017785115511,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T18:32:44.203442Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12798841017785115511,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T18:32:44.241505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000137419s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-11T18:32:44.241591Z","caller":"traceutil/trace.go:171","msg":"trace[1352755320] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000263431s","start":"2026-05-11T18:32:42.241304Z","end":"2026-05-11T18:32:44.241567Z","steps":["trace[1352755320] 'agreement among raft nodes before linearized reading' (duration: 2.000157768s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:44.24165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:42.241293Z","time spent":"2.000344054s","remote":"127.0.0.1:51380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T18:32:44.704486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12798841017785115511,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T18:32:45.205344Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12798841017785115511,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T18:32:45.658285Z","caller":"traceutil/trace.go:171","msg":"trace[1178878561] transaction","detail":"{read_only:false; response_revision:16363; number_of_response:1; }","duration":"3.521070158s","start":"2026-05-11T18:32:42.137198Z","end":"2026-05-11T18:32:45.658268Z","steps":["trace[1178878561] 'process raft request' (duration: 3.520960055s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.658415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:42.137161Z","time spent":"3.521178871s","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:32:45.663567Z","caller":"traceutil/trace.go:171","msg":"trace[18497557] transaction","detail":"{read_only:false; response_revision:16364; number_of_response:1; }","duration":"3.523673201s","start":"2026-05-11T18:32:42.139845Z","end":"2026-05-11T18:32:45.663518Z","steps":["trace[18497557] 'process raft request' (duration: 3.523376703s)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:32:45.663624Z","caller":"traceutil/trace.go:171","msg":"trace[670839013] transaction","detail":"{read_only:false; response_revision:16367; number_of_response:1; }","duration":"2.009625412s","start":"2026-05-11T18:32:43.653972Z","end":"2026-05-11T18:32:45.663597Z","steps":["trace[670839013] 'process raft request' (duration: 2.009529099s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.663787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:42.139813Z","time spent":"3.523850286s","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:32:45.663818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:43.653948Z","time spent":"2.009756216s","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:32:45.664015Z","caller":"traceutil/trace.go:171","msg":"trace[1404675300] transaction","detail":"{read_only:false; response_revision:16365; number_of_response:1; }","duration":"3.154166572s","start":"2026-05-11T18:32:42.509832Z","end":"2026-05-11T18:32:45.663998Z","steps":["trace[1404675300] 'process raft request' (duration: 3.153531475s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.664095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:42.509808Z","time spent":"3.154244075s","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:32:45.664227Z","caller":"traceutil/trace.go:171","msg":"trace[989454303] transaction","detail":"{read_only:false; response_revision:16366; number_of_response:1; }","duration":"2.969242893s","start":"2026-05-11T18:32:42.694947Z","end":"2026-05-11T18:32:45.664189Z","steps":["trace[989454303] 'process raft request' (duration: 2.968509703s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.66437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:42.694917Z","time spent":"2.969378847s","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:32:45.665867Z","caller":"traceutil/trace.go:171","msg":"trace[1050160493] linearizableReadLoop","detail":"{readStateIndex:16933; appliedIndex:16929; }","duration":"3.466887636s","start":"2026-05-11T18:32:42.198955Z","end":"2026-05-11T18:32:45.665843Z","steps":["trace[1050160493] 'read index received' (duration: 3.459370116s)","trace[1050160493] 'applied index is now lower than readState.Index' (duration: 7.51616ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:32:45.666133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.467173224s","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-11T18:32:45.666189Z","caller":"traceutil/trace.go:171","msg":"trace[119283266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16367; }","duration":"3.467292877s","start":"2026-05-11T18:32:42.198882Z","end":"2026-05-11T18:32:45.666175Z","steps":["trace[119283266] 'agreement among raft nodes before linearized reading' (duration: 3.467111882s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.666235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:42.198854Z","time spent":"3.467370919s","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T18:32:45.666255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.446758665s","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-11T18:32:45.666353Z","caller":"traceutil/trace.go:171","msg":"trace[347863320] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16367; }","duration":"3.446897207s","start":"2026-05-11T18:32:42.219437Z","end":"2026-05-11T18:32:45.666334Z","steps":["trace[347863320] 'agreement among raft nodes before linearized reading' (duration: 3.446683211s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.666401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:42.219419Z","time spent":"3.44697301s","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T18:32:45.666554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.138754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:32:45.666631Z","caller":"traceutil/trace.go:171","msg":"trace[871560800] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:16367; }","duration":"352.239926ms","start":"2026-05-11T18:32:45.31438Z","end":"2026-05-11T18:32:45.66662Z","steps":["trace[871560800] 'agreement among raft nodes before linearized reading' (duration: 352.137643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.666641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.140952266s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T18:32:45.666661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:45.314369Z","time spent":"352.284198ms","remote":"127.0.0.1:57900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"info","ts":"2026-05-11T18:32:45.666687Z","caller":"traceutil/trace.go:171","msg":"trace[1466376731] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:16367; }","duration":"2.141050889s","start":"2026-05-11T18:32:43.525623Z","end":"2026-05-11T18:32:45.666674Z","steps":["trace[1466376731] 'agreement among raft nodes before linearized reading' (duration: 2.140927745s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.666722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:43.525604Z","time spent":"2.14110833s","remote":"127.0.0.1:58018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true "} {"level":"warn","ts":"2026-05-11T18:32:45.666933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.332250313s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-6ftx9\" ","response":"range_response_count:1 size:20743"} {"level":"warn","ts":"2026-05-11T18:32:45.666937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.566012257s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:32:45.666972Z","caller":"traceutil/trace.go:171","msg":"trace[1507836959] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-6ftx9; range_end:; response_count:1; response_revision:16367; }","duration":"2.332321885s","start":"2026-05-11T18:32:43.334641Z","end":"2026-05-11T18:32:45.666962Z","steps":["trace[1507836959] 'agreement among raft nodes before linearized reading' (duration: 2.332203751s)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:32:45.667005Z","caller":"traceutil/trace.go:171","msg":"trace[679758041] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16367; }","duration":"1.566084129s","start":"2026-05-11T18:32:44.10089Z","end":"2026-05-11T18:32:45.666974Z","steps":["trace[679758041] 'agreement among raft nodes before linearized reading' (duration: 1.566002707s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.667031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:43.33462Z","time spent":"2.332381266s","remote":"127.0.0.1:51558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20765,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-6ftx9\" "} {"level":"warn","ts":"2026-05-11T18:32:45.667064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.566574812s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-05-11T18:32:45.667096Z","caller":"traceutil/trace.go:171","msg":"trace[1435537597] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16367; }","duration":"1.566644234s","start":"2026-05-11T18:32:44.100444Z","end":"2026-05-11T18:32:45.667089Z","steps":["trace[1435537597] 'agreement among raft nodes before linearized reading' (duration: 1.566524741s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.667118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:44.100426Z","time spent":"1.566686826s","remote":"127.0.0.1:51546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-05-11T18:32:45.667137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.425208728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:32:45.667181Z","caller":"traceutil/trace.go:171","msg":"trace[1431646930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16367; }","duration":"1.42528037s","start":"2026-05-11T18:32:44.241888Z","end":"2026-05-11T18:32:45.667168Z","steps":["trace[1431646930] 'agreement among raft nodes before linearized reading' (duration: 1.425220789s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.667229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:44.241871Z","time spent":"1.425349432s","remote":"127.0.0.1:51390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T18:32:45.667353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.51031266s","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-11T18:32:45.66737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99055894s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T18:32:45.667444Z","caller":"traceutil/trace.go:171","msg":"trace[593504834] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:16367; }","duration":"1.990663833s","start":"2026-05-11T18:32:43.676772Z","end":"2026-05-11T18:32:45.667436Z","steps":["trace[593504834] 'agreement among raft nodes before linearized reading' (duration: 1.990474777s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.667467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:43.676755Z","time spent":"1.990705854s","remote":"127.0.0.1:34074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":110,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-05-11T18:32:45.667561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.809050776s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-05-11T18:32:45.667054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:44.100881Z","time spent":"1.566156441s","remote":"127.0.0.1:51606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"info","ts":"2026-05-11T18:32:45.667604Z","caller":"traceutil/trace.go:171","msg":"trace[843017917] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16367; }","duration":"1.809125398s","start":"2026-05-11T18:32:43.85847Z","end":"2026-05-11T18:32:45.667596Z","steps":["trace[843017917] 'agreement among raft nodes before linearized reading' (duration: 1.808757788s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.667641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:43.858456Z","time spent":"1.8091791s","remote":"127.0.0.1:51546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-05-11T18:32:45.66739Z","caller":"traceutil/trace.go:171","msg":"trace[553335955] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:16367; }","duration":"2.510392212s","start":"2026-05-11T18:32:43.156989Z","end":"2026-05-11T18:32:45.667381Z","steps":["trace[553335955] 'agreement among raft nodes before linearized reading' (duration: 2.510267709s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.667772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.740695358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"warn","ts":"2026-05-11T18:32:45.667777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:43.156962Z","time spent":"2.510804995s","remote":"127.0.0.1:51686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":125,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-05-11T18:32:45.66784Z","caller":"traceutil/trace.go:171","msg":"trace[1054419243] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16367; }","duration":"2.740730659s","start":"2026-05-11T18:32:42.927059Z","end":"2026-05-11T18:32:45.667789Z","steps":["trace[1054419243] 'agreement among raft nodes before linearized reading' (duration: 2.740659847s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.667817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.734714284s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:416786"} {"level":"warn","ts":"2026-05-11T18:32:45.667867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:42.92705Z","time spent":"2.740811001s","remote":"127.0.0.1:51546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":704,"request content":"key:\"/registry/services/endpoints/openstack/nova-metadata\" "} {"level":"warn","ts":"2026-05-11T18:32:45.668011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.741220762s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:32:45.6681Z","caller":"traceutil/trace.go:171","msg":"trace[484847362] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16367; }","duration":"2.741368816s","start":"2026-05-11T18:32:42.926712Z","end":"2026-05-11T18:32:45.66808Z","steps":["trace[484847362] 'agreement among raft nodes before linearized reading' (duration: 2.741221582s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.668114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.126000526s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-05-11T18:32:45.668107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.560816943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:32:45.668144Z","caller":"traceutil/trace.go:171","msg":"trace[1468093335] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16367; }","duration":"3.126055058s","start":"2026-05-11T18:32:42.542077Z","end":"2026-05-11T18:32:45.668132Z","steps":["trace[1468093335] 'agreement among raft nodes before linearized reading' (duration: 3.125883863s)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:32:45.668152Z","caller":"traceutil/trace.go:171","msg":"trace[991545029] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16367; }","duration":"1.560875234s","start":"2026-05-11T18:32:44.107266Z","end":"2026-05-11T18:32:45.668142Z","steps":["trace[991545029] 'agreement among raft nodes before linearized reading' (duration: 1.560808722s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.668147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:42.926693Z","time spent":"2.741440949s","remote":"127.0.0.1:51546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":27,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-11T18:32:45.668164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:42.542055Z","time spent":"3.1261048s","remote":"127.0.0.1:51546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-05-11T18:32:45.668173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:44.107259Z","time spent":"1.560910256s","remote":"127.0.0.1:51606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"warn","ts":"2026-05-11T18:32:45.667236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.371798065s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6pfhp\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-11T18:32:45.668227Z","caller":"traceutil/trace.go:171","msg":"trace[379695225] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6pfhp; range_end:; response_count:1; response_revision:16367; }","duration":"2.372800754s","start":"2026-05-11T18:32:43.295403Z","end":"2026-05-11T18:32:45.668203Z","steps":["trace[379695225] 'agreement among raft nodes before linearized reading' (duration: 2.371778545s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.66832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.257626299s","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":"warn","ts":"2026-05-11T18:32:45.668051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.440115869s","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-11T18:32:45.668316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:43.29539Z","time spent":"2.372896386s","remote":"127.0.0.1:51558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6542,"request content":"key:\"/registry/pods/openstack/keepalived-6pfhp\" "} {"level":"info","ts":"2026-05-11T18:32:45.668353Z","caller":"traceutil/trace.go:171","msg":"trace[582637940] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:16367; }","duration":"3.25766058s","start":"2026-05-11T18:32:42.410682Z","end":"2026-05-11T18:32:45.668343Z","steps":["trace[582637940] 'agreement among raft nodes before linearized reading' (duration: 3.257595028s)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:32:45.668357Z","caller":"traceutil/trace.go:171","msg":"trace[1277507353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16367; }","duration":"3.440444908s","start":"2026-05-11T18:32:42.227905Z","end":"2026-05-11T18:32:45.66835Z","steps":["trace[1277507353] 'agreement among raft nodes before linearized reading' (duration: 3.439937114s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.668372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:42.410604Z","time spent":"3.257762602s","remote":"127.0.0.1:33964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-05-11T18:32:45.668381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:42.227893Z","time spent":"3.44048071s","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-11T18:32:45.66792Z","caller":"traceutil/trace.go:171","msg":"trace[1077103386] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:16367; }","duration":"1.734845877s","start":"2026-05-11T18:32:43.933053Z","end":"2026-05-11T18:32:45.667899Z","steps":["trace[1077103386] 'agreement among raft nodes before linearized reading' (duration: 1.733937481s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.668732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.741729916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-05-11T18:32:45.668754Z","caller":"traceutil/trace.go:171","msg":"trace[898985262] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16367; }","duration":"2.741766218s","start":"2026-05-11T18:32:42.92698Z","end":"2026-05-11T18:32:45.668747Z","steps":["trace[898985262] 'agreement among raft nodes before linearized reading' (duration: 2.74075238s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.668726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:43.933034Z","time spent":"1.735681351s","remote":"127.0.0.1:44658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":416808,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-05-11T18:32:45.668772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:42.926972Z","time spent":"2.741795898s","remote":"127.0.0.1:51546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-05-11T18:32:45.668692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593086882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-hn6db\" ","response":"range_response_count:1 size:22089"} {"level":"info","ts":"2026-05-11T18:32:45.668822Z","caller":"traceutil/trace.go:171","msg":"trace[994928686] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-hn6db; range_end:; response_count:1; response_revision:16367; }","duration":"1.593254077s","start":"2026-05-11T18:32:44.075558Z","end":"2026-05-11T18:32:45.668812Z","steps":["trace[994928686] 'agreement among raft nodes before linearized reading' (duration: 1.592570708s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.668842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:44.075541Z","time spent":"1.593295848s","remote":"127.0.0.1:51558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22111,"request content":"key:\"/registry/pods/openstack/nova-compute-default-hn6db\" "} {"level":"warn","ts":"2026-05-11T18:32:45.668841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.54782319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-hn6db\" ","response":"range_response_count:1 size:22089"} {"level":"warn","ts":"2026-05-11T18:32:45.668894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.443290838s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-11T18:32:45.668901Z","caller":"traceutil/trace.go:171","msg":"trace[1846055209] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-hn6db; range_end:; response_count:1; response_revision:16367; }","duration":"1.547937424s","start":"2026-05-11T18:32:44.120947Z","end":"2026-05-11T18:32:45.668885Z","steps":["trace[1846055209] 'agreement among raft nodes before linearized reading' (duration: 1.546541414s)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:32:45.668938Z","caller":"traceutil/trace.go:171","msg":"trace[468478232] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16367; }","duration":"3.44336125s","start":"2026-05-11T18:32:42.225569Z","end":"2026-05-11T18:32:45.668931Z","steps":["trace[468478232] 'agreement among raft nodes before linearized reading' (duration: 3.442374413s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.668959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:42.225557Z","time spent":"3.44339643s","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T18:32:45.668953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:44.120935Z","time spent":"1.548007035s","remote":"127.0.0.1:51558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22111,"request content":"key:\"/registry/pods/openstack/nova-compute-default-hn6db\" "} {"level":"warn","ts":"2026-05-11T18:32:45.668963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.676816905s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-h9nsq\" ","response":"range_response_count:1 size:9790"} {"level":"warn","ts":"2026-05-11T18:32:45.66894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.431760629s","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-11T18:32:45.669066Z","caller":"traceutil/trace.go:171","msg":"trace[788382560] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16367; }","duration":"2.431943124s","start":"2026-05-11T18:32:43.237085Z","end":"2026-05-11T18:32:45.669028Z","steps":["trace[788382560] 'agreement among raft nodes before linearized reading' (duration: 2.430117572s)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:32:45.668997Z","caller":"traceutil/trace.go:171","msg":"trace[57407339] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-h9nsq; range_end:; response_count:1; response_revision:16367; }","duration":"2.676864576s","start":"2026-05-11T18:32:42.992124Z","end":"2026-05-11T18:32:45.668988Z","steps":["trace[57407339] 'agreement among raft nodes before linearized reading' (duration: 2.675283632s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.669081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.560344299s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:1 size:12491"} {"level":"warn","ts":"2026-05-11T18:32:45.669117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:42.992101Z","time spent":"2.677008651s","remote":"127.0.0.1:51558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9812,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-h9nsq\" "} {"level":"info","ts":"2026-05-11T18:32:45.669129Z","caller":"traceutil/trace.go:171","msg":"trace[186517505] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:1; response_revision:16367; }","duration":"1.560415311s","start":"2026-05-11T18:32:44.108701Z","end":"2026-05-11T18:32:45.669116Z","steps":["trace[186517505] 'agreement among raft nodes before linearized reading' (duration: 1.559353882s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.669132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:43.237064Z","time spent":"2.432056347s","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T18:32:45.669148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:44.108689Z","time spent":"1.560453742s","remote":"127.0.0.1:51606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":12513,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-05-11T18:32:45.669162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.817759317s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-api-679f84dfb-smfxl.18ae96180a19c4d7\" ","response":"range_response_count:1 size:1174"} {"level":"info","ts":"2026-05-11T18:32:45.669192Z","caller":"traceutil/trace.go:171","msg":"trace[1084645875] range","detail":"{range_begin:/registry/events/openstack/cinder-api-679f84dfb-smfxl.18ae96180a19c4d7; range_end:; response_count:1; response_revision:16367; }","duration":"2.817830829s","start":"2026-05-11T18:32:42.851353Z","end":"2026-05-11T18:32:45.669184Z","steps":["trace[1084645875] 'agreement among raft nodes before linearized reading' (duration: 2.816718578s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.66921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:42.851332Z","time spent":"2.81787334s","remote":"127.0.0.1:51484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":1196,"request content":"key:\"/registry/events/openstack/cinder-api-679f84dfb-smfxl.18ae96180a19c4d7\" "} {"level":"warn","ts":"2026-05-11T18:32:45.669261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.084879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-11T18:32:45.669287Z","caller":"traceutil/trace.go:171","msg":"trace[1501362348] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16367; }","duration":"382.141499ms","start":"2026-05-11T18:32:45.287139Z","end":"2026-05-11T18:32:45.66928Z","steps":["trace[1501362348] 'agreement among raft nodes before linearized reading' (duration: 379.942088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.669303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:45.287124Z","time spent":"382.175451ms","remote":"127.0.0.1:51558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-11T18:32:45.669836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.563496346s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:1 size:12491"} {"level":"info","ts":"2026-05-11T18:32:45.669895Z","caller":"traceutil/trace.go:171","msg":"trace[1603344525] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:1; response_revision:16367; }","duration":"1.563572859s","start":"2026-05-11T18:32:44.106305Z","end":"2026-05-11T18:32:45.669878Z","steps":["trace[1603344525] 'agreement among raft nodes before linearized reading' (duration: 1.561702627s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.669925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:44.106298Z","time spent":"1.56361912s","remote":"127.0.0.1:51606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":12513,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-05-11T18:32:45.670371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.570174583s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-05-11T18:32:45.670429Z","caller":"traceutil/trace.go:171","msg":"trace[1012631868] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16367; }","duration":"1.570265315s","start":"2026-05-11T18:32:44.100148Z","end":"2026-05-11T18:32:45.670413Z","steps":["trace[1012631868] 'agreement among raft nodes before linearized reading' (duration: 1.567802547s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:32:45.670462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:32:44.100133Z","time spent":"1.570321387s","remote":"127.0.0.1:51546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-05-11T18:34:36.882018Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14616} {"level":"info","ts":"2026-05-11T18:34:36.960025Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14616,"took":"75.624199ms","hash":4027457673,"current-db-size-bytes":56279040,"current-db-size":"56 MB","current-db-size-in-use-bytes":33480704,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-11T18:34:36.960114Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4027457673,"revision":14616,"compact-revision":12621} {"level":"warn","ts":"2026-05-11T18:37:11.48401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.705855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:940908"} {"level":"info","ts":"2026-05-11T18:37:11.484121Z","caller":"traceutil/trace.go:171","msg":"trace[238493815] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:18413; }","duration":"126.88963ms","start":"2026-05-11T18:37:11.357212Z","end":"2026-05-11T18:37:11.484102Z","steps":["trace[238493815] 'range keys from in-memory index tree' (duration: 125.825941ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:37:15.971993Z","caller":"traceutil/trace.go:171","msg":"trace[394259283] transaction","detail":"{read_only:false; response_revision:18509; number_of_response:1; }","duration":"297.217973ms","start":"2026-05-11T18:37:15.674733Z","end":"2026-05-11T18:37:15.971951Z","steps":["trace[394259283] 'process raft request' (duration: 295.804393ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:38:40.069898Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2451868e402db19e","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T18:38:40.078439Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-11T18:38:40.078537Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-11T18:39:36.888337Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17360} {"level":"info","ts":"2026-05-11T18:39:36.983775Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17360,"took":"91.935924ms","hash":187958246,"current-db-size-bytes":56279040,"current-db-size":"56 MB","current-db-size-in-use-bytes":34512896,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-11T18:39:36.983923Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":187958246,"revision":17360,"compact-revision":14616} {"level":"info","ts":"2026-05-11T18:43:11.356072Z","caller":"traceutil/trace.go:171","msg":"trace[1400569111] linearizableReadLoop","detail":"{readStateIndex:21907; appliedIndex:21906; }","duration":"174.065067ms","start":"2026-05-11T18:43:11.181983Z","end":"2026-05-11T18:43:11.356048Z","steps":["trace[1400569111] 'read index received' (duration: 73.632µs)","trace[1400569111] 'applied index is now lower than readState.Index' (duration: 173.990155ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:43:11.356159Z","caller":"traceutil/trace.go:171","msg":"trace[1601129580] transaction","detail":"{read_only:false; response_revision:21181; number_of_response:1; }","duration":"174.15015ms","start":"2026-05-11T18:43:11.181961Z","end":"2026-05-11T18:43:11.356111Z","steps":["trace[1601129580] 'process raft request' (duration: 173.934704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:43:11.356286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.275533ms","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-11T18:43:11.356346Z","caller":"traceutil/trace.go:171","msg":"trace[510174028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21181; }","duration":"174.382516ms","start":"2026-05-11T18:43:11.181948Z","end":"2026-05-11T18:43:11.356331Z","steps":["trace[510174028] 'agreement among raft nodes before linearized reading' (duration: 174.17395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:43:11.585383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.782114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T18:43:11.585586Z","caller":"traceutil/trace.go:171","msg":"trace[118986952] transaction","detail":"{read_only:false; response_revision:21183; number_of_response:1; }","duration":"223.742708ms","start":"2026-05-11T18:43:11.361822Z","end":"2026-05-11T18:43:11.585564Z","steps":["trace[118986952] 'process raft request' (duration: 223.677726ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:43:11.585613Z","caller":"traceutil/trace.go:171","msg":"trace[1735946373] linearizableReadLoop","detail":"{readStateIndex:21908; appliedIndex:21907; }","duration":"229.442937ms","start":"2026-05-11T18:43:11.356145Z","end":"2026-05-11T18:43:11.585588Z","steps":["trace[1735946373] 'read index received' (duration: 84.435336ms)","trace[1735946373] 'applied index is now lower than readState.Index' (duration: 145.00483ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:43:11.585815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.920139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-11T18:43:11.585845Z","caller":"traceutil/trace.go:171","msg":"trace[2045109229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21183; }","duration":"402.005841ms","start":"2026-05-11T18:43:11.183832Z","end":"2026-05-11T18:43:11.585838Z","steps":["trace[2045109229] 'agreement among raft nodes before linearized reading' (duration: 401.832407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:43:11.585867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:43:11.183815Z","time spent":"402.048304ms","remote":"127.0.0.1:51628","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-11T18:43:11.585957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.930696ms","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":"info","ts":"2026-05-11T18:43:11.58601Z","caller":"traceutil/trace.go:171","msg":"trace[169316283] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:21183; }","duration":"245.03439ms","start":"2026-05-11T18:43:11.340964Z","end":"2026-05-11T18:43:11.585998Z","steps":["trace[169316283] 'agreement among raft nodes before linearized reading' (duration: 244.942177ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:43:11.586022Z","caller":"traceutil/trace.go:171","msg":"trace[252805843] transaction","detail":"{read_only:false; response_revision:21182; number_of_response:1; }","duration":"401.562429ms","start":"2026-05-11T18:43:11.18445Z","end":"2026-05-11T18:43:11.586012Z","steps":["trace[252805843] 'process raft request' (duration: 256.044545ms)","trace[252805843] 'compare' (duration: 144.562438ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:43:11.586093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:43:11.184438Z","time spent":"401.606881ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:43:11.58598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.405596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-11T18:43:11.586158Z","caller":"traceutil/trace.go:171","msg":"trace[1013494504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21183; }","duration":"396.594351ms","start":"2026-05-11T18:43:11.189557Z","end":"2026-05-11T18:43:11.586152Z","steps":["trace[1013494504] 'agreement among raft nodes before linearized reading' (duration: 396.384905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:43:11.586173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:43:11.18955Z","time spent":"396.619532ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-11T18:43:11.744406Z","caller":"traceutil/trace.go:171","msg":"trace[11052975] transaction","detail":"{read_only:false; response_revision:21185; number_of_response:1; }","duration":"152.339705ms","start":"2026-05-11T18:43:11.592037Z","end":"2026-05-11T18:43:11.744377Z","steps":["trace[11052975] 'process raft request' (duration: 149.763053ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:43:12.440967Z","caller":"traceutil/trace.go:171","msg":"trace[1344187100] linearizableReadLoop","detail":"{readStateIndex:21915; appliedIndex:21912; }","duration":"199.680569ms","start":"2026-05-11T18:43:12.241263Z","end":"2026-05-11T18:43:12.440943Z","steps":["trace[1344187100] 'read index received' (duration: 199.481144ms)","trace[1344187100] 'applied index is now lower than readState.Index' (duration: 197.975µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:43:12.441118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.837204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:43:12.441153Z","caller":"traceutil/trace.go:171","msg":"trace[1839023412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21189; }","duration":"199.917086ms","start":"2026-05-11T18:43:12.241223Z","end":"2026-05-11T18:43:12.44114Z","steps":["trace[1839023412] 'agreement among raft nodes before linearized reading' (duration: 199.821833ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:43:12.441359Z","caller":"traceutil/trace.go:171","msg":"trace[978381054] transaction","detail":"{read_only:false; response_revision:21187; number_of_response:1; }","duration":"256.657892ms","start":"2026-05-11T18:43:12.184685Z","end":"2026-05-11T18:43:12.441343Z","steps":["trace[978381054] 'process raft request' (duration: 256.055136ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:43:12.44149Z","caller":"traceutil/trace.go:171","msg":"trace[578698343] transaction","detail":"{read_only:false; response_revision:21189; number_of_response:1; }","duration":"232.986395ms","start":"2026-05-11T18:43:12.20848Z","end":"2026-05-11T18:43:12.441467Z","steps":["trace[578698343] 'process raft request' (duration: 232.418409ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:43:12.441457Z","caller":"traceutil/trace.go:171","msg":"trace[1139178559] transaction","detail":"{read_only:false; response_revision:21188; number_of_response:1; }","duration":"255.750547ms","start":"2026-05-11T18:43:12.185657Z","end":"2026-05-11T18:43:12.441408Z","steps":["trace[1139178559] 'process raft request' (duration: 255.195692ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:44:36.895255Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19709} {"level":"info","ts":"2026-05-11T18:44:37.007852Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19709,"took":"108.149766ms","hash":2805033079,"current-db-size-bytes":56279040,"current-db-size":"56 MB","current-db-size-in-use-bytes":29609984,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-11T18:44:37.00793Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2805033079,"revision":19709,"compact-revision":17360} {"level":"warn","ts":"2026-05-11T18:47:09.043444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.864343ms","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-11T18:47:09.043512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.894918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T18:47:09.043568Z","caller":"traceutil/trace.go:171","msg":"trace[1519516581] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23631; }","duration":"101.055938ms","start":"2026-05-11T18:47:08.942491Z","end":"2026-05-11T18:47:09.043546Z","steps":["trace[1519516581] 'range keys from in-memory index tree' (duration: 100.598205ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:47:09.043892Z","caller":"traceutil/trace.go:171","msg":"trace[525043772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23631; }","duration":"103.063003ms","start":"2026-05-11T18:47:08.940531Z","end":"2026-05-11T18:47:09.043594Z","steps":["trace[525043772] 'range keys from in-memory index tree' (duration: 102.682603ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:47:09.154122Z","caller":"traceutil/trace.go:171","msg":"trace[27906206] transaction","detail":"{read_only:false; response_revision:23632; number_of_response:1; }","duration":"105.917133ms","start":"2026-05-11T18:47:09.048174Z","end":"2026-05-11T18:47:09.154091Z","steps":["trace[27906206] 'process raft request' (duration: 105.675147ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:47:09.453329Z","caller":"traceutil/trace.go:171","msg":"trace[1116442612] transaction","detail":"{read_only:false; response_revision:23633; number_of_response:1; }","duration":"405.079707ms","start":"2026-05-11T18:47:09.048227Z","end":"2026-05-11T18:47:09.453306Z","steps":["trace[1116442612] 'process raft request' (duration: 404.787059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:47:09.453486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:47:09.048179Z","time spent":"405.226281ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:47:09.453327Z","caller":"traceutil/trace.go:171","msg":"trace[2055029147] transaction","detail":"{read_only:false; response_revision:23634; number_of_response:1; }","duration":"405.024875ms","start":"2026-05-11T18:47:09.048274Z","end":"2026-05-11T18:47:09.453299Z","steps":["trace[2055029147] 'process raft request' (duration: 404.926272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:47:09.453635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:47:09.048234Z","time spent":"405.375895ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:47:09.453727Z","caller":"traceutil/trace.go:171","msg":"trace[411487604] transaction","detail":"{read_only:false; response_revision:23635; number_of_response:1; }","duration":"404.780999ms","start":"2026-05-11T18:47:09.048935Z","end":"2026-05-11T18:47:09.453716Z","steps":["trace[411487604] 'process raft request' (duration: 404.323806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:47:09.453777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:47:09.048926Z","time spent":"404.8286ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:47:09.459728Z","caller":"traceutil/trace.go:171","msg":"trace[1049709743] transaction","detail":"{read_only:false; response_revision:23636; number_of_response:1; }","duration":"305.670014ms","start":"2026-05-11T18:47:09.154041Z","end":"2026-05-11T18:47:09.459711Z","steps":["trace[1049709743] 'process raft request' (duration: 305.552821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:47:09.459805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:47:09.154001Z","time spent":"305.768647ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:47:09.460272Z","caller":"traceutil/trace.go:171","msg":"trace[1697808525] transaction","detail":"{read_only:false; response_revision:23637; number_of_response:1; }","duration":"230.410113ms","start":"2026-05-11T18:47:09.229844Z","end":"2026-05-11T18:47:09.460254Z","steps":["trace[1697808525] 'process raft request' (duration: 229.826447ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:47:09.46049Z","caller":"traceutil/trace.go:171","msg":"trace[1726891042] transaction","detail":"{read_only:false; response_revision:23638; number_of_response:1; }","duration":"191.922113ms","start":"2026-05-11T18:47:09.268529Z","end":"2026-05-11T18:47:09.460451Z","steps":["trace[1726891042] 'process raft request' (duration: 191.638926ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:47:09.460598Z","caller":"traceutil/trace.go:171","msg":"trace[1598277739] transaction","detail":"{read_only:false; response_revision:23639; number_of_response:1; }","duration":"187.549052ms","start":"2026-05-11T18:47:09.273034Z","end":"2026-05-11T18:47:09.460583Z","steps":["trace[1598277739] 'process raft request' (duration: 187.358277ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:47:09.460845Z","caller":"traceutil/trace.go:171","msg":"trace[1147512479] linearizableReadLoop","detail":"{readStateIndex:24439; appliedIndex:24434; }","duration":"218.07085ms","start":"2026-05-11T18:47:09.242765Z","end":"2026-05-11T18:47:09.460836Z","steps":["trace[1147512479] 'read index received' (duration: 210.350056ms)","trace[1147512479] 'applied index is now lower than readState.Index' (duration: 7.720304ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:47:09.461018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.260725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:47:09.461046Z","caller":"traceutil/trace.go:171","msg":"trace[889837843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23642; }","duration":"218.322876ms","start":"2026-05-11T18:47:09.242717Z","end":"2026-05-11T18:47:09.46104Z","steps":["trace[889837843] 'agreement among raft nodes before linearized reading' (duration: 218.241274ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:48:37.92097Z","caller":"traceutil/trace.go:171","msg":"trace[491090795] transaction","detail":"{read_only:false; response_revision:24561; number_of_response:1; }","duration":"128.882441ms","start":"2026-05-11T18:48:37.792042Z","end":"2026-05-11T18:48:37.920924Z","steps":["trace[491090795] 'process raft request' (duration: 120.22279ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:48:37.921324Z","caller":"traceutil/trace.go:171","msg":"trace[546589763] transaction","detail":"{read_only:false; response_revision:24563; number_of_response:1; }","duration":"103.345832ms","start":"2026-05-11T18:48:37.817965Z","end":"2026-05-11T18:48:37.92131Z","steps":["trace[546589763] 'process raft request' (duration: 102.936501ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:48:37.921494Z","caller":"traceutil/trace.go:171","msg":"trace[1223862827] transaction","detail":"{read_only:false; response_revision:24564; number_of_response:1; }","duration":"103.155557ms","start":"2026-05-11T18:48:37.818297Z","end":"2026-05-11T18:48:37.921453Z","steps":["trace[1223862827] 'process raft request' (duration: 102.669604ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:48:37.92171Z","caller":"traceutil/trace.go:171","msg":"trace[501833972] transaction","detail":"{read_only:false; response_revision:24562; number_of_response:1; }","duration":"109.654327ms","start":"2026-05-11T18:48:37.81201Z","end":"2026-05-11T18:48:37.921665Z","steps":["trace[501833972] 'process raft request' (duration: 108.786232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:48:37.921845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.947308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:48:37.921897Z","caller":"traceutil/trace.go:171","msg":"trace[471446427] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:24565; }","duration":"127.01803ms","start":"2026-05-11T18:48:37.794863Z","end":"2026-05-11T18:48:37.921881Z","steps":["trace[471446427] 'agreement among raft nodes before linearized reading' (duration: 126.912067ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:48:37.921092Z","caller":"traceutil/trace.go:171","msg":"trace[1003987393] linearizableReadLoop","detail":"{readStateIndex:25384; appliedIndex:25382; }","duration":"126.193766ms","start":"2026-05-11T18:48:37.794875Z","end":"2026-05-11T18:48:37.921068Z","steps":["trace[1003987393] 'read index received' (duration: 61.319083ms)","trace[1003987393] 'applied index is now lower than readState.Index' (duration: 64.873313ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:49:36.902131Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21943} {"level":"info","ts":"2026-05-11T18:49:37.013559Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21943,"took":"106.52151ms","hash":2735814356,"current-db-size-bytes":56279040,"current-db-size":"56 MB","current-db-size-in-use-bytes":42835968,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-05-11T18:49:37.013621Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2735814356,"revision":21943,"compact-revision":19709} {"level":"info","ts":"2026-05-11T18:50:38.69759Z","caller":"traceutil/trace.go:171","msg":"trace[1196501121] linearizableReadLoop","detail":"{readStateIndex:26587; appliedIndex:26586; }","duration":"387.475658ms","start":"2026-05-11T18:50:38.310083Z","end":"2026-05-11T18:50:38.697558Z","steps":["trace[1196501121] 'read index received' (duration: 387.237111ms)","trace[1196501121] 'applied index is now lower than readState.Index' (duration: 236.767µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:38.697736Z","caller":"traceutil/trace.go:171","msg":"trace[2055708998] transaction","detail":"{read_only:false; response_revision:25731; number_of_response:1; }","duration":"401.602831ms","start":"2026-05-11T18:50:38.296121Z","end":"2026-05-11T18:50:38.697724Z","steps":["trace[2055708998] 'process raft request' (duration: 401.22452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:38.69786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:38.296094Z","time spent":"401.676363ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:38.698007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.8929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-05-11T18:50:38.698097Z","caller":"traceutil/trace.go:171","msg":"trace[1043581307] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:25731; }","duration":"388.047934ms","start":"2026-05-11T18:50:38.310028Z","end":"2026-05-11T18:50:38.698076Z","steps":["trace[1043581307] 'agreement among raft nodes before linearized reading' (duration: 387.808888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:38.69813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:38.310008Z","time spent":"388.115985ms","remote":"127.0.0.1:51474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-05-11T18:50:39.111161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.026589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T18:50:39.111664Z","caller":"traceutil/trace.go:171","msg":"trace[1205388095] transaction","detail":"{read_only:false; response_revision:25733; number_of_response:1; }","duration":"690.803696ms","start":"2026-05-11T18:50:38.420821Z","end":"2026-05-11T18:50:39.111625Z","steps":["trace[1205388095] 'process raft request' (duration: 690.445875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:39.111809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:38.420785Z","time spent":"690.94412ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:39.111785Z","caller":"traceutil/trace.go:171","msg":"trace[1563127981] transaction","detail":"{read_only:false; response_revision:25737; number_of_response:1; }","duration":"679.348519ms","start":"2026-05-11T18:50:38.432406Z","end":"2026-05-11T18:50:39.111754Z","steps":["trace[1563127981] 'process raft request' (duration: 679.157072ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:39.111929Z","caller":"traceutil/trace.go:171","msg":"trace[1644617601] transaction","detail":"{read_only:false; response_revision:25732; number_of_response:1; }","duration":"784.637123ms","start":"2026-05-11T18:50:38.327282Z","end":"2026-05-11T18:50:39.111919Z","steps":["trace[1644617601] 'process raft request' (duration: 656.928244ms)","trace[1644617601] 'compare' (duration: 126.33189ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:50:39.111988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:38.327259Z","time spent":"784.704385ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:39.111974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:38.432394Z","time spent":"679.476372ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:39.112123Z","caller":"traceutil/trace.go:171","msg":"trace[1509035908] linearizableReadLoop","detail":"{readStateIndex:26593; appliedIndex:26587; }","duration":"414.432746ms","start":"2026-05-11T18:50:38.697679Z","end":"2026-05-11T18:50:39.112112Z","steps":["trace[1509035908] 'read index received' (duration: 286.557643ms)","trace[1509035908] 'applied index is now lower than readState.Index' (duration: 127.872753ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:50:39.112312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.032863ms","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-11T18:50:39.112339Z","caller":"traceutil/trace.go:171","msg":"trace[790468067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25738; }","duration":"460.106155ms","start":"2026-05-11T18:50:38.652225Z","end":"2026-05-11T18:50:39.112331Z","steps":["trace[790468067] 'agreement among raft nodes before linearized reading' (duration: 459.962061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:39.112361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:38.652173Z","time spent":"460.182598ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-11T18:50:39.112372Z","caller":"traceutil/trace.go:171","msg":"trace[1480134681] transaction","detail":"{read_only:false; response_revision:25734; number_of_response:1; }","duration":"682.605889ms","start":"2026-05-11T18:50:38.42974Z","end":"2026-05-11T18:50:39.112345Z","steps":["trace[1480134681] 'process raft request' (duration: 681.656232ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:39.1124Z","caller":"traceutil/trace.go:171","msg":"trace[571597011] transaction","detail":"{read_only:false; response_revision:25735; number_of_response:1; }","duration":"681.226989ms","start":"2026-05-11T18:50:38.431141Z","end":"2026-05-11T18:50:39.112368Z","steps":["trace[571597011] 'process raft request' (duration: 680.329144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:39.112439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.666474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-05-11T18:50:39.112435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.743001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T18:50:39.112488Z","caller":"traceutil/trace.go:171","msg":"trace[1377687672] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25738; }","duration":"463.783438ms","start":"2026-05-11T18:50:38.648687Z","end":"2026-05-11T18:50:39.112471Z","steps":["trace[1377687672] 'agreement among raft nodes before linearized reading' (duration: 463.595833ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:39.112496Z","caller":"traceutil/trace.go:171","msg":"trace[864709219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25738; }","duration":"461.824604ms","start":"2026-05-11T18:50:38.650659Z","end":"2026-05-11T18:50:39.112484Z","steps":["trace[864709219] 'agreement among raft nodes before linearized reading' (duration: 461.68539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:39.112509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:38.429711Z","time spent":"682.725242ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:39.112518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:38.648659Z","time spent":"463.85218ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T18:50:39.112529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:38.650651Z","time spent":"461.871015ms","remote":"127.0.0.1:51628","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-11T18:50:39.112534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.675508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:127953"} {"level":"warn","ts":"2026-05-11T18:50:39.112559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:38.431104Z","time spent":"681.366813ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:39.1126Z","caller":"traceutil/trace.go:171","msg":"trace[1780487234] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:25738; }","duration":"787.798081ms","start":"2026-05-11T18:50:38.324784Z","end":"2026-05-11T18:50:39.112582Z","steps":["trace[1780487234] 'agreement among raft nodes before linearized reading' (duration: 787.524914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:39.112638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:38.324747Z","time spent":"787.883054ms","remote":"127.0.0.1:51466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":127975,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-05-11T18:50:39.111682Z","caller":"traceutil/trace.go:171","msg":"trace[2106145806] transaction","detail":"{read_only:false; response_revision:25738; number_of_response:1; }","duration":"293.890997ms","start":"2026-05-11T18:50:38.817771Z","end":"2026-05-11T18:50:39.111662Z","steps":["trace[2106145806] 'process raft request' (duration: 293.839595ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:39.112643Z","caller":"traceutil/trace.go:171","msg":"trace[22066936] transaction","detail":"{read_only:false; response_revision:25736; number_of_response:1; }","duration":"679.892133ms","start":"2026-05-11T18:50:38.431822Z","end":"2026-05-11T18:50:39.111715Z","steps":["trace[22066936] 'process raft request' (duration: 679.684977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:39.112752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:38.431807Z","time spent":"680.907761ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:39.131955Z","caller":"traceutil/trace.go:171","msg":"trace[87909509] transaction","detail":"{read_only:false; response_revision:25739; number_of_response:1; }","duration":"140.36976ms","start":"2026-05-11T18:50:38.99156Z","end":"2026-05-11T18:50:39.13193Z","steps":["trace[87909509] 'process raft request' (duration: 140.164644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:39.132616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.671926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11786"} {"level":"info","ts":"2026-05-11T18:50:39.132653Z","caller":"traceutil/trace.go:171","msg":"trace[1027347238] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25739; }","duration":"399.767179ms","start":"2026-05-11T18:50:38.732878Z","end":"2026-05-11T18:50:39.132645Z","steps":["trace[1027347238] 'agreement among raft nodes before linearized reading' (duration: 399.658716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:39.132673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:38.732859Z","time spent":"399.80837ms","remote":"127.0.0.1:51788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11808,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-05-11T18:50:39.133093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.94134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:89 size:116267"} {"level":"info","ts":"2026-05-11T18:50:39.133168Z","caller":"traceutil/trace.go:171","msg":"trace[984563838] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:89; response_revision:25739; }","duration":"433.039163ms","start":"2026-05-11T18:50:38.700114Z","end":"2026-05-11T18:50:39.133153Z","steps":["trace[984563838] 'agreement among raft nodes before linearized reading' (duration: 432.249151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:39.133208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:38.700096Z","time spent":"433.102094ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":89,"response size":116289,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"info","ts":"2026-05-11T18:50:39.498181Z","caller":"traceutil/trace.go:171","msg":"trace[1840478731] linearizableReadLoop","detail":"{readStateIndex:26598; appliedIndex:26595; }","duration":"366.264417ms","start":"2026-05-11T18:50:39.131891Z","end":"2026-05-11T18:50:39.498155Z","steps":["trace[1840478731] 'read index received' (duration: 365.922668ms)","trace[1840478731] 'applied index is now lower than readState.Index' (duration: 340.319µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:39.498232Z","caller":"traceutil/trace.go:171","msg":"trace[1934190456] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"380.859824ms","start":"2026-05-11T18:50:39.117349Z","end":"2026-05-11T18:50:39.498209Z","steps":["trace[1934190456] 'process raft request' (duration: 380.727179ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:39.498333Z","caller":"traceutil/trace.go:171","msg":"trace[2114277085] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"380.964217ms","start":"2026-05-11T18:50:39.117356Z","end":"2026-05-11T18:50:39.49832Z","steps":["trace[2114277085] 'process raft request' (duration: 380.75625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:39.498364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:39.117332Z","time spent":"380.950586ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:39.498187Z","caller":"traceutil/trace.go:171","msg":"trace[331164404] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"380.866103ms","start":"2026-05-11T18:50:39.117283Z","end":"2026-05-11T18:50:39.498149Z","steps":["trace[331164404] 'process raft request' (duration: 380.613136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:39.498484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:39.117334Z","time spent":"381.031778ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:39.498497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:39.117263Z","time spent":"381.182872ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:39.498796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.649689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-11T18:50:39.498827Z","caller":"traceutil/trace.go:171","msg":"trace[476592932] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25742; }","duration":"362.695509ms","start":"2026-05-11T18:50:39.136124Z","end":"2026-05-11T18:50:39.498819Z","steps":["trace[476592932] 'agreement among raft nodes before linearized reading' (duration: 362.564586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:39.498847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:39.136109Z","time spent":"362.73438ms","remote":"127.0.0.1:51558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-11T18:50:39.498973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.17286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:39.498998Z","caller":"traceutil/trace.go:171","msg":"trace[112302542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25742; }","duration":"256.228341ms","start":"2026-05-11T18:50:39.242761Z","end":"2026-05-11T18:50:39.498989Z","steps":["trace[112302542] 'agreement among raft nodes before linearized reading' (duration: 256.19095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:39.499137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.442045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11786"} {"level":"info","ts":"2026-05-11T18:50:39.499209Z","caller":"traceutil/trace.go:171","msg":"trace[566978783] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25742; }","duration":"356.726563ms","start":"2026-05-11T18:50:39.142466Z","end":"2026-05-11T18:50:39.499192Z","steps":["trace[566978783] 'agreement among raft nodes before linearized reading' (duration: 356.560548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:39.499249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.966564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:89 size:116267"} {"level":"info","ts":"2026-05-11T18:50:39.499287Z","caller":"traceutil/trace.go:171","msg":"trace[294409521] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:89; response_revision:25742; }","duration":"382.028675ms","start":"2026-05-11T18:50:39.117249Z","end":"2026-05-11T18:50:39.499278Z","steps":["trace[294409521] 'agreement among raft nodes before linearized reading' (duration: 381.382717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:39.499313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:39.117226Z","time spent":"382.079437ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":89,"response size":116289,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-05-11T18:50:39.499406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.541787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T18:50:39.499437Z","caller":"traceutil/trace.go:171","msg":"trace[907514464] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:25742; }","duration":"311.574378ms","start":"2026-05-11T18:50:39.187855Z","end":"2026-05-11T18:50:39.499429Z","steps":["trace[907514464] 'agreement among raft nodes before linearized reading' (duration: 311.462995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:39.499459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:39.187782Z","time spent":"311.670001ms","remote":"127.0.0.1:51700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":99,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-11T18:50:39.499148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.274542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-05-11T18:50:39.49971Z","caller":"traceutil/trace.go:171","msg":"trace[1251998254] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:25742; }","duration":"360.852888ms","start":"2026-05-11T18:50:39.138849Z","end":"2026-05-11T18:50:39.499702Z","steps":["trace[1251998254] 'agreement among raft nodes before linearized reading' (duration: 360.243461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:39.499732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:39.138838Z","time spent":"360.887799ms","remote":"127.0.0.1:51474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-05-11T18:50:39.499269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:39.142458Z","time spent":"356.800774ms","remote":"127.0.0.1:51788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11808,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-05-11T18:50:39.78632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.545238ms","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-11T18:50:39.786541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.077709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-05-11T18:50:39.78664Z","caller":"traceutil/trace.go:171","msg":"trace[1984202873] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25742; }","duration":"182.205293ms","start":"2026-05-11T18:50:39.604408Z","end":"2026-05-11T18:50:39.786614Z","steps":["trace[1984202873] 'range keys from in-memory index tree' (duration: 181.913395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:39.786642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.624745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-11T18:50:39.786721Z","caller":"traceutil/trace.go:171","msg":"trace[937871613] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25742; }","duration":"177.700907ms","start":"2026-05-11T18:50:39.609002Z","end":"2026-05-11T18:50:39.786703Z","steps":["trace[937871613] 'range keys from in-memory index tree' (duration: 177.43333ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:39.786403Z","caller":"traceutil/trace.go:171","msg":"trace[1298711630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25742; }","duration":"148.679572ms","start":"2026-05-11T18:50:39.637707Z","end":"2026-05-11T18:50:39.786387Z","steps":["trace[1298711630] 'range keys from in-memory index tree' (duration: 148.356702ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:39.798497Z","caller":"traceutil/trace.go:171","msg":"trace[1289013997] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"134.710373ms","start":"2026-05-11T18:50:39.663778Z","end":"2026-05-11T18:50:39.798488Z","steps":["trace[1289013997] 'process raft request' (duration: 134.316813ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:39.79835Z","caller":"traceutil/trace.go:171","msg":"trace[968574642] linearizableReadLoop","detail":"{readStateIndex:26599; appliedIndex:26598; }","duration":"112.808075ms","start":"2026-05-11T18:50:39.685531Z","end":"2026-05-11T18:50:39.798339Z","steps":["trace[968574642] 'read index received' (duration: 112.706092ms)","trace[968574642] 'applied index is now lower than readState.Index' (duration: 101.443µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:50:39.798842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.293388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:39.798902Z","caller":"traceutil/trace.go:171","msg":"trace[1519590275] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:25743; }","duration":"113.395811ms","start":"2026-05-11T18:50:39.685488Z","end":"2026-05-11T18:50:39.798884Z","steps":["trace[1519590275] 'agreement among raft nodes before linearized reading' (duration: 113.293988ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:39.909722Z","caller":"traceutil/trace.go:171","msg":"trace[2008280223] linearizableReadLoop","detail":"{readStateIndex:26601; appliedIndex:26599; }","duration":"110.937972ms","start":"2026-05-11T18:50:39.798764Z","end":"2026-05-11T18:50:39.909702Z","steps":["trace[2008280223] 'read index received' (duration: 110.452629ms)","trace[2008280223] 'applied index is now lower than readState.Index' (duration: 484.553µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:50:39.909918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.677914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:39.910021Z","caller":"traceutil/trace.go:171","msg":"trace[1982803912] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:25745; }","duration":"201.814198ms","start":"2026-05-11T18:50:39.708167Z","end":"2026-05-11T18:50:39.909982Z","steps":["trace[1982803912] 'agreement among raft nodes before linearized reading' (duration: 201.623622ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:39.910315Z","caller":"traceutil/trace.go:171","msg":"trace[1537283259] transaction","detail":"{read_only:false; response_revision:25745; number_of_response:1; }","duration":"116.065505ms","start":"2026-05-11T18:50:39.794236Z","end":"2026-05-11T18:50:39.910301Z","steps":["trace[1537283259] 'process raft request' (duration: 115.380866ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:39.910329Z","caller":"traceutil/trace.go:171","msg":"trace[203770326] transaction","detail":"{read_only:false; response_revision:25744; number_of_response:1; }","duration":"118.783551ms","start":"2026-05-11T18:50:39.791498Z","end":"2026-05-11T18:50:39.910281Z","steps":["trace[203770326] 'process raft request' (duration: 117.70459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:45.546756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.417138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18620"} {"level":"info","ts":"2026-05-11T18:50:45.546836Z","caller":"traceutil/trace.go:171","msg":"trace[1705136727] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25791; }","duration":"178.542751ms","start":"2026-05-11T18:50:45.368274Z","end":"2026-05-11T18:50:45.546817Z","steps":["trace[1705136727] 'range keys from in-memory index tree' (duration: 178.262573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:45.54724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.563913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:45.547284Z","caller":"traceutil/trace.go:171","msg":"trace[987147880] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:25791; }","duration":"295.637076ms","start":"2026-05-11T18:50:45.251632Z","end":"2026-05-11T18:50:45.547269Z","steps":["trace[987147880] 'count revisions from in-memory index tree' (duration: 295.419959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:45.547553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.928792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:45.54759Z","caller":"traceutil/trace.go:171","msg":"trace[802520643] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25791; }","duration":"188.011104ms","start":"2026-05-11T18:50:45.35957Z","end":"2026-05-11T18:50:45.547581Z","steps":["trace[802520643] 'count revisions from in-memory index tree' (duration: 187.84383ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:45.887607Z","caller":"traceutil/trace.go:171","msg":"trace[1778948608] transaction","detail":"{read_only:false; response_revision:25792; number_of_response:1; }","duration":"335.313137ms","start":"2026-05-11T18:50:45.552269Z","end":"2026-05-11T18:50:45.887582Z","steps":["trace[1778948608] 'process raft request' (duration: 335.152412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:45.888405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:45.552238Z","time spent":"336.10159ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:45.900255Z","caller":"traceutil/trace.go:171","msg":"trace[1226643583] transaction","detail":"{read_only:false; response_revision:25794; number_of_response:1; }","duration":"346.273583ms","start":"2026-05-11T18:50:45.55396Z","end":"2026-05-11T18:50:45.900233Z","steps":["trace[1226643583] 'process raft request' (duration: 346.048757ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:45.900261Z","caller":"traceutil/trace.go:171","msg":"trace[763359110] transaction","detail":"{read_only:false; response_revision:25793; number_of_response:1; }","duration":"347.108525ms","start":"2026-05-11T18:50:45.553112Z","end":"2026-05-11T18:50:45.90022Z","steps":["trace[763359110] 'process raft request' (duration: 346.775566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:45.900364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:45.553927Z","time spent":"346.384626ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:45.900441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:45.553096Z","time spent":"347.24198ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:46.090805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.659609ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-11T18:50:46.092249Z","caller":"traceutil/trace.go:171","msg":"trace[455086371] linearizableReadLoop","detail":"{readStateIndex:26651; appliedIndex:26649; }","duration":"538.01536ms","start":"2026-05-11T18:50:45.554204Z","end":"2026-05-11T18:50:46.092219Z","steps":["trace[455086371] 'read index received' (duration: 333.372334ms)","trace[455086371] 'applied index is now lower than readState.Index' (duration: 204.640686ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:46.092412Z","caller":"traceutil/trace.go:171","msg":"trace[1116530756] transaction","detail":"{read_only:false; response_revision:25795; number_of_response:1; }","duration":"273.87279ms","start":"2026-05-11T18:50:45.8185Z","end":"2026-05-11T18:50:46.092373Z","steps":["trace[1116530756] 'process raft request' (duration: 272.404009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:46.140928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.717315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18620"} {"level":"info","ts":"2026-05-11T18:50:46.140995Z","caller":"traceutil/trace.go:171","msg":"trace[927019483] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25795; }","duration":"586.823018ms","start":"2026-05-11T18:50:45.554154Z","end":"2026-05-11T18:50:46.140977Z","steps":["trace[927019483] 'agreement among raft nodes before linearized reading' (duration: 538.269428ms)","trace[927019483] 'range keys from in-memory index tree' (duration: 48.358564ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:50:46.141036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:45.554139Z","time spent":"586.888019ms","remote":"127.0.0.1:51548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18642,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-11T18:50:46.141326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.197301ms","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-11T18:50:46.14148Z","caller":"traceutil/trace.go:171","msg":"trace[1087116520] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25795; }","duration":"196.425978ms","start":"2026-05-11T18:50:45.945026Z","end":"2026-05-11T18:50:46.141452Z","steps":["trace[1087116520] 'agreement among raft nodes before linearized reading' (duration: 147.363274ms)","trace[1087116520] 'range keys from in-memory index tree' (duration: 48.733164ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:50:46.472043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.899381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:46.472135Z","caller":"traceutil/trace.go:171","msg":"trace[320381465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25796; }","duration":"229.057265ms","start":"2026-05-11T18:50:46.243056Z","end":"2026-05-11T18:50:46.472114Z","steps":["trace[320381465] 'range keys from in-memory index tree' (duration: 228.778288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:46.472051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.017006ms","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":"info","ts":"2026-05-11T18:50:46.472228Z","caller":"traceutil/trace.go:171","msg":"trace[1224530718] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25796; }","duration":"102.257232ms","start":"2026-05-11T18:50:46.369952Z","end":"2026-05-11T18:50:46.472209Z","steps":["trace[1224530718] 'count revisions from in-memory index tree' (duration: 101.909811ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:46.888408Z","caller":"traceutil/trace.go:171","msg":"trace[927305467] transaction","detail":"{read_only:false; response_revision:25797; number_of_response:1; }","duration":"151.82412ms","start":"2026-05-11T18:50:46.736464Z","end":"2026-05-11T18:50:46.888288Z","steps":["trace[927305467] 'process raft request' (duration: 151.220313ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:48.032778Z","caller":"traceutil/trace.go:171","msg":"trace[175608403] linearizableReadLoop","detail":"{readStateIndex:26663; appliedIndex:26662; }","duration":"129.507808ms","start":"2026-05-11T18:50:47.903243Z","end":"2026-05-11T18:50:48.032751Z","steps":["trace[175608403] 'read index received' (duration: 129.22672ms)","trace[175608403] 'applied index is now lower than readState.Index' (duration: 279.608µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:48.032928Z","caller":"traceutil/trace.go:171","msg":"trace[95976429] transaction","detail":"{read_only:false; response_revision:25805; number_of_response:1; }","duration":"135.775752ms","start":"2026-05-11T18:50:47.897112Z","end":"2026-05-11T18:50:48.032888Z","steps":["trace[95976429] 'process raft request' (duration: 135.382701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:48.03309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.790047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T18:50:48.033145Z","caller":"traceutil/trace.go:171","msg":"trace[1039952474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25805; }","duration":"129.91419ms","start":"2026-05-11T18:50:47.903218Z","end":"2026-05-11T18:50:48.033132Z","steps":["trace[1039952474] 'agreement among raft nodes before linearized reading' (duration: 129.693334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:48.033375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.152747ms","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-11T18:50:48.033483Z","caller":"traceutil/trace.go:171","msg":"trace[780719664] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25805; }","duration":"125.321922ms","start":"2026-05-11T18:50:47.908134Z","end":"2026-05-11T18:50:48.033456Z","steps":["trace[780719664] 'agreement among raft nodes before linearized reading' (duration: 125.034084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:48.35232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.035755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T18:50:48.352459Z","caller":"traceutil/trace.go:171","msg":"trace[2024343315] transaction","detail":"{read_only:false; response_revision:25807; number_of_response:1; }","duration":"313.990725ms","start":"2026-05-11T18:50:48.038451Z","end":"2026-05-11T18:50:48.352442Z","steps":["trace[2024343315] 'process raft request' (duration: 170.751555ms)","trace[2024343315] 'compare' (duration: 142.646133ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:50:48.352496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:48.038434Z","time spent":"314.045117ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:48.443593Z","caller":"traceutil/trace.go:171","msg":"trace[632667019] linearizableReadLoop","detail":"{readStateIndex:26666; appliedIndex:26664; }","duration":"284.478785ms","start":"2026-05-11T18:50:48.159086Z","end":"2026-05-11T18:50:48.443565Z","steps":["trace[632667019] 'read index received' (duration: 50.122113ms)","trace[632667019] 'applied index is now lower than readState.Index' (duration: 234.355272ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:48.444018Z","caller":"traceutil/trace.go:171","msg":"trace[2066170427] transaction","detail":"{read_only:false; response_revision:25808; number_of_response:1; }","duration":"345.80667ms","start":"2026-05-11T18:50:48.098192Z","end":"2026-05-11T18:50:48.443998Z","steps":["trace[2066170427] 'process raft request' (duration: 345.214514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:48.44414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:48.098162Z","time spent":"345.898313ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:48.444358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.276718ms","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-11T18:50:48.444396Z","caller":"traceutil/trace.go:171","msg":"trace[1762023625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25808; }","duration":"285.35156ms","start":"2026-05-11T18:50:48.159035Z","end":"2026-05-11T18:50:48.444387Z","steps":["trace[1762023625] 'agreement among raft nodes before linearized reading' (duration: 285.225756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:48.516419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.820173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:48.516484Z","caller":"traceutil/trace.go:171","msg":"trace[1816671637] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:25809; }","duration":"348.916096ms","start":"2026-05-11T18:50:48.167553Z","end":"2026-05-11T18:50:48.516469Z","steps":["trace[1816671637] 'agreement among raft nodes before linearized reading' (duration: 348.804963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:48.516518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:48.167535Z","time spent":"348.973048ms","remote":"127.0.0.1:36216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-05-11T18:50:48.516757Z","caller":"traceutil/trace.go:171","msg":"trace[1920830935] transaction","detail":"{read_only:false; response_revision:25809; number_of_response:1; }","duration":"195.642896ms","start":"2026-05-11T18:50:48.321103Z","end":"2026-05-11T18:50:48.516745Z","steps":["trace[1920830935] 'process raft request' (duration: 195.154533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:48.516929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.869597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:48.516962Z","caller":"traceutil/trace.go:171","msg":"trace[1733868333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25809; }","duration":"274.94591ms","start":"2026-05-11T18:50:48.242005Z","end":"2026-05-11T18:50:48.51695Z","steps":["trace[1733868333] 'agreement among raft nodes before linearized reading' (duration: 274.899319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:48.517112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.030614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:48.517151Z","caller":"traceutil/trace.go:171","msg":"trace[1600935966] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25809; }","duration":"315.108636ms","start":"2026-05-11T18:50:48.202033Z","end":"2026-05-11T18:50:48.517142Z","steps":["trace[1600935966] 'agreement among raft nodes before linearized reading' (duration: 315.046435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:48.517179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:48.202013Z","time spent":"315.158248ms","remote":"127.0.0.1:57972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-05-11T18:50:48.843891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.677204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:48.843959Z","caller":"traceutil/trace.go:171","msg":"trace[1001499463] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:25810; }","duration":"147.800227ms","start":"2026-05-11T18:50:48.696144Z","end":"2026-05-11T18:50:48.843944Z","steps":["trace[1001499463] 'count revisions from in-memory index tree' (duration: 147.587451ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:48.877338Z","caller":"traceutil/trace.go:171","msg":"trace[1724329532] transaction","detail":"{read_only:false; response_revision:25811; number_of_response:1; }","duration":"108.378192ms","start":"2026-05-11T18:50:48.768923Z","end":"2026-05-11T18:50:48.877301Z","steps":["trace[1724329532] 'process raft request' (duration: 108.095843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:49.17158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.665499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T18:50:49.171765Z","caller":"traceutil/trace.go:171","msg":"trace[1801892702] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"271.524025ms","start":"2026-05-11T18:50:48.900216Z","end":"2026-05-11T18:50:49.17174Z","steps":["trace[1801892702] 'process raft request' (duration: 148.547538ms)","trace[1801892702] 'compare' (duration: 122.203634ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:49.427686Z","caller":"traceutil/trace.go:171","msg":"trace[988173322] transaction","detail":"{read_only:false; response_revision:25814; number_of_response:1; }","duration":"246.049017ms","start":"2026-05-11T18:50:49.181611Z","end":"2026-05-11T18:50:49.42766Z","steps":["trace[988173322] 'process raft request' (duration: 232.543081ms)","trace[988173322] 'compare' (duration: 13.361512ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:49.589089Z","caller":"traceutil/trace.go:171","msg":"trace[114059827] transaction","detail":"{read_only:false; response_revision:25815; number_of_response:1; }","duration":"352.442284ms","start":"2026-05-11T18:50:49.236599Z","end":"2026-05-11T18:50:49.589042Z","steps":["trace[114059827] 'process raft request' (duration: 352.005421ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:49.58917Z","caller":"traceutil/trace.go:171","msg":"trace[2018348924] transaction","detail":"{read_only:false; response_revision:25817; number_of_response:1; }","duration":"351.125157ms","start":"2026-05-11T18:50:49.238017Z","end":"2026-05-11T18:50:49.589142Z","steps":["trace[2018348924] 'process raft request' (duration: 351.046285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:49.589262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:49.236579Z","time spent":"352.588448ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:49.589263Z","caller":"traceutil/trace.go:171","msg":"trace[1042755718] transaction","detail":"{read_only:false; response_revision:25816; number_of_response:1; }","duration":"352.212478ms","start":"2026-05-11T18:50:49.237013Z","end":"2026-05-11T18:50:49.589225Z","steps":["trace[1042755718] 'process raft request' (duration: 351.989812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:49.589335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:49.238002Z","time spent":"351.243221ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:49.589432Z","caller":"traceutil/trace.go:171","msg":"trace[1991666089] transaction","detail":"{read_only:false; response_revision:25819; number_of_response:1; }","duration":"349.992726ms","start":"2026-05-11T18:50:49.239426Z","end":"2026-05-11T18:50:49.589419Z","steps":["trace[1991666089] 'process raft request' (duration: 349.818451ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:49.589404Z","caller":"traceutil/trace.go:171","msg":"trace[1057742055] transaction","detail":"{read_only:false; response_revision:25818; number_of_response:1; }","duration":"351.300483ms","start":"2026-05-11T18:50:49.238065Z","end":"2026-05-11T18:50:49.589366Z","steps":["trace[1057742055] 'process raft request' (duration: 351.042946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:49.589428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:49.237001Z","time spent":"352.337611ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:49.58949Z","caller":"traceutil/trace.go:171","msg":"trace[156340201] transaction","detail":"{read_only:false; response_revision:25820; number_of_response:1; }","duration":"349.484532ms","start":"2026-05-11T18:50:49.23999Z","end":"2026-05-11T18:50:49.589475Z","steps":["trace[156340201] 'process raft request' (duration: 349.343678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:49.589494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:49.239405Z","time spent":"350.054827ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:49.589528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.541683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:49.589407Z","caller":"traceutil/trace.go:171","msg":"trace[493023203] linearizableReadLoop","detail":"{readStateIndex:26677; appliedIndex:26671; }","duration":"349.41817ms","start":"2026-05-11T18:50:49.239962Z","end":"2026-05-11T18:50:49.58938Z","steps":["trace[493023203] 'read index received' (duration: 174.216271ms)","trace[493023203] 'applied index is now lower than readState.Index' (duration: 175.200229ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:50:49.589555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:49.239959Z","time spent":"349.557234ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:49.58957Z","caller":"traceutil/trace.go:171","msg":"trace[1975078793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25820; }","duration":"349.624906ms","start":"2026-05-11T18:50:49.239935Z","end":"2026-05-11T18:50:49.58956Z","steps":["trace[1975078793] 'agreement among raft nodes before linearized reading' (duration: 349.536804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:49.589575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:49.238032Z","time spent":"351.449587ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:49.589606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:49.239925Z","time spent":"349.669557ms","remote":"127.0.0.1:51380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T18:50:49.589694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.656303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:49.589767Z","caller":"traceutil/trace.go:171","msg":"trace[1587689438] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:25820; }","duration":"147.759426ms","start":"2026-05-11T18:50:49.441964Z","end":"2026-05-11T18:50:49.589723Z","steps":["trace[1587689438] 'agreement among raft nodes before linearized reading' (duration: 147.680654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:49.589811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.375806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:49.589957Z","caller":"traceutil/trace.go:171","msg":"trace[1576453796] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:25820; }","duration":"124.594863ms","start":"2026-05-11T18:50:49.465323Z","end":"2026-05-11T18:50:49.589917Z","steps":["trace[1576453796] 'agreement among raft nodes before linearized reading' (duration: 124.329375ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:58.345308Z","caller":"traceutil/trace.go:171","msg":"trace[846220298] linearizableReadLoop","detail":"{readStateIndex:26740; appliedIndex:26739; }","duration":"221.796161ms","start":"2026-05-11T18:50:58.123488Z","end":"2026-05-11T18:50:58.345284Z","steps":["trace[846220298] 'read index received' (duration: 221.597235ms)","trace[846220298] 'applied index is now lower than readState.Index' (duration: 198.016µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:58.345602Z","caller":"traceutil/trace.go:171","msg":"trace[2118250638] transaction","detail":"{read_only:false; response_revision:25880; number_of_response:1; }","duration":"250.646732ms","start":"2026-05-11T18:50:58.094934Z","end":"2026-05-11T18:50:58.34558Z","steps":["trace[2118250638] 'process raft request' (duration: 250.20267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:58.345817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.259066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:58.345865Z","caller":"traceutil/trace.go:171","msg":"trace[1612299134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25880; }","duration":"104.355478ms","start":"2026-05-11T18:50:58.241498Z","end":"2026-05-11T18:50:58.345853Z","steps":["trace[1612299134] 'agreement among raft nodes before linearized reading' (duration: 104.270856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:58.346291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.803099ms","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-11T18:50:58.346333Z","caller":"traceutil/trace.go:171","msg":"trace[895350038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25880; }","duration":"222.871351ms","start":"2026-05-11T18:50:58.123451Z","end":"2026-05-11T18:50:58.346322Z","steps":["trace[895350038] 'agreement among raft nodes before linearized reading' (duration: 222.554383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:58.70862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.565022ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T18:50:58.708807Z","caller":"traceutil/trace.go:171","msg":"trace[386958297] transaction","detail":"{read_only:false; response_revision:25881; number_of_response:1; }","duration":"356.348267ms","start":"2026-05-11T18:50:58.352406Z","end":"2026-05-11T18:50:58.708754Z","steps":["trace[386958297] 'process raft request' (duration: 133.556779ms)","trace[386958297] 'compare' (duration: 222.349846ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:58.70891Z","caller":"traceutil/trace.go:171","msg":"trace[1687228140] transaction","detail":"{read_only:false; response_revision:25883; number_of_response:1; }","duration":"117.405121ms","start":"2026-05-11T18:50:58.591491Z","end":"2026-05-11T18:50:58.708896Z","steps":["trace[1687228140] 'process raft request' (duration: 117.35974ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:58.708909Z","caller":"traceutil/trace.go:171","msg":"trace[1902784870] linearizableReadLoop","detail":"{readStateIndex:26741; appliedIndex:26740; }","duration":"336.66925ms","start":"2026-05-11T18:50:58.372155Z","end":"2026-05-11T18:50:58.708825Z","steps":["trace[1902784870] 'read index received' (duration: 113.648076ms)","trace[1902784870] 'applied index is now lower than readState.Index' (duration: 223.014964ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:50:58.708951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:58.352395Z","time spent":"356.494661ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:58.708977Z","caller":"traceutil/trace.go:171","msg":"trace[784570520] transaction","detail":"{read_only:false; response_revision:25882; number_of_response:1; }","duration":"220.778602ms","start":"2026-05-11T18:50:58.488177Z","end":"2026-05-11T18:50:58.708955Z","steps":["trace[784570520] 'process raft request' (duration: 220.561386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:58.709022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.839916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T18:50:58.709063Z","caller":"traceutil/trace.go:171","msg":"trace[136795708] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:25883; }","duration":"336.922707ms","start":"2026-05-11T18:50:58.372129Z","end":"2026-05-11T18:50:58.709052Z","steps":["trace[136795708] 'agreement among raft nodes before linearized reading' (duration: 336.766073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:58.709087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:58.372119Z","time spent":"336.962398ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-05-11T18:50:58.709145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.90378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T18:50:58.70918Z","caller":"traceutil/trace.go:171","msg":"trace[851919736] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:25883; }","duration":"133.991032ms","start":"2026-05-11T18:50:58.575177Z","end":"2026-05-11T18:50:58.709168Z","steps":["trace[851919736] 'agreement among raft nodes before linearized reading' (duration: 133.873148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:58.709217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.629784ms","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-11T18:50:58.709218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.902375ms","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-11T18:50:58.709275Z","caller":"traceutil/trace.go:171","msg":"trace[274291209] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25883; }","duration":"145.739118ms","start":"2026-05-11T18:50:58.563522Z","end":"2026-05-11T18:50:58.709261Z","steps":["trace[274291209] 'agreement among raft nodes before linearized reading' (duration: 145.561603ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:58.70932Z","caller":"traceutil/trace.go:171","msg":"trace[1996309464] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25883; }","duration":"293.033428ms","start":"2026-05-11T18:50:58.416257Z","end":"2026-05-11T18:50:58.709291Z","steps":["trace[1996309464] 'agreement among raft nodes before linearized reading' (duration: 292.797851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:59.299854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.851145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T18:50:59.299962Z","caller":"traceutil/trace.go:171","msg":"trace[803428839] transaction","detail":"{read_only:false; response_revision:25885; number_of_response:1; }","duration":"585.898934ms","start":"2026-05-11T18:50:58.714044Z","end":"2026-05-11T18:50:59.299943Z","steps":["trace[803428839] 'process raft request' (duration: 279.871894ms)","trace[803428839] 'compare' (duration: 305.740612ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:50:59.300022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:58.714034Z","time spent":"585.959995ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:59.566182Z","caller":"traceutil/trace.go:171","msg":"trace[46999477] linearizableReadLoop","detail":"{readStateIndex:26746; appliedIndex:26745; }","duration":"350.271209ms","start":"2026-05-11T18:50:59.215879Z","end":"2026-05-11T18:50:59.56615Z","steps":["trace[46999477] 'read index received' (duration: 349.355494ms)","trace[46999477] 'applied index is now lower than readState.Index' (duration: 914.245µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:59.566251Z","caller":"traceutil/trace.go:171","msg":"trace[1589801872] transaction","detail":"{read_only:false; response_revision:25886; number_of_response:1; }","duration":"377.732751ms","start":"2026-05-11T18:50:59.188481Z","end":"2026-05-11T18:50:59.566214Z","steps":["trace[1589801872] 'process raft request' (duration: 376.800616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:59.56663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:59.188455Z","time spent":"378.055031ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:59.566673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.776243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-11T18:50:59.566728Z","caller":"traceutil/trace.go:171","msg":"trace[1444844180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25886; }","duration":"350.846655ms","start":"2026-05-11T18:50:59.215863Z","end":"2026-05-11T18:50:59.56671Z","steps":["trace[1444844180] 'agreement among raft nodes before linearized reading' (duration: 350.425104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:59.566789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:59.215801Z","time spent":"350.974088ms","remote":"127.0.0.1:51628","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-11T18:50:59.567212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.609343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T18:50:59.567241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.053479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:59.567244Z","caller":"traceutil/trace.go:171","msg":"trace[2122460979] transaction","detail":"{read_only:false; response_revision:25887; number_of_response:1; }","duration":"104.682777ms","start":"2026-05-11T18:50:59.462508Z","end":"2026-05-11T18:50:59.567191Z","steps":["trace[2122460979] 'process raft request' (duration: 104.538483ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:59.567277Z","caller":"traceutil/trace.go:171","msg":"trace[394993345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25887; }","duration":"325.705896ms","start":"2026-05-11T18:50:59.241554Z","end":"2026-05-11T18:50:59.56726Z","steps":["trace[394993345] 'agreement among raft nodes before linearized reading' (duration: 325.605493ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:59.56729Z","caller":"traceutil/trace.go:171","msg":"trace[420817937] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:25887; }","duration":"248.132382ms","start":"2026-05-11T18:50:59.319141Z","end":"2026-05-11T18:50:59.567273Z","steps":["trace[420817937] 'agreement among raft nodes before linearized reading' (duration: 248.037669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:59.567319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:59.241538Z","time spent":"325.773629ms","remote":"127.0.0.1:51380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T18:50:59.997659Z","caller":"traceutil/trace.go:171","msg":"trace[1660325616] transaction","detail":"{read_only:false; response_revision:25889; number_of_response:1; }","duration":"379.589303ms","start":"2026-05-11T18:50:59.618037Z","end":"2026-05-11T18:50:59.997626Z","steps":["trace[1660325616] 'process raft request' (duration: 342.77991ms)","trace[1660325616] 'compare' (duration: 36.021931ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:50:59.997827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:59.61802Z","time spent":"379.733206ms","remote":"127.0.0.1:34770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1633,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T18:51:00.119241Z","caller":"traceutil/trace.go:171","msg":"trace[1394126491] transaction","detail":"{read_only:false; response_revision:25890; number_of_response:1; }","duration":"484.756944ms","start":"2026-05-11T18:50:59.634437Z","end":"2026-05-11T18:51:00.119194Z","steps":["trace[1394126491] 'process raft request' (duration: 484.213969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:00.119461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:59.634413Z","time spent":"484.93636ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:51:00.119238Z","caller":"traceutil/trace.go:171","msg":"trace[1624622165] transaction","detail":"{read_only:false; response_revision:25891; number_of_response:1; }","duration":"481.39444ms","start":"2026-05-11T18:50:59.637807Z","end":"2026-05-11T18:51:00.119201Z","steps":["trace[1624622165] 'process raft request' (duration: 481.223836ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:00.119596Z","caller":"traceutil/trace.go:171","msg":"trace[139947662] transaction","detail":"{read_only:false; response_revision:25892; number_of_response:1; }","duration":"477.916495ms","start":"2026-05-11T18:50:59.641664Z","end":"2026-05-11T18:51:00.11958Z","steps":["trace[139947662] 'process raft request' (duration: 477.485572ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:00.119671Z","caller":"traceutil/trace.go:171","msg":"trace[1129732168] transaction","detail":"{read_only:false; response_revision:25893; number_of_response:1; }","duration":"476.364561ms","start":"2026-05-11T18:50:59.64326Z","end":"2026-05-11T18:51:00.119625Z","steps":["trace[1129732168] 'process raft request' (duration: 476.139275ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:00.11963Z","caller":"traceutil/trace.go:171","msg":"trace[1913357297] linearizableReadLoop","detail":"{readStateIndex:26755; appliedIndex:26748; }","duration":"321.202862ms","start":"2026-05-11T18:50:59.798399Z","end":"2026-05-11T18:51:00.119602Z","steps":["trace[1913357297] 'read index received' (duration: 162.532425ms)","trace[1913357297] 'applied index is now lower than readState.Index' (duration: 158.669007ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:00.119712Z","caller":"traceutil/trace.go:171","msg":"trace[263613929] transaction","detail":"{read_only:false; response_revision:25895; number_of_response:1; }","duration":"474.380447ms","start":"2026-05-11T18:50:59.645302Z","end":"2026-05-11T18:51:00.119682Z","steps":["trace[263613929] 'process raft request' (duration: 474.253803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:00.11985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:59.641647Z","time spent":"478.028557ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:51:00.119899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:59.645281Z","time spent":"474.510441ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:51:00.119907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:59.643242Z","time spent":"476.533176ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:51:00.119948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.512051ms","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":"warn","ts":"2026-05-11T18:51:00.119973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.193057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:51:00.119997Z","caller":"traceutil/trace.go:171","msg":"trace[1010148650] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:25895; }","duration":"321.645394ms","start":"2026-05-11T18:50:59.798334Z","end":"2026-05-11T18:51:00.11998Z","steps":["trace[1010148650] 'agreement among raft nodes before linearized reading' (duration: 321.51979ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:00.120054Z","caller":"traceutil/trace.go:171","msg":"trace[2012856074] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25895; }","duration":"130.32424ms","start":"2026-05-11T18:50:59.989711Z","end":"2026-05-11T18:51:00.120035Z","steps":["trace[2012856074] 'agreement among raft nodes before linearized reading' (duration: 130.149696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:00.120055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:59.798305Z","time spent":"321.739367ms","remote":"127.0.0.1:44712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"info","ts":"2026-05-11T18:51:00.119699Z","caller":"traceutil/trace.go:171","msg":"trace[1819588408] transaction","detail":"{read_only:false; response_revision:25894; number_of_response:1; }","duration":"474.652234ms","start":"2026-05-11T18:50:59.645014Z","end":"2026-05-11T18:51:00.119666Z","steps":["trace[1819588408] 'process raft request' (duration: 474.48953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:00.120123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.187082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1651"} {"level":"warn","ts":"2026-05-11T18:51:00.120157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:59.644968Z","time spent":"475.155508ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:51:00.120203Z","caller":"traceutil/trace.go:171","msg":"trace[157799266] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25895; }","duration":"114.316285ms","start":"2026-05-11T18:51:00.005864Z","end":"2026-05-11T18:51:00.12018Z","steps":["trace[157799266] 'agreement among raft nodes before linearized reading' (duration: 114.098939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:00.120438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.072062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1651"} {"level":"info","ts":"2026-05-11T18:51:00.121534Z","caller":"traceutil/trace.go:171","msg":"trace[1400124654] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25895; }","duration":"118.182493ms","start":"2026-05-11T18:51:00.003307Z","end":"2026-05-11T18:51:00.121489Z","steps":["trace[1400124654] 'agreement among raft nodes before linearized reading' (duration: 116.922489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:00.12048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:59.637781Z","time spent":"481.784301ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:51:00.591427Z","caller":"traceutil/trace.go:171","msg":"trace[1207729910] linearizableReadLoop","detail":"{readStateIndex:26759; appliedIndex:26758; }","duration":"376.383784ms","start":"2026-05-11T18:51:00.215006Z","end":"2026-05-11T18:51:00.59139Z","steps":["trace[1207729910] 'read index received' (duration: 376.081235ms)","trace[1207729910] 'applied index is now lower than readState.Index' (duration: 300.669µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:00.591492Z","caller":"traceutil/trace.go:171","msg":"trace[1417695399] transaction","detail":"{read_only:false; response_revision:25898; number_of_response:1; }","duration":"440.315089ms","start":"2026-05-11T18:51:00.151136Z","end":"2026-05-11T18:51:00.591451Z","steps":["trace[1417695399] 'process raft request' (duration: 439.98833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:00.591691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:51:00.151126Z","time spent":"440.451503ms","remote":"127.0.0.1:34770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2793,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:51:00.591809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.785334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-05-11T18:51:00.591858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.917929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:51:00.591891Z","caller":"traceutil/trace.go:171","msg":"trace[97478334] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25898; }","duration":"376.932929ms","start":"2026-05-11T18:51:00.214939Z","end":"2026-05-11T18:51:00.591872Z","steps":["trace[97478334] 'agreement among raft nodes before linearized reading' (duration: 376.59987ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:00.591908Z","caller":"traceutil/trace.go:171","msg":"trace[81596213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25898; }","duration":"350.031952ms","start":"2026-05-11T18:51:00.241862Z","end":"2026-05-11T18:51:00.591894Z","steps":["trace[81596213] 'agreement among raft nodes before linearized reading' (duration: 349.908269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:00.591939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:51:00.214912Z","time spent":"377.01806ms","remote":"127.0.0.1:51558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-11T18:51:00.591975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:51:00.241819Z","time spent":"350.146685ms","remote":"127.0.0.1:51380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T18:51:00.59202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.955946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-11T18:51:00.59211Z","caller":"traceutil/trace.go:171","msg":"trace[45142947] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25898; }","duration":"239.08636ms","start":"2026-05-11T18:51:00.353004Z","end":"2026-05-11T18:51:00.59209Z","steps":["trace[45142947] 'agreement among raft nodes before linearized reading' (duration: 238.803452ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:00.76954Z","caller":"traceutil/trace.go:171","msg":"trace[436259758] transaction","detail":"{read_only:false; response_revision:25899; number_of_response:1; }","duration":"169.839518ms","start":"2026-05-11T18:51:00.59968Z","end":"2026-05-11T18:51:00.769519Z","steps":["trace[436259758] 'process raft request' (duration: 139.466954ms)","trace[436259758] 'compare' (duration: 30.284112ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:00.860279Z","caller":"traceutil/trace.go:171","msg":"trace[1509914356] transaction","detail":"{read_only:false; response_revision:25900; number_of_response:1; }","duration":"260.038473ms","start":"2026-05-11T18:51:00.600198Z","end":"2026-05-11T18:51:00.860236Z","steps":["trace[1509914356] 'process raft request' (duration: 259.20496ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:00.860425Z","caller":"traceutil/trace.go:171","msg":"trace[1389308896] transaction","detail":"{read_only:false; response_revision:25901; number_of_response:1; }","duration":"259.737214ms","start":"2026-05-11T18:51:00.600652Z","end":"2026-05-11T18:51:00.860389Z","steps":["trace[1389308896] 'process raft request' (duration: 259.434946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:00.860557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.137398ms","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-11T18:51:00.860611Z","caller":"traceutil/trace.go:171","msg":"trace[1518097658] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25903; }","duration":"196.254701ms","start":"2026-05-11T18:51:00.664345Z","end":"2026-05-11T18:51:00.8606Z","steps":["trace[1518097658] 'agreement among raft nodes before linearized reading' (duration: 196.162099ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:00.860453Z","caller":"traceutil/trace.go:171","msg":"trace[2016754749] linearizableReadLoop","detail":"{readStateIndex:26763; appliedIndex:26759; }","duration":"196.018924ms","start":"2026-05-11T18:51:00.66441Z","end":"2026-05-11T18:51:00.860429Z","steps":["trace[2016754749] 'read index received' (duration: 74.795818ms)","trace[2016754749] 'applied index is now lower than readState.Index' (duration: 121.222186ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:00.860746Z","caller":"traceutil/trace.go:171","msg":"trace[1979778265] transaction","detail":"{read_only:false; response_revision:25903; number_of_response:1; }","duration":"146.317914ms","start":"2026-05-11T18:51:00.714387Z","end":"2026-05-11T18:51:00.860705Z","steps":["trace[1979778265] 'process raft request' (duration: 145.989475ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:00.860812Z","caller":"traceutil/trace.go:171","msg":"trace[682185217] transaction","detail":"{read_only:false; response_revision:25902; number_of_response:1; }","duration":"259.448305ms","start":"2026-05-11T18:51:00.601344Z","end":"2026-05-11T18:51:00.860793Z","steps":["trace[682185217] 'process raft request' (duration: 258.978523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:00.860886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.148176ms","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-11T18:51:00.860939Z","caller":"traceutil/trace.go:171","msg":"trace[1832880169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25903; }","duration":"147.256049ms","start":"2026-05-11T18:51:00.713668Z","end":"2026-05-11T18:51:00.860924Z","steps":["trace[1832880169] 'agreement among raft nodes before linearized reading' (duration: 147.067874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:00.861184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.259444ms","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-11T18:51:00.861264Z","caller":"traceutil/trace.go:171","msg":"trace[127343222] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25903; }","duration":"118.398728ms","start":"2026-05-11T18:51:00.742846Z","end":"2026-05-11T18:51:00.861245Z","steps":["trace[127343222] 'agreement among raft nodes before linearized reading' (duration: 118.11778ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:01.073497Z","caller":"traceutil/trace.go:171","msg":"trace[1086311778] transaction","detail":"{read_only:false; response_revision:25904; number_of_response:1; }","duration":"207.476932ms","start":"2026-05-11T18:51:00.865988Z","end":"2026-05-11T18:51:01.073465Z","steps":["trace[1086311778] 'process raft request' (duration: 206.893886ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:01.073961Z","caller":"traceutil/trace.go:171","msg":"trace[1964087761] transaction","detail":"{read_only:false; response_revision:25907; number_of_response:1; }","duration":"206.082213ms","start":"2026-05-11T18:51:00.867853Z","end":"2026-05-11T18:51:01.073935Z","steps":["trace[1964087761] 'process raft request' (duration: 206.037852ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:01.074185Z","caller":"traceutil/trace.go:171","msg":"trace[1483819788] linearizableReadLoop","detail":"{readStateIndex:26765; appliedIndex:26764; }","duration":"208.10829ms","start":"2026-05-11T18:51:00.866059Z","end":"2026-05-11T18:51:01.074167Z","steps":["trace[1483819788] 'read index received' (duration: 206.219488ms)","trace[1483819788] 'applied index is now lower than readState.Index' (duration: 1.886352ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:01.074179Z","caller":"traceutil/trace.go:171","msg":"trace[1391744524] transaction","detail":"{read_only:false; response_revision:25905; number_of_response:1; }","duration":"207.928765ms","start":"2026-05-11T18:51:00.866207Z","end":"2026-05-11T18:51:01.074136Z","steps":["trace[1391744524] 'process raft request' (duration: 206.892926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:01.074468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.592767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:127953"} {"level":"info","ts":"2026-05-11T18:51:01.074518Z","caller":"traceutil/trace.go:171","msg":"trace[1453773543] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:25907; }","duration":"206.695251ms","start":"2026-05-11T18:51:00.86781Z","end":"2026-05-11T18:51:01.074505Z","steps":["trace[1453773543] 'agreement among raft nodes before linearized reading' (duration: 206.464664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:01.074515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.53876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-11T18:51:01.074605Z","caller":"traceutil/trace.go:171","msg":"trace[1628401241] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25907; }","duration":"205.617651ms","start":"2026-05-11T18:51:00.868945Z","end":"2026-05-11T18:51:01.074563Z","steps":["trace[1628401241] 'agreement among raft nodes before linearized reading' (duration: 205.458316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:01.074595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.47049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra-registry.18ae9774b80563b5\" ","response":"range_response_count:1 size:722"} {"level":"info","ts":"2026-05-11T18:51:01.07475Z","caller":"traceutil/trace.go:171","msg":"trace[427691379] range","detail":"{range_begin:/registry/events/openstack/container-infra-registry.18ae9774b80563b5; range_end:; response_count:1; response_revision:25907; }","duration":"208.720458ms","start":"2026-05-11T18:51:00.865997Z","end":"2026-05-11T18:51:01.074718Z","steps":["trace[427691379] 'agreement among raft nodes before linearized reading' (duration: 208.264604ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:01.076215Z","caller":"traceutil/trace.go:171","msg":"trace[1331333570] transaction","detail":"{read_only:false; response_revision:25906; number_of_response:1; }","duration":"208.394538ms","start":"2026-05-11T18:51:00.867794Z","end":"2026-05-11T18:51:01.076188Z","steps":["trace[1331333570] 'process raft request' (duration: 206.026982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:02.448117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.959502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:51:02.448255Z","caller":"traceutil/trace.go:171","msg":"trace[432540506] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25969; }","duration":"102.149498ms","start":"2026-05-11T18:51:02.346082Z","end":"2026-05-11T18:51:02.448231Z","steps":["trace[432540506] 'count revisions from in-memory index tree' (duration: 101.87209ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:05.027417Z","caller":"traceutil/trace.go:171","msg":"trace[1662687674] transaction","detail":"{read_only:false; response_revision:25987; number_of_response:1; }","duration":"238.728162ms","start":"2026-05-11T18:51:04.788657Z","end":"2026-05-11T18:51:05.027385Z","steps":["trace[1662687674] 'process raft request' (duration: 238.541536ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:05.132725Z","caller":"traceutil/trace.go:171","msg":"trace[1523839603] transaction","detail":"{read_only:false; response_revision:25988; number_of_response:1; }","duration":"257.406199ms","start":"2026-05-11T18:51:04.875295Z","end":"2026-05-11T18:51:05.132701Z","steps":["trace[1523839603] 'process raft request' (duration: 231.373577ms)","trace[1523839603] 'compare' (duration: 25.849737ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:09.783833Z","caller":"traceutil/trace.go:171","msg":"trace[437543185] transaction","detail":"{read_only:false; response_revision:26043; number_of_response:1; }","duration":"126.342989ms","start":"2026-05-11T18:51:09.657453Z","end":"2026-05-11T18:51:09.783796Z","steps":["trace[437543185] 'process raft request' (duration: 126.103922ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:10.278343Z","caller":"traceutil/trace.go:171","msg":"trace[952757755] linearizableReadLoop","detail":"{readStateIndex:26907; appliedIndex:26906; }","duration":"173.478729ms","start":"2026-05-11T18:51:10.104835Z","end":"2026-05-11T18:51:10.278314Z","steps":["trace[952757755] 'read index received' (duration: 173.414137ms)","trace[952757755] 'applied index is now lower than readState.Index' (duration: 62.882µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:10.27852Z","caller":"traceutil/trace.go:171","msg":"trace[526798838] transaction","detail":"{read_only:false; response_revision:26044; number_of_response:1; }","duration":"281.765047ms","start":"2026-05-11T18:51:09.996709Z","end":"2026-05-11T18:51:10.278474Z","steps":["trace[526798838] 'process raft request' (duration: 281.460269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:10.278757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.90308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.62\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-11T18:51:10.278812Z","caller":"traceutil/trace.go:171","msg":"trace[924213905] range","detail":"{range_begin:/registry/masterleases/199.19.213.62; range_end:; response_count:1; response_revision:26044; }","duration":"174.011423ms","start":"2026-05-11T18:51:10.104788Z","end":"2026-05-11T18:51:10.2788Z","steps":["trace[924213905] 'agreement among raft nodes before linearized reading' (duration: 173.687874ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:10.449974Z","caller":"traceutil/trace.go:171","msg":"trace[1574512069] linearizableReadLoop","detail":"{readStateIndex:26913; appliedIndex:26907; }","duration":"171.532774ms","start":"2026-05-11T18:51:10.278421Z","end":"2026-05-11T18:51:10.449953Z","steps":["trace[1574512069] 'read index received' (duration: 171.291077ms)","trace[1574512069] 'applied index is now lower than readState.Index' (duration: 240.857µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:10.450297Z","caller":"traceutil/trace.go:171","msg":"trace[1733351874] transaction","detail":"{read_only:false; response_revision:26045; number_of_response:1; }","duration":"292.971367ms","start":"2026-05-11T18:51:10.157315Z","end":"2026-05-11T18:51:10.450287Z","steps":["trace[1733351874] 'process raft request' (duration: 292.305389ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:10.450521Z","caller":"traceutil/trace.go:171","msg":"trace[241652702] transaction","detail":"{read_only:false; response_revision:26046; number_of_response:1; }","duration":"291.28066ms","start":"2026-05-11T18:51:10.159227Z","end":"2026-05-11T18:51:10.450507Z","steps":["trace[241652702] 'process raft request' (duration: 290.54659ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:10.450681Z","caller":"traceutil/trace.go:171","msg":"trace[527416384] transaction","detail":"{read_only:false; response_revision:26047; number_of_response:1; }","duration":"290.398484ms","start":"2026-05-11T18:51:10.160274Z","end":"2026-05-11T18:51:10.450673Z","steps":["trace[527416384] 'process raft request' (duration: 289.542191ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:10.450785Z","caller":"traceutil/trace.go:171","msg":"trace[1147047521] transaction","detail":"{read_only:false; response_revision:26048; number_of_response:1; }","duration":"289.684246ms","start":"2026-05-11T18:51:10.161088Z","end":"2026-05-11T18:51:10.450772Z","steps":["trace[1147047521] 'process raft request' (duration: 288.76356ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:10.45087Z","caller":"traceutil/trace.go:171","msg":"trace[824434156] transaction","detail":"{read_only:false; response_revision:26049; number_of_response:1; }","duration":"288.506623ms","start":"2026-05-11T18:51:10.162356Z","end":"2026-05-11T18:51:10.450863Z","steps":["trace[824434156] 'process raft request' (duration: 287.530205ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:10.450964Z","caller":"traceutil/trace.go:171","msg":"trace[1503870730] transaction","detail":"{read_only:false; response_revision:26050; number_of_response:1; }","duration":"284.602805ms","start":"2026-05-11T18:51:10.166352Z","end":"2026-05-11T18:51:10.450954Z","steps":["trace[1503870730] 'process raft request' (duration: 283.569636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:10.451144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.520043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-11T18:51:10.451177Z","caller":"traceutil/trace.go:171","msg":"trace[1016149017] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26050; }","duration":"257.605905ms","start":"2026-05-11T18:51:10.193563Z","end":"2026-05-11T18:51:10.451169Z","steps":["trace[1016149017] 'agreement among raft nodes before linearized reading' (duration: 257.478422ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:31.281592Z","caller":"traceutil/trace.go:171","msg":"trace[1277777545] transaction","detail":"{read_only:false; response_revision:26220; number_of_response:1; }","duration":"112.487624ms","start":"2026-05-11T18:51:31.169075Z","end":"2026-05-11T18:51:31.281562Z","steps":["trace[1277777545] 'process raft request' (duration: 112.33123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:31.472534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.789073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T18:51:31.472609Z","caller":"traceutil/trace.go:171","msg":"trace[204741275] linearizableReadLoop","detail":"{readStateIndex:27089; appliedIndex:27087; }","duration":"228.416055ms","start":"2026-05-11T18:51:31.244182Z","end":"2026-05-11T18:51:31.472598Z","steps":["trace[204741275] 'read index received' (duration: 37.192213ms)","trace[204741275] 'applied index is now lower than readState.Index' (duration: 191.222972ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:51:31.472887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.412434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-05-11T18:51:31.472919Z","caller":"traceutil/trace.go:171","msg":"trace[1877839281] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26221; }","duration":"206.478906ms","start":"2026-05-11T18:51:31.26643Z","end":"2026-05-11T18:51:31.472909Z","steps":["trace[1877839281] 'agreement among raft nodes before linearized reading' (duration: 206.375643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:31.473206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.044274ms","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-11T18:51:31.473238Z","caller":"traceutil/trace.go:171","msg":"trace[1593424967] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26221; }","duration":"170.129696ms","start":"2026-05-11T18:51:31.303101Z","end":"2026-05-11T18:51:31.47323Z","steps":["trace[1593424967] 'agreement among raft nodes before linearized reading' (duration: 170.056324ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:31.473366Z","caller":"traceutil/trace.go:171","msg":"trace[1411475364] transaction","detail":"{read_only:false; response_revision:26221; number_of_response:1; }","duration":"253.42701ms","start":"2026-05-11T18:51:31.219887Z","end":"2026-05-11T18:51:31.473314Z","steps":["trace[1411475364] 'process raft request' (duration: 143.793665ms)","trace[1411475364] 'compare' (duration: 108.470162ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:51:31.47343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.223167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:51:31.473503Z","caller":"traceutil/trace.go:171","msg":"trace[915251862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26221; }","duration":"229.371982ms","start":"2026-05-11T18:51:31.244115Z","end":"2026-05-11T18:51:31.473487Z","steps":["trace[915251862] 'agreement among raft nodes before linearized reading' (duration: 229.206207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:31.473089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.698193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T18:51:31.473865Z","caller":"traceutil/trace.go:171","msg":"trace[1754192049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26221; }","duration":"166.499035ms","start":"2026-05-11T18:51:31.307356Z","end":"2026-05-11T18:51:31.473855Z","steps":["trace[1754192049] 'agreement among raft nodes before linearized reading' (duration: 165.692553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:31.717043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.150788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9547"} {"level":"info","ts":"2026-05-11T18:51:31.717178Z","caller":"traceutil/trace.go:171","msg":"trace[1708840329] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26224; }","duration":"142.341313ms","start":"2026-05-11T18:51:31.574813Z","end":"2026-05-11T18:51:31.717154Z","steps":["trace[1708840329] 'range keys from in-memory index tree' (duration: 141.962773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:32.012664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.718016ms","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-11T18:51:32.012727Z","caller":"traceutil/trace.go:171","msg":"trace[644859572] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26225; }","duration":"132.791748ms","start":"2026-05-11T18:51:31.879921Z","end":"2026-05-11T18:51:32.012713Z","steps":["trace[644859572] 'range keys from in-memory index tree' (duration: 132.571552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:32.01296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.063797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-11T18:51:32.01304Z","caller":"traceutil/trace.go:171","msg":"trace[965382458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26225; }","duration":"110.192032ms","start":"2026-05-11T18:51:31.902831Z","end":"2026-05-11T18:51:32.013023Z","steps":["trace[965382458] 'range keys from in-memory index tree' (duration: 109.83256ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:32.11243Z","caller":"traceutil/trace.go:171","msg":"trace[629131170] transaction","detail":"{read_only:false; response_revision:26226; number_of_response:1; }","duration":"101.271743ms","start":"2026-05-11T18:51:32.011129Z","end":"2026-05-11T18:51:32.112401Z","steps":["trace[629131170] 'process raft request' (duration: 101.063327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:32.212112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.750707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:51:32.212201Z","caller":"traceutil/trace.go:171","msg":"trace[615124399] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26226; }","duration":"195.898741ms","start":"2026-05-11T18:51:32.016279Z","end":"2026-05-11T18:51:32.212178Z","steps":["trace[615124399] 'agreement among raft nodes before linearized reading' (duration: 96.12356ms)","trace[615124399] 'range keys from in-memory index tree' (duration: 99.616027ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:32.212386Z","caller":"traceutil/trace.go:171","msg":"trace[222552707] transaction","detail":"{read_only:false; response_revision:26227; number_of_response:1; }","duration":"194.625476ms","start":"2026-05-11T18:51:32.017719Z","end":"2026-05-11T18:51:32.212344Z","steps":["trace[222552707] 'process raft request' (duration: 165.837485ms)","trace[222552707] 'compare' (duration: 28.447311ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:51:32.212605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.570902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-06f7b0e3-861e-48e1-aee9-b27d8acd4ec6\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-05-11T18:51:32.212727Z","caller":"traceutil/trace.go:171","msg":"trace[623014592] range","detail":"{range_begin:/registry/persistentvolumes/pvc-06f7b0e3-861e-48e1-aee9-b27d8acd4ec6; range_end:; response_count:1; response_revision:26227; }","duration":"195.732216ms","start":"2026-05-11T18:51:32.016964Z","end":"2026-05-11T18:51:32.212696Z","steps":["trace[623014592] 'agreement among raft nodes before linearized reading' (duration: 195.363916ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:32.727263Z","caller":"traceutil/trace.go:171","msg":"trace[712042210] transaction","detail":"{read_only:false; response_revision:26231; number_of_response:1; }","duration":"188.261099ms","start":"2026-05-11T18:51:32.538963Z","end":"2026-05-11T18:51:32.727224Z","steps":["trace[712042210] 'process raft request' (duration: 187.914139ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:32.727265Z","caller":"traceutil/trace.go:171","msg":"trace[1053416173] transaction","detail":"{read_only:false; response_revision:26229; number_of_response:1; }","duration":"190.561133ms","start":"2026-05-11T18:51:32.536661Z","end":"2026-05-11T18:51:32.727222Z","steps":["trace[1053416173] 'process raft request' (duration: 168.011317ms)","trace[1053416173] 'compare' (duration: 21.774645ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:32.727377Z","caller":"traceutil/trace.go:171","msg":"trace[1623344070] transaction","detail":"{read_only:false; response_revision:26232; number_of_response:1; }","duration":"187.800086ms","start":"2026-05-11T18:51:32.539545Z","end":"2026-05-11T18:51:32.727345Z","steps":["trace[1623344070] 'process raft request' (duration: 187.375755ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:32.727507Z","caller":"traceutil/trace.go:171","msg":"trace[2051681905] transaction","detail":"{read_only:false; response_revision:26230; number_of_response:1; }","duration":"188.581599ms","start":"2026-05-11T18:51:32.538917Z","end":"2026-05-11T18:51:32.727499Z","steps":["trace[2051681905] 'process raft request' (duration: 187.855078ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:32.727569Z","caller":"traceutil/trace.go:171","msg":"trace[1870770121] transaction","detail":"{read_only:false; response_revision:26233; number_of_response:1; }","duration":"185.333629ms","start":"2026-05-11T18:51:32.542231Z","end":"2026-05-11T18:51:32.727564Z","steps":["trace[1870770121] 'process raft request' (duration: 184.727932ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:33.745469Z","caller":"traceutil/trace.go:171","msg":"trace[95499595] transaction","detail":"{read_only:false; response_revision:26236; number_of_response:1; }","duration":"178.762956ms","start":"2026-05-11T18:51:33.566686Z","end":"2026-05-11T18:51:33.745449Z","steps":["trace[95499595] 'process raft request' (duration: 178.642083ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:33.780735Z","caller":"traceutil/trace.go:171","msg":"trace[707444798] transaction","detail":"{read_only:false; response_revision:26238; number_of_response:1; }","duration":"210.880019ms","start":"2026-05-11T18:51:33.569831Z","end":"2026-05-11T18:51:33.780711Z","steps":["trace[707444798] 'process raft request' (duration: 210.720393ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:33.781007Z","caller":"traceutil/trace.go:171","msg":"trace[1358319007] transaction","detail":"{read_only:false; response_revision:26237; number_of_response:1; }","duration":"212.514713ms","start":"2026-05-11T18:51:33.568479Z","end":"2026-05-11T18:51:33.780994Z","steps":["trace[1358319007] 'process raft request' (duration: 211.943687ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:34.688674Z","caller":"traceutil/trace.go:171","msg":"trace[47915738] transaction","detail":"{read_only:false; response_revision:26242; number_of_response:1; }","duration":"101.981823ms","start":"2026-05-11T18:51:34.586653Z","end":"2026-05-11T18:51:34.688634Z","steps":["trace[47915738] 'process raft request' (duration: 101.617122ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:34.869968Z","caller":"traceutil/trace.go:171","msg":"trace[1442254351] transaction","detail":"{read_only:false; response_revision:26248; number_of_response:1; }","duration":"135.900084ms","start":"2026-05-11T18:51:34.734045Z","end":"2026-05-11T18:51:34.869945Z","steps":["trace[1442254351] 'process raft request' (duration: 135.856323ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:34.870064Z","caller":"traceutil/trace.go:171","msg":"trace[856337412] transaction","detail":"{read_only:false; response_revision:26245; number_of_response:1; }","duration":"136.299785ms","start":"2026-05-11T18:51:34.733736Z","end":"2026-05-11T18:51:34.870036Z","steps":["trace[856337412] 'process raft request' (duration: 135.920404ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:34.870196Z","caller":"traceutil/trace.go:171","msg":"trace[1661235410] transaction","detail":"{read_only:false; response_revision:26247; number_of_response:1; }","duration":"136.124841ms","start":"2026-05-11T18:51:34.734021Z","end":"2026-05-11T18:51:34.870145Z","steps":["trace[1661235410] 'process raft request' (duration: 135.841153ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:34.87013Z","caller":"traceutil/trace.go:171","msg":"trace[2131462457] transaction","detail":"{read_only:false; response_revision:26246; number_of_response:1; }","duration":"136.193232ms","start":"2026-05-11T18:51:34.733823Z","end":"2026-05-11T18:51:34.870016Z","steps":["trace[2131462457] 'process raft request' (duration: 135.989636ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:38.807733Z","caller":"traceutil/trace.go:171","msg":"trace[235655521] transaction","detail":"{read_only:false; response_revision:26269; number_of_response:1; }","duration":"104.7743ms","start":"2026-05-11T18:51:38.702916Z","end":"2026-05-11T18:51:38.80769Z","steps":["trace[235655521] 'process raft request' (duration: 104.484862ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:52:10.686639Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006b1f.wal"} {"level":"info","ts":"2026-05-11T18:54:36.909724Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25110} {"level":"info","ts":"2026-05-11T18:54:37.05419Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25110,"took":"136.942073ms","hash":3495889432,"current-db-size-bytes":57122816,"current-db-size":"57 MB","current-db-size-in-use-bytes":37081088,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-11T18:54:37.054283Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3495889432,"revision":25110,"compact-revision":21943} {"level":"info","ts":"2026-05-11T18:55:10.002155Z","caller":"traceutil/trace.go:171","msg":"trace[1006359868] transaction","detail":"{read_only:false; response_revision:28355; number_of_response:1; }","duration":"101.781437ms","start":"2026-05-11T18:55:09.900346Z","end":"2026-05-11T18:55:10.002128Z","steps":["trace[1006359868] 'process raft request' (duration: 92.755267ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:55:10.014095Z","caller":"traceutil/trace.go:171","msg":"trace[1194213066] transaction","detail":"{read_only:false; response_revision:28356; number_of_response:1; }","duration":"113.158373ms","start":"2026-05-11T18:55:09.900926Z","end":"2026-05-11T18:55:10.014084Z","steps":["trace[1194213066] 'process raft request' (duration: 112.855585ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:55:10.01421Z","caller":"traceutil/trace.go:171","msg":"trace[1300723536] transaction","detail":"{read_only:false; response_revision:28357; number_of_response:1; }","duration":"113.152903ms","start":"2026-05-11T18:55:09.90105Z","end":"2026-05-11T18:55:10.014203Z","steps":["trace[1300723536] 'process raft request' (duration: 112.792012ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:56:26.830556Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2451868e402db19e","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T18:56:26.84311Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-11T18:56:26.843242Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-11T18:59:36.91691Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27953} {"level":"info","ts":"2026-05-11T18:59:37.055285Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27953,"took":"132.078068ms","hash":2515637734,"current-db-size-bytes":57122816,"current-db-size":"57 MB","current-db-size-in-use-bytes":32661504,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-11T18:59:37.055373Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2515637734,"revision":27953,"compact-revision":25110} {"level":"info","ts":"2026-05-11T19:04:36.924914Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30452} {"level":"info","ts":"2026-05-11T19:04:37.06295Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30452,"took":"133.511749ms","hash":463010221,"current-db-size-bytes":57122816,"current-db-size":"57 MB","current-db-size-in-use-bytes":30560256,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-11T19:04:37.063041Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":463010221,"revision":30452,"compact-revision":27953} {"level":"info","ts":"2026-05-11T19:06:14.530076Z","caller":"traceutil/trace.go:171","msg":"trace[26381957] transaction","detail":"{read_only:false; response_revision:33553; number_of_response:1; }","duration":"254.465019ms","start":"2026-05-11T19:06:14.275563Z","end":"2026-05-11T19:06:14.530028Z","steps":["trace[26381957] 'process raft request' (duration: 254.227443ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T19:09:36.932017Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32771} {"level":"info","ts":"2026-05-11T19:09:37.084156Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32771,"took":"142.413937ms","hash":1010380434,"current-db-size-bytes":57122816,"current-db-size":"57 MB","current-db-size-in-use-bytes":30482432,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-11T19:09:37.08422Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1010380434,"revision":32771,"compact-revision":30452} {"level":"info","ts":"2026-05-11T19:14:36.94332Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35087} {"level":"info","ts":"2026-05-11T19:14:37.093322Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35087,"took":"144.589377ms","hash":2867989830,"current-db-size-bytes":58425344,"current-db-size":"58 MB","current-db-size-in-use-bytes":31608832,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T19:14:37.093413Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2867989830,"revision":35087,"compact-revision":32771} {"level":"info","ts":"2026-05-11T19:17:45.5177Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2451868e402db19e","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T19:17:45.522759Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-11T19:17:45.522902Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-11T19:19:36.951225Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37326} {"level":"info","ts":"2026-05-11T19:19:37.076783Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37326,"took":"121.229287ms","hash":32207346,"current-db-size-bytes":60956672,"current-db-size":"61 MB","current-db-size-in-use-bytes":32407552,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T19:19:37.076862Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":32207346,"revision":37326,"compact-revision":35087}