{"level":"warn","ts":"2026-06-30T17:53:36.178075Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-30T17:53:36.178173Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-06-30T17:53:36.178278Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-30T17:53:36.178294Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-06-30T17:53:36.178318Z","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-06-30T17:53:36.179327Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-06-30T17:53:36.179406Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-06-30T17:53:36.183297Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.667329ms"} {"level":"info","ts":"2026-06-30T17:53:36.18865Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-06-30T17:53:36.18871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-06-30T17:53:36.188727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-06-30T17:53:36.188735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-30T17:53:36.188743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-06-30T17:53:36.188775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-06-30T17:53:36.194072Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-30T17:53:36.195588Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-30T17:53:36.196905Z","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-06-30T17:53:36.199118Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-30T17:53:36.199356Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-30T17:53:36.1997Z","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-06-30T17:53:36.199798Z","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-06-30T17:53:36.200012Z","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-06-30T17:53:36.202543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-06-30T17:53:36.202788Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-06-30T17:53:36.205507Z","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-06-30T17:53:36.20568Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-06-30T17:53:36.205854Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-06-30T17:53:36.206001Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-30T17:53:36.206088Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-30T17:53:36.289003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-06-30T17:53:36.289063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-30T17:53:36.289122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-06-30T17:53:36.289145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-06-30T17:53:36.289157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-06-30T17:53:36.289174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-06-30T17:53:36.289189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-06-30T17:53:36.290074Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T17:53:36.291125Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-30T17:53:36.291131Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-06-30T17:53:36.291166Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-30T17:53:36.291572Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-30T17:53:36.291615Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T17:53:36.291648Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-30T17:53:36.291758Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T17:53:36.291808Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T17:53:36.295339Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-06-30T17:53:36.295767Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-30T17:54:19.260705Z","caller":"traceutil/trace.go:171","msg":"trace[227387908] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"177.221613ms","start":"2026-06-30T17:54:19.08345Z","end":"2026-06-30T17:54:19.260672Z","steps":["trace[227387908] 'read index received' (duration: 88.570896ms)","trace[227387908] 'applied index is now lower than readState.Index' (duration: 88.649527ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:54:19.26082Z","caller":"traceutil/trace.go:171","msg":"trace[350778200] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"220.505872ms","start":"2026-06-30T17:54:19.040281Z","end":"2026-06-30T17:54:19.260787Z","steps":["trace[350778200] 'process raft request' (duration: 131.869775ms)","trace[350778200] 'compare' (duration: 88.242737ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:54:19.260978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.515891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-30T17:54:19.261056Z","caller":"traceutil/trace.go:171","msg":"trace[418586795] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"177.662344ms","start":"2026-06-30T17:54:19.08338Z","end":"2026-06-30T17:54:19.261043Z","steps":["trace[418586795] 'agreement among raft nodes before linearized reading' (duration: 177.415108ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:54:20.062268Z","caller":"traceutil/trace.go:171","msg":"trace[876353797] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"391.547017ms","start":"2026-06-30T17:54:19.670673Z","end":"2026-06-30T17:54:20.06222Z","steps":["trace[876353797] 'process raft request' (duration: 390.595901ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:54:20.062271Z","caller":"traceutil/trace.go:171","msg":"trace[1202666124] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"359.428442ms","start":"2026-06-30T17:54:19.702736Z","end":"2026-06-30T17:54:20.062164Z","steps":["trace[1202666124] 'read index received' (duration: 358.148057ms)","trace[1202666124] 'applied index is now lower than readState.Index' (duration: 1.279195ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:54:20.062608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.17932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-30T17:54:20.062668Z","caller":"traceutil/trace.go:171","msg":"trace[948875060] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:635; }","duration":"174.295923ms","start":"2026-06-30T17:54:19.888354Z","end":"2026-06-30T17:54:20.06265Z","steps":["trace[948875060] 'agreement among raft nodes before linearized reading' (duration: 174.159579ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:20.062688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.922985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7w8mg\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-30T17:54:20.062791Z","caller":"traceutil/trace.go:171","msg":"trace[926349515] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7w8mg; range_end:; response_count:1; response_revision:635; }","duration":"360.055658ms","start":"2026-06-30T17:54:19.702709Z","end":"2026-06-30T17:54:20.062765Z","steps":["trace[926349515] 'agreement among raft nodes before linearized reading' (duration: 359.670298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:20.062852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:54:19.70269Z","time spent":"360.151661ms","remote":"127.0.0.1:38014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-7w8mg\" "} {"level":"warn","ts":"2026-06-30T17:54:20.062916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:54:19.670616Z","time spent":"391.753713ms","remote":"127.0.0.1:37910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T17:55:21.221711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.684958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T17:55:21.221808Z","caller":"traceutil/trace.go:171","msg":"trace[650267395] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1437; }","duration":"143.800021ms","start":"2026-06-30T17:55:21.077987Z","end":"2026-06-30T17:55:21.221787Z","steps":["trace[650267395] 'count revisions from in-memory index tree' (duration: 143.591696ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:44.196494Z","caller":"traceutil/trace.go:171","msg":"trace[1447995200] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"232.054974ms","start":"2026-06-30T17:55:43.964365Z","end":"2026-06-30T17:55:44.19642Z","steps":["trace[1447995200] 'process raft request' (duration: 230.553702ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:44.308248Z","caller":"traceutil/trace.go:171","msg":"trace[1818591550] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"152.322633ms","start":"2026-06-30T17:55:44.155882Z","end":"2026-06-30T17:55:44.308204Z","steps":["trace[1818591550] 'process raft request' (duration: 152.270121ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:44.308281Z","caller":"traceutil/trace.go:171","msg":"trace[1307210616] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"153.99262ms","start":"2026-06-30T17:55:44.154251Z","end":"2026-06-30T17:55:44.308244Z","steps":["trace[1307210616] 'process raft request' (duration: 153.664811ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:44.375699Z","caller":"traceutil/trace.go:171","msg":"trace[1925305274] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"169.544901ms","start":"2026-06-30T17:55:44.206117Z","end":"2026-06-30T17:55:44.375662Z","steps":["trace[1925305274] 'process raft request' (duration: 169.358515ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:57.780569Z","caller":"traceutil/trace.go:171","msg":"trace[1467758712] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"125.632623ms","start":"2026-06-30T17:55:57.654904Z","end":"2026-06-30T17:55:57.780537Z","steps":["trace[1467758712] 'process raft request' (duration: 125.466139ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:28.117149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.585107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:56:28.117312Z","caller":"traceutil/trace.go:171","msg":"trace[1330057969] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"171.829894ms","start":"2026-06-30T17:56:27.945452Z","end":"2026-06-30T17:56:28.117282Z","steps":["trace[1330057969] 'process raft request' (duration: 47.906268ms)","trace[1330057969] 'compare' (duration: 123.394421ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:56:34.255587Z","caller":"traceutil/trace.go:171","msg":"trace[384052078] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"112.462418ms","start":"2026-06-30T17:56:34.143098Z","end":"2026-06-30T17:56:34.25556Z","steps":["trace[384052078] 'process raft request' (duration: 112.087908ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:01:46.059739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.289467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2pb79\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-30T18:01:46.059822Z","caller":"traceutil/trace.go:171","msg":"trace[1046714212] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2pb79; range_end:; response_count:1; response_revision:4838; }","duration":"101.434001ms","start":"2026-06-30T18:01:45.95837Z","end":"2026-06-30T18:01:46.059804Z","steps":["trace[1046714212] 'range keys from in-memory index tree' (duration: 101.031659ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:02:18.30449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.182802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-06-30T18:02:18.304593Z","caller":"traceutil/trace.go:171","msg":"trace[189841975] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5335; }","duration":"160.329827ms","start":"2026-06-30T18:02:18.144242Z","end":"2026-06-30T18:02:18.304572Z","steps":["trace[189841975] 'range keys from in-memory index tree' (duration: 159.951225ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:02:18.487786Z","caller":"traceutil/trace.go:171","msg":"trace[1634229440] linearizableReadLoop","detail":"{readStateIndex:5526; appliedIndex:5525; }","duration":"173.096623ms","start":"2026-06-30T18:02:18.314659Z","end":"2026-06-30T18:02:18.487756Z","steps":["trace[1634229440] 'read index received' (duration: 172.877477ms)","trace[1634229440] 'applied index is now lower than readState.Index' (duration: 217.666µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:02:18.487981Z","caller":"traceutil/trace.go:171","msg":"trace[1054580312] transaction","detail":"{read_only:false; response_revision:5336; number_of_response:1; }","duration":"173.423602ms","start":"2026-06-30T18:02:18.314542Z","end":"2026-06-30T18:02:18.487966Z","steps":["trace[1054580312] 'process raft request' (duration: 173.029951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:02:18.488243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.404354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T18:02:18.488316Z","caller":"traceutil/trace.go:171","msg":"trace[491466503] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:5336; }","duration":"109.563499ms","start":"2026-06-30T18:02:18.378736Z","end":"2026-06-30T18:02:18.488299Z","steps":["trace[491466503] 'agreement among raft nodes before linearized reading' (duration: 109.346793ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:02:18.488758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.08682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-06-30T18:02:18.488818Z","caller":"traceutil/trace.go:171","msg":"trace[1139614648] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:5336; }","duration":"174.175532ms","start":"2026-06-30T18:02:18.31463Z","end":"2026-06-30T18:02:18.488805Z","steps":["trace[1139614648] 'agreement among raft nodes before linearized reading' (duration: 173.583286ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:02:59.730695Z","caller":"traceutil/trace.go:171","msg":"trace[933197320] transaction","detail":"{read_only:false; response_revision:5698; number_of_response:1; }","duration":"100.543763ms","start":"2026-06-30T18:02:59.630128Z","end":"2026-06-30T18:02:59.730672Z","steps":["trace[933197320] 'process raft request' (duration: 100.385978ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:03:36.787162Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2865} {"level":"info","ts":"2026-06-30T18:03:36.852796Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2865,"took":"64.085692ms","hash":1908445940,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":33775616,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-30T18:03:36.852848Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1908445940,"revision":2865,"compact-revision":-1} {"level":"info","ts":"2026-06-30T18:07:57.372139Z","caller":"traceutil/trace.go:171","msg":"trace[1771433420] linearizableReadLoop","detail":"{readStateIndex:8216; appliedIndex:8215; }","duration":"131.202005ms","start":"2026-06-30T18:07:57.240913Z","end":"2026-06-30T18:07:57.372115Z","steps":["trace[1771433420] 'read index received' (duration: 131.02511ms)","trace[1771433420] 'applied index is now lower than readState.Index' (duration: 175.755µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:07:57.372247Z","caller":"traceutil/trace.go:171","msg":"trace[393960097] transaction","detail":"{read_only:false; response_revision:7942; number_of_response:1; }","duration":"132.11499ms","start":"2026-06-30T18:07:57.240103Z","end":"2026-06-30T18:07:57.372218Z","steps":["trace[393960097] 'process raft request' (duration: 131.840613ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:07:57.372386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.457612ms","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-06-30T18:07:57.372424Z","caller":"traceutil/trace.go:171","msg":"trace[1686360095] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7942; }","duration":"131.544815ms","start":"2026-06-30T18:07:57.240869Z","end":"2026-06-30T18:07:57.372413Z","steps":["trace[1686360095] 'agreement among raft nodes before linearized reading' (duration: 131.35162ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:07:57.521396Z","caller":"traceutil/trace.go:171","msg":"trace[1410460854] transaction","detail":"{read_only:false; response_revision:7943; number_of_response:1; }","duration":"145.266848ms","start":"2026-06-30T18:07:57.376103Z","end":"2026-06-30T18:07:57.52137Z","steps":["trace[1410460854] 'process raft request' (duration: 88.222214ms)","trace[1410460854] 'compare' (duration: 56.802287ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:08:08.658061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.818289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-30T18:08:08.658075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.163806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T18:08:08.658145Z","caller":"traceutil/trace.go:171","msg":"trace[210774069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7996; }","duration":"113.968654ms","start":"2026-06-30T18:08:08.544157Z","end":"2026-06-30T18:08:08.658125Z","steps":["trace[210774069] 'range keys from in-memory index tree' (duration: 113.662775ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:08:08.658162Z","caller":"traceutil/trace.go:171","msg":"trace[1255051245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7996; }","duration":"120.31494ms","start":"2026-06-30T18:08:08.537829Z","end":"2026-06-30T18:08:08.658144Z","steps":["trace[1255051245] 'range keys from in-memory index tree' (duration: 120.070684ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:08:08.658183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.073805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-30T18:08:08.658061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.639955ms","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-06-30T18:08:08.65825Z","caller":"traceutil/trace.go:171","msg":"trace[828999456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7996; }","duration":"115.189138ms","start":"2026-06-30T18:08:08.543043Z","end":"2026-06-30T18:08:08.658232Z","steps":["trace[828999456] 'range keys from in-memory index tree' (duration: 114.89902ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:08:08.658256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.601935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-06-30T18:08:08.658276Z","caller":"traceutil/trace.go:171","msg":"trace[1261212214] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7996; }","duration":"132.893451ms","start":"2026-06-30T18:08:08.525359Z","end":"2026-06-30T18:08:08.658252Z","steps":["trace[1261212214] 'range keys from in-memory index tree' (duration: 132.47329ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:08:08.658332Z","caller":"traceutil/trace.go:171","msg":"trace[7740904] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7996; }","duration":"112.684258ms","start":"2026-06-30T18:08:08.545618Z","end":"2026-06-30T18:08:08.658302Z","steps":["trace[7740904] 'range keys from in-memory index tree' (duration: 112.445071ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:08:08.827951Z","caller":"traceutil/trace.go:171","msg":"trace[1576502079] transaction","detail":"{read_only:false; response_revision:7998; number_of_response:1; }","duration":"165.77446ms","start":"2026-06-30T18:08:08.662157Z","end":"2026-06-30T18:08:08.827931Z","steps":["trace[1576502079] 'process raft request' (duration: 165.630426ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:08:08.828153Z","caller":"traceutil/trace.go:171","msg":"trace[528501194] transaction","detail":"{read_only:false; response_revision:7999; number_of_response:1; }","duration":"165.896104ms","start":"2026-06-30T18:08:08.662247Z","end":"2026-06-30T18:08:08.828143Z","steps":["trace[528501194] 'process raft request' (duration: 165.640177ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:08:08.828867Z","caller":"traceutil/trace.go:171","msg":"trace[699986617] transaction","detail":"{read_only:false; response_revision:8000; number_of_response:1; }","duration":"166.432778ms","start":"2026-06-30T18:08:08.662401Z","end":"2026-06-30T18:08:08.828834Z","steps":["trace[699986617] 'process raft request' (duration: 165.701718ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:08:09.551535Z","caller":"traceutil/trace.go:171","msg":"trace[1123908471] transaction","detail":"{read_only:false; response_revision:8003; number_of_response:1; }","duration":"123.693564ms","start":"2026-06-30T18:08:09.427811Z","end":"2026-06-30T18:08:09.551505Z","steps":["trace[1123908471] 'process raft request' (duration: 123.490438ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:08:36.186916Z","caller":"traceutil/trace.go:171","msg":"trace[1972109955] transaction","detail":"{read_only:false; response_revision:8198; number_of_response:1; }","duration":"183.976393ms","start":"2026-06-30T18:08:36.002921Z","end":"2026-06-30T18:08:36.186898Z","steps":["trace[1972109955] 'process raft request' (duration: 183.85346ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:08:36.793103Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5985} {"level":"info","ts":"2026-06-30T18:08:36.884458Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5985,"took":"89.97893ms","hash":3699995245,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":22700032,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-06-30T18:08:36.884556Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3699995245,"revision":5985,"compact-revision":2865} {"level":"info","ts":"2026-06-30T18:08:49.864854Z","caller":"traceutil/trace.go:171","msg":"trace[702104838] linearizableReadLoop","detail":"{readStateIndex:8604; appliedIndex:8603; }","duration":"116.934582ms","start":"2026-06-30T18:08:49.747899Z","end":"2026-06-30T18:08:49.864834Z","steps":["trace[702104838] 'read index received' (duration: 116.745667ms)","trace[702104838] 'applied index is now lower than readState.Index' (duration: 187.975µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:08:49.864978Z","caller":"traceutil/trace.go:171","msg":"trace[1714829334] transaction","detail":"{read_only:false; response_revision:8315; number_of_response:1; }","duration":"158.920804ms","start":"2026-06-30T18:08:49.706047Z","end":"2026-06-30T18:08:49.864967Z","steps":["trace[1714829334] 'process raft request' (duration: 158.647496ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:08:49.865326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.420225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-30T18:08:49.86538Z","caller":"traceutil/trace.go:171","msg":"trace[779598458] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8315; }","duration":"117.524859ms","start":"2026-06-30T18:08:49.747844Z","end":"2026-06-30T18:08:49.865369Z","steps":["trace[779598458] 'agreement among raft nodes before linearized reading' (duration: 117.372495ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:09:33.821016Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000237a.wal"} {"level":"info","ts":"2026-06-30T18:10:42.461319Z","caller":"traceutil/trace.go:171","msg":"trace[1206546448] transaction","detail":"{read_only:false; response_revision:9370; number_of_response:1; }","duration":"100.682954ms","start":"2026-06-30T18:10:42.360611Z","end":"2026-06-30T18:10:42.461294Z","steps":["trace[1206546448] 'process raft request' (duration: 92.937768ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:11:21.855944Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e46287fb5aa4785","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-30T18:11:21.861358Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-30T18:11:21.861458Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-30T18:13:36.801209Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8200} {"level":"info","ts":"2026-06-30T18:13:36.880552Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8200,"took":"76.806812ms","hash":3875695465,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":26685440,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-30T18:13:36.880633Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3875695465,"revision":8200,"compact-revision":5985} {"level":"info","ts":"2026-06-30T18:18:36.807207Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10879} {"level":"info","ts":"2026-06-30T18:18:36.893928Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10879,"took":"84.477343ms","hash":3318822865,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":27832320,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-30T18:18:36.89405Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3318822865,"revision":10879,"compact-revision":8200} {"level":"warn","ts":"2026-06-30T18:19:53.276037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.655285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-30T18:19:53.276126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.302274ms","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-06-30T18:19:53.276129Z","caller":"traceutil/trace.go:171","msg":"trace[1189595012] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13430; }","duration":"156.794969ms","start":"2026-06-30T18:19:53.119314Z","end":"2026-06-30T18:19:53.276109Z","steps":["trace[1189595012] 'range keys from in-memory index tree' (duration: 156.46128ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:19:53.276169Z","caller":"traceutil/trace.go:171","msg":"trace[1686540547] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13430; }","duration":"111.345615ms","start":"2026-06-30T18:19:53.164812Z","end":"2026-06-30T18:19:53.276158Z","steps":["trace[1686540547] 'range keys from in-memory index tree' (duration: 111.163801ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:19:53.276241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.809671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2pb79\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-06-30T18:19:53.276268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.208265ms","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-06-30T18:19:53.276362Z","caller":"traceutil/trace.go:171","msg":"trace[35538906] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:13430; }","duration":"146.351949ms","start":"2026-06-30T18:19:53.129991Z","end":"2026-06-30T18:19:53.276343Z","steps":["trace[35538906] 'count revisions from in-memory index tree' (duration: 146.112502ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:19:53.276305Z","caller":"traceutil/trace.go:171","msg":"trace[1628532191] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2pb79; range_end:; response_count:1; response_revision:13430; }","duration":"178.921244ms","start":"2026-06-30T18:19:53.097368Z","end":"2026-06-30T18:19:53.27629Z","steps":["trace[1628532191] 'range keys from in-memory index tree' (duration: 178.668537ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:20:13.955842Z","caller":"traceutil/trace.go:171","msg":"trace[511676458] linearizableReadLoop","detail":"{readStateIndex:14199; appliedIndex:14198; }","duration":"128.012259ms","start":"2026-06-30T18:20:13.827812Z","end":"2026-06-30T18:20:13.955825Z","steps":["trace[511676458] 'read index received' (duration: 127.791273ms)","trace[511676458] 'applied index is now lower than readState.Index' (duration: 220.186µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:20:13.955911Z","caller":"traceutil/trace.go:171","msg":"trace[293728540] transaction","detail":"{read_only:false; response_revision:13736; number_of_response:1; }","duration":"144.112716ms","start":"2026-06-30T18:20:13.811766Z","end":"2026-06-30T18:20:13.955879Z","steps":["trace[293728540] 'process raft request' (duration: 143.89541ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:20:13.956017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.184923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-06-30T18:20:13.956047Z","caller":"traceutil/trace.go:171","msg":"trace[1637578969] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:13736; }","duration":"128.271116ms","start":"2026-06-30T18:20:13.827767Z","end":"2026-06-30T18:20:13.956038Z","steps":["trace[1637578969] 'agreement among raft nodes before linearized reading' (duration: 128.130872ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:21:38.550944Z","caller":"traceutil/trace.go:171","msg":"trace[1748780070] transaction","detail":"{read_only:false; response_revision:14531; number_of_response:1; }","duration":"107.031365ms","start":"2026-06-30T18:21:38.443881Z","end":"2026-06-30T18:21:38.550912Z","steps":["trace[1748780070] 'process raft request' (duration: 106.424098ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:21:38.768327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.938615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T18:21:38.768392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.826732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-06-30T18:21:38.768492Z","caller":"traceutil/trace.go:171","msg":"trace[65571686] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:14531; }","duration":"105.952196ms","start":"2026-06-30T18:21:38.662517Z","end":"2026-06-30T18:21:38.768469Z","steps":["trace[65571686] 'range keys from in-memory index tree' (duration: 105.645377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:21:38.768496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.672148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-06-30T18:21:38.768421Z","caller":"traceutil/trace.go:171","msg":"trace[897908952] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:14531; }","duration":"106.083519ms","start":"2026-06-30T18:21:38.66231Z","end":"2026-06-30T18:21:38.768394Z","steps":["trace[897908952] 'range keys from in-memory index tree' (duration: 105.823092ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:21:38.768571Z","caller":"traceutil/trace.go:171","msg":"trace[1395919475] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14531; }","duration":"105.77357ms","start":"2026-06-30T18:21:38.66278Z","end":"2026-06-30T18:21:38.768553Z","steps":["trace[1395919475] 'range keys from in-memory index tree' (duration: 105.528744ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:21:38.768556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.154021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T18:21:38.768327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.532762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-06-30T18:21:38.768635Z","caller":"traceutil/trace.go:171","msg":"trace[1837488510] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14531; }","duration":"106.253134ms","start":"2026-06-30T18:21:38.662365Z","end":"2026-06-30T18:21:38.768619Z","steps":["trace[1837488510] 'range keys from in-memory index tree' (duration: 106.071419ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:21:38.768706Z","caller":"traceutil/trace.go:171","msg":"trace[1242795960] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14531; }","duration":"106.943574ms","start":"2026-06-30T18:21:38.661729Z","end":"2026-06-30T18:21:38.768673Z","steps":["trace[1242795960] 'range keys from in-memory index tree' (duration: 106.365147ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:23:36.817129Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12941} {"level":"info","ts":"2026-06-30T18:23:36.896648Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12941,"took":"77.030321ms","hash":2857137135,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":31174656,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-30T18:23:36.896702Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2857137135,"revision":12941,"compact-revision":10879} {"level":"info","ts":"2026-06-30T18:26:47.933825Z","caller":"traceutil/trace.go:171","msg":"trace[2143047111] transaction","detail":"{read_only:false; response_revision:16915; number_of_response:1; }","duration":"208.972263ms","start":"2026-06-30T18:26:47.724826Z","end":"2026-06-30T18:26:47.933799Z","steps":["trace[2143047111] 'process raft request' (duration: 208.828059ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:26:48.229814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.986968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-06-30T18:26:48.2299Z","caller":"traceutil/trace.go:171","msg":"trace[731256057] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:16915; }","duration":"104.119241ms","start":"2026-06-30T18:26:48.125762Z","end":"2026-06-30T18:26:48.229882Z","steps":["trace[731256057] 'range keys from in-memory index tree' (duration: 103.851184ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:26:48.229818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.165784ms","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-06-30T18:26:48.230047Z","caller":"traceutil/trace.go:171","msg":"trace[1961605437] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:16915; }","duration":"215.441451ms","start":"2026-06-30T18:26:48.014588Z","end":"2026-06-30T18:26:48.230029Z","steps":["trace[1961605437] 'range keys from in-memory index tree' (duration: 215.072971ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:26:48.360694Z","caller":"traceutil/trace.go:171","msg":"trace[1192360905] transaction","detail":"{read_only:false; response_revision:16916; number_of_response:1; }","duration":"127.119037ms","start":"2026-06-30T18:26:48.233547Z","end":"2026-06-30T18:26:48.360666Z","steps":["trace[1192360905] 'process raft request' (duration: 126.947012ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:26:48.376838Z","caller":"traceutil/trace.go:171","msg":"trace[1721816047] linearizableReadLoop","detail":"{readStateIndex:17481; appliedIndex:17479; }","duration":"140.899969ms","start":"2026-06-30T18:26:48.235915Z","end":"2026-06-30T18:26:48.376815Z","steps":["trace[1721816047] 'read index received' (duration: 124.522456ms)","trace[1721816047] 'applied index is now lower than readState.Index' (duration: 16.376613ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:26:48.376941Z","caller":"traceutil/trace.go:171","msg":"trace[43190588] transaction","detail":"{read_only:false; response_revision:16917; number_of_response:1; }","duration":"141.801374ms","start":"2026-06-30T18:26:48.23513Z","end":"2026-06-30T18:26:48.376932Z","steps":["trace[43190588] 'process raft request' (duration: 141.571638ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:26:48.377176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.247188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-06-30T18:26:48.377218Z","caller":"traceutil/trace.go:171","msg":"trace[403305300] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16917; }","duration":"141.334791ms","start":"2026-06-30T18:26:48.235872Z","end":"2026-06-30T18:26:48.377207Z","steps":["trace[403305300] 'agreement among raft nodes before linearized reading' (duration: 141.181686ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:28:36.82359Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15453} {"level":"info","ts":"2026-06-30T18:28:36.924886Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15453,"took":"98.008245ms","hash":4160490915,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":33157120,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-30T18:28:36.924947Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4160490915,"revision":15453,"compact-revision":12941} {"level":"info","ts":"2026-06-30T18:31:15.828294Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e46287fb5aa4785","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-30T18:31:15.834426Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-30T18:31:15.834557Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-30T18:33:36.82937Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17961} {"level":"info","ts":"2026-06-30T18:33:36.940441Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17961,"took":"107.50434ms","hash":3079876698,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":31416320,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-30T18:33:36.940514Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3079876698,"revision":17961,"compact-revision":15453} {"level":"info","ts":"2026-06-30T18:34:02.586179Z","caller":"traceutil/trace.go:171","msg":"trace[717466635] transaction","detail":"{read_only:false; response_revision:20566; number_of_response:1; }","duration":"130.95382ms","start":"2026-06-30T18:34:02.455198Z","end":"2026-06-30T18:34:02.586152Z","steps":["trace[717466635] 'process raft request' (duration: 130.653032ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:34:02.587492Z","caller":"traceutil/trace.go:171","msg":"trace[212750081] transaction","detail":"{read_only:false; response_revision:20567; number_of_response:1; }","duration":"130.703113ms","start":"2026-06-30T18:34:02.456745Z","end":"2026-06-30T18:34:02.587448Z","steps":["trace[212750081] 'process raft request' (duration: 130.618711ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:34:07.98479Z","caller":"traceutil/trace.go:171","msg":"trace[1429277575] transaction","detail":"{read_only:false; response_revision:20592; number_of_response:1; }","duration":"160.117678ms","start":"2026-06-30T18:34:07.824643Z","end":"2026-06-30T18:34:07.984761Z","steps":["trace[1429277575] 'process raft request' (duration: 159.967064ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:34:08.498355Z","caller":"traceutil/trace.go:171","msg":"trace[636653813] transaction","detail":"{read_only:false; response_revision:20593; number_of_response:1; }","duration":"115.09405ms","start":"2026-06-30T18:34:08.38324Z","end":"2026-06-30T18:34:08.498334Z","steps":["trace[636653813] 'process raft request' (duration: 114.954906ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:34:08.939031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.992537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:34:08.93965Z","caller":"traceutil/trace.go:171","msg":"trace[1010169598] linearizableReadLoop","detail":"{readStateIndex:21273; appliedIndex:21272; }","duration":"247.700516ms","start":"2026-06-30T18:34:08.691924Z","end":"2026-06-30T18:34:08.939624Z","steps":["trace[1010169598] 'read index received' (duration: 100.970089ms)","trace[1010169598] 'applied index is now lower than readState.Index' (duration: 146.728637ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:34:08.940083Z","caller":"traceutil/trace.go:171","msg":"trace[52268349] transaction","detail":"{read_only:false; response_revision:20595; number_of_response:1; }","duration":"284.211678ms","start":"2026-06-30T18:34:08.655837Z","end":"2026-06-30T18:34:08.940049Z","steps":["trace[52268349] 'process raft request' (duration: 137.10073ms)","trace[52268349] 'compare' (duration: 145.819372ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:34:08.940191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.409178ms","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-06-30T18:34:08.940232Z","caller":"traceutil/trace.go:171","msg":"trace[2028596276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20595; }","duration":"247.47305ms","start":"2026-06-30T18:34:08.692747Z","end":"2026-06-30T18:34:08.94022Z","steps":["trace[2028596276] 'agreement among raft nodes before linearized reading' (duration: 247.307975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:34:08.940282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.490905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T18:34:08.940377Z","caller":"traceutil/trace.go:171","msg":"trace[780916106] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:20595; }","duration":"140.624459ms","start":"2026-06-30T18:34:08.799729Z","end":"2026-06-30T18:34:08.940353Z","steps":["trace[780916106] 'agreement among raft nodes before linearized reading' (duration: 140.352701ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:34:08.940354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.418436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-30T18:34:08.94039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.278915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-30T18:34:08.94049Z","caller":"traceutil/trace.go:171","msg":"trace[665180487] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20595; }","duration":"229.412779ms","start":"2026-06-30T18:34:08.711068Z","end":"2026-06-30T18:34:08.940481Z","steps":["trace[665180487] 'agreement among raft nodes before linearized reading' (duration: 229.276025ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:34:08.940457Z","caller":"traceutil/trace.go:171","msg":"trace[10093068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20595; }","duration":"248.55181ms","start":"2026-06-30T18:34:08.691887Z","end":"2026-06-30T18:34:08.940439Z","steps":["trace[10093068] 'agreement among raft nodes before linearized reading' (duration: 248.19638ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:34:09.357625Z","caller":"traceutil/trace.go:171","msg":"trace[935481233] linearizableReadLoop","detail":"{readStateIndex:21277; appliedIndex:21274; }","duration":"399.707379ms","start":"2026-06-30T18:34:08.957892Z","end":"2026-06-30T18:34:09.357599Z","steps":["trace[935481233] 'read index received' (duration: 77.097227ms)","trace[935481233] 'applied index is now lower than readState.Index' (duration: 322.609002ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:34:09.357713Z","caller":"traceutil/trace.go:171","msg":"trace[1871731847] transaction","detail":"{read_only:false; response_revision:20597; number_of_response:1; }","duration":"411.047664ms","start":"2026-06-30T18:34:08.946656Z","end":"2026-06-30T18:34:09.357704Z","steps":["trace[1871731847] 'process raft request' (duration: 410.653893ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:34:09.357797Z","caller":"traceutil/trace.go:171","msg":"trace[526919129] transaction","detail":"{read_only:false; response_revision:20599; number_of_response:1; }","duration":"399.994097ms","start":"2026-06-30T18:34:08.957788Z","end":"2026-06-30T18:34:09.357782Z","steps":["trace[526919129] 'process raft request' (duration: 399.767261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:34:09.35784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:34:08.946622Z","time spent":"411.132086ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:34:09.35765Z","caller":"traceutil/trace.go:171","msg":"trace[1786594132] transaction","detail":"{read_only:false; response_revision:20598; number_of_response:1; }","duration":"408.805142ms","start":"2026-06-30T18:34:08.94881Z","end":"2026-06-30T18:34:09.357615Z","steps":["trace[1786594132] 'process raft request' (duration: 408.701819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:34:09.358166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:34:08.948794Z","time spent":"409.273495ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:34:09.358157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:34:08.957771Z","time spent":"400.06515ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:34:09.358419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.516882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:34:09.35848Z","caller":"traceutil/trace.go:171","msg":"trace[2107041978] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:20599; }","duration":"400.595234ms","start":"2026-06-30T18:34:08.957864Z","end":"2026-06-30T18:34:09.358459Z","steps":["trace[2107041978] 'agreement among raft nodes before linearized reading' (duration: 400.505282ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:34:09.358615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:34:08.957852Z","time spent":"400.708827ms","remote":"127.0.0.1:41010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"warn","ts":"2026-06-30T18:34:09.596868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.577716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:34:09.597047Z","caller":"traceutil/trace.go:171","msg":"trace[862349417] linearizableReadLoop","detail":"{readStateIndex:21280; appliedIndex:21277; }","duration":"152.834276ms","start":"2026-06-30T18:34:09.444199Z","end":"2026-06-30T18:34:09.597033Z","steps":["trace[862349417] 'read index received' (duration: 82.445605ms)","trace[862349417] 'applied index is now lower than readState.Index' (duration: 70.387841ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:34:09.597028Z","caller":"traceutil/trace.go:171","msg":"trace[810059604] transaction","detail":"{read_only:false; response_revision:20600; number_of_response:1; }","duration":"235.097156ms","start":"2026-06-30T18:34:09.361906Z","end":"2026-06-30T18:34:09.597004Z","steps":["trace[810059604] 'process raft request' (duration: 81.317054ms)","trace[810059604] 'compare' (duration: 153.34817ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:34:09.597236Z","caller":"traceutil/trace.go:171","msg":"trace[566818138] transaction","detail":"{read_only:false; response_revision:20601; number_of_response:1; }","duration":"234.195281ms","start":"2026-06-30T18:34:09.363033Z","end":"2026-06-30T18:34:09.597228Z","steps":["trace[566818138] 'process raft request' (duration: 233.909884ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:34:09.597374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.156655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T18:34:09.597405Z","caller":"traceutil/trace.go:171","msg":"trace[739440530] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20602; }","duration":"153.234057ms","start":"2026-06-30T18:34:09.444161Z","end":"2026-06-30T18:34:09.597395Z","steps":["trace[739440530] 'agreement among raft nodes before linearized reading' (duration: 153.166055ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:34:09.59741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.225995ms","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-06-30T18:34:09.597445Z","caller":"traceutil/trace.go:171","msg":"trace[1877061352] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:20602; }","duration":"119.288617ms","start":"2026-06-30T18:34:09.478137Z","end":"2026-06-30T18:34:09.597425Z","steps":["trace[1877061352] 'agreement among raft nodes before linearized reading' (duration: 119.245186ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:34:09.59752Z","caller":"traceutil/trace.go:171","msg":"trace[468078929] transaction","detail":"{read_only:false; response_revision:20602; number_of_response:1; }","duration":"218.765604ms","start":"2026-06-30T18:34:09.378744Z","end":"2026-06-30T18:34:09.59751Z","steps":["trace[468078929] 'process raft request' (duration: 218.25827ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:37:45.715312Z","caller":"traceutil/trace.go:171","msg":"trace[1810142191] transaction","detail":"{read_only:false; response_revision:22980; number_of_response:1; }","duration":"150.722338ms","start":"2026-06-30T18:37:45.564523Z","end":"2026-06-30T18:37:45.715245Z","steps":["trace[1810142191] 'process raft request' (duration: 149.556296ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:38:36.837301Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20435} {"level":"info","ts":"2026-06-30T18:38:36.947402Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20435,"took":"103.520099ms","hash":1267197082,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":44707840,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-06-30T18:38:36.949018Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1267197082,"revision":20435,"compact-revision":17961} {"level":"info","ts":"2026-06-30T18:40:42.147889Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006427.wal"} {"level":"info","ts":"2026-06-30T18:40:53.20468Z","caller":"traceutil/trace.go:171","msg":"trace[662115911] transaction","detail":"{read_only:false; response_revision:24985; number_of_response:1; }","duration":"136.145495ms","start":"2026-06-30T18:40:53.068512Z","end":"2026-06-30T18:40:53.204658Z","steps":["trace[662115911] 'process raft request' (duration: 136.014402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:40:53.853588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.452465ms","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-06-30T18:40:53.853643Z","caller":"traceutil/trace.go:171","msg":"trace[1498637352] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24990; }","duration":"299.550407ms","start":"2026-06-30T18:40:53.55408Z","end":"2026-06-30T18:40:53.85363Z","steps":["trace[1498637352] 'range keys from in-memory index tree' (duration: 299.321741ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:40:54.108633Z","caller":"traceutil/trace.go:171","msg":"trace[464922217] transaction","detail":"{read_only:false; response_revision:24991; number_of_response:1; }","duration":"252.02172ms","start":"2026-06-30T18:40:53.856587Z","end":"2026-06-30T18:40:54.108609Z","steps":["trace[464922217] 'process raft request' (duration: 251.866986ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:40:54.111122Z","caller":"traceutil/trace.go:171","msg":"trace[1291117540] transaction","detail":"{read_only:false; response_revision:24992; number_of_response:1; }","duration":"207.503334ms","start":"2026-06-30T18:40:53.903581Z","end":"2026-06-30T18:40:54.111084Z","steps":["trace[1291117540] 'process raft request' (duration: 207.157395ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:40:54.111228Z","caller":"traceutil/trace.go:171","msg":"trace[1093394552] linearizableReadLoop","detail":"{readStateIndex:25788; appliedIndex:25786; }","duration":"186.484522ms","start":"2026-06-30T18:40:53.924498Z","end":"2026-06-30T18:40:54.110983Z","steps":["trace[1093394552] 'read index received' (duration: 183.956912ms)","trace[1093394552] 'applied index is now lower than readState.Index' (duration: 2.52588ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:40:54.111347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.836342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:40:54.111425Z","caller":"traceutil/trace.go:171","msg":"trace[344695464] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24992; }","duration":"186.979846ms","start":"2026-06-30T18:40:53.924433Z","end":"2026-06-30T18:40:54.111413Z","steps":["trace[344695464] 'agreement among raft nodes before linearized reading' (duration: 186.851432ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:40:58.543705Z","caller":"traceutil/trace.go:171","msg":"trace[1002570030] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"125.083299ms","start":"2026-06-30T18:40:58.418596Z","end":"2026-06-30T18:40:58.543679Z","steps":["trace[1002570030] 'process raft request' (duration: 124.75369ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:40:58.573407Z","caller":"traceutil/trace.go:171","msg":"trace[468358735] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"134.769018ms","start":"2026-06-30T18:40:58.43861Z","end":"2026-06-30T18:40:58.573379Z","steps":["trace[468358735] 'process raft request' (duration: 134.538091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:40:58.735246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.485537ms","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-06-30T18:40:58.735332Z","caller":"traceutil/trace.go:171","msg":"trace[661755820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25026; }","duration":"100.627131ms","start":"2026-06-30T18:40:58.634684Z","end":"2026-06-30T18:40:58.735311Z","steps":["trace[661755820] 'range keys from in-memory index tree' (duration: 100.24216ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:40:58.855762Z","caller":"traceutil/trace.go:171","msg":"trace[1396086401] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"116.312646ms","start":"2026-06-30T18:40:58.739423Z","end":"2026-06-30T18:40:58.855735Z","steps":["trace[1396086401] 'process raft request' (duration: 116.133691ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:40:59.26539Z","caller":"traceutil/trace.go:171","msg":"trace[1652144237] linearizableReadLoop","detail":"{readStateIndex:25825; appliedIndex:25824; }","duration":"273.746202ms","start":"2026-06-30T18:40:58.991614Z","end":"2026-06-30T18:40:59.26536Z","steps":["trace[1652144237] 'read index received' (duration: 273.568117ms)","trace[1652144237] 'applied index is now lower than readState.Index' (duration: 176.665µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:40:59.265453Z","caller":"traceutil/trace.go:171","msg":"trace[491147106] transaction","detail":"{read_only:false; response_revision:25028; number_of_response:1; }","duration":"274.349029ms","start":"2026-06-30T18:40:58.991074Z","end":"2026-06-30T18:40:59.265423Z","steps":["trace[491147106] 'process raft request' (duration: 274.090692ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:40:59.265682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.03913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-06-30T18:40:59.265736Z","caller":"traceutil/trace.go:171","msg":"trace[1392930019] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25028; }","duration":"274.147183ms","start":"2026-06-30T18:40:58.991574Z","end":"2026-06-30T18:40:59.265721Z","steps":["trace[1392930019] 'agreement among raft nodes before linearized reading' (duration: 273.942787ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:40:59.56117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.55759ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:40:59.561385Z","caller":"traceutil/trace.go:171","msg":"trace[667352105] linearizableReadLoop","detail":"{readStateIndex:25830; appliedIndex:25827; }","duration":"120.168953ms","start":"2026-06-30T18:40:59.441202Z","end":"2026-06-30T18:40:59.561371Z","steps":["trace[667352105] 'read index received' (duration: 110.723731ms)","trace[667352105] 'applied index is now lower than readState.Index' (duration: 9.442362ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:40:59.561498Z","caller":"traceutil/trace.go:171","msg":"trace[472936394] transaction","detail":"{read_only:false; response_revision:25032; number_of_response:1; }","duration":"242.080204ms","start":"2026-06-30T18:40:59.319409Z","end":"2026-06-30T18:40:59.561489Z","steps":["trace[472936394] 'process raft request' (duration: 241.851157ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:40:59.561534Z","caller":"traceutil/trace.go:171","msg":"trace[1089087008] transaction","detail":"{read_only:false; response_revision:25031; number_of_response:1; }","duration":"274.965335ms","start":"2026-06-30T18:40:59.286546Z","end":"2026-06-30T18:40:59.561511Z","steps":["trace[1089087008] 'process raft request' (duration: 134.953092ms)","trace[1089087008] 'compare' (duration: 139.307304ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:40:59.561614Z","caller":"traceutil/trace.go:171","msg":"trace[1841890885] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"188.132828ms","start":"2026-06-30T18:40:59.373473Z","end":"2026-06-30T18:40:59.561605Z","steps":["trace[1841890885] 'process raft request' (duration: 187.84214ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:40:59.561804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.592745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-30T18:40:59.561838Z","caller":"traceutil/trace.go:171","msg":"trace[583488561] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25033; }","duration":"120.639486ms","start":"2026-06-30T18:40:59.44119Z","end":"2026-06-30T18:40:59.56183Z","steps":["trace[583488561] 'agreement among raft nodes before linearized reading' (duration: 120.519973ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:40:59.839609Z","caller":"traceutil/trace.go:171","msg":"trace[1270388924] transaction","detail":"{read_only:false; response_revision:25034; number_of_response:1; }","duration":"273.111404ms","start":"2026-06-30T18:40:59.566479Z","end":"2026-06-30T18:40:59.839591Z","steps":["trace[1270388924] 'process raft request' (duration: 272.995621ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:40:59.966761Z","caller":"traceutil/trace.go:171","msg":"trace[2019218271] linearizableReadLoop","detail":"{readStateIndex:25832; appliedIndex:25831; }","duration":"375.055411ms","start":"2026-06-30T18:40:59.591678Z","end":"2026-06-30T18:40:59.966734Z","steps":["trace[2019218271] 'read index received' (duration: 247.980107ms)","trace[2019218271] 'applied index is now lower than readState.Index' (duration: 127.074494ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:40:59.967124Z","caller":"traceutil/trace.go:171","msg":"trace[901181095] transaction","detail":"{read_only:false; response_revision:25035; number_of_response:1; }","duration":"400.562929ms","start":"2026-06-30T18:40:59.566527Z","end":"2026-06-30T18:40:59.96709Z","steps":["trace[901181095] 'process raft request' (duration: 348.852995ms)","trace[901181095] 'compare' (duration: 51.2142ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:40:59.967166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.999748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-30T18:40:59.967177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.615054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:40:59.967216Z","caller":"traceutil/trace.go:171","msg":"trace[629252688] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25035; }","duration":"221.05969ms","start":"2026-06-30T18:40:59.746138Z","end":"2026-06-30T18:40:59.967198Z","steps":["trace[629252688] 'agreement among raft nodes before linearized reading' (duration: 220.973328ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:40:59.967262Z","caller":"traceutil/trace.go:171","msg":"trace[716035373] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:25035; }","duration":"301.745268ms","start":"2026-06-30T18:40:59.665493Z","end":"2026-06-30T18:40:59.967239Z","steps":["trace[716035373] 'agreement among raft nodes before linearized reading' (duration: 301.598804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:40:59.967285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:40:59.566508Z","time spent":"400.687522ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:40:59.967305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:40:59.665476Z","time spent":"301.81859ms","remote":"127.0.0.1:45058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true "} {"level":"warn","ts":"2026-06-30T18:40:59.967954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.266745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-06-30T18:40:59.968012Z","caller":"traceutil/trace.go:171","msg":"trace[311072100] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:25035; }","duration":"376.357437ms","start":"2026-06-30T18:40:59.591643Z","end":"2026-06-30T18:40:59.968Z","steps":["trace[311072100] 'agreement among raft nodes before linearized reading' (duration: 375.579746ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:40:59.968044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:40:59.591625Z","time spent":"376.410249ms","remote":"127.0.0.1:37984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" "} {"level":"info","ts":"2026-06-30T18:41:00.182905Z","caller":"traceutil/trace.go:171","msg":"trace[555922473] linearizableReadLoop","detail":"{readStateIndex:25833; appliedIndex:25832; }","duration":"127.341781ms","start":"2026-06-30T18:41:00.055533Z","end":"2026-06-30T18:41:00.182875Z","steps":["trace[555922473] 'read index received' (duration: 125.025917ms)","trace[555922473] 'applied index is now lower than readState.Index' (duration: 2.313084ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:00.183191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.636689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-06-30T18:41:00.18325Z","caller":"traceutil/trace.go:171","msg":"trace[169806993] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25036; }","duration":"127.718722ms","start":"2026-06-30T18:41:00.055513Z","end":"2026-06-30T18:41:00.183232Z","steps":["trace[169806993] 'agreement among raft nodes before linearized reading' (duration: 127.519927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:00.44869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.292687ms","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-06-30T18:41:00.448748Z","caller":"traceutil/trace.go:171","msg":"trace[1588586612] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25038; }","duration":"146.38776ms","start":"2026-06-30T18:41:00.302347Z","end":"2026-06-30T18:41:00.448735Z","steps":["trace[1588586612] 'range keys from in-memory index tree' (duration: 146.236666ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:00.935369Z","caller":"traceutil/trace.go:171","msg":"trace[587229133] transaction","detail":"{read_only:false; response_revision:25039; number_of_response:1; }","duration":"386.818888ms","start":"2026-06-30T18:41:00.548529Z","end":"2026-06-30T18:41:00.935348Z","steps":["trace[587229133] 'process raft request' (duration: 386.678144ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:00.935592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:00.548509Z","time spent":"386.997572ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:41:01.099634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.624984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:41:01.099859Z","caller":"traceutil/trace.go:171","msg":"trace[1425198113] linearizableReadLoop","detail":"{readStateIndex:25839; appliedIndex:25837; }","duration":"240.226272ms","start":"2026-06-30T18:41:00.85962Z","end":"2026-06-30T18:41:01.099846Z","steps":["trace[1425198113] 'read index received' (duration: 75.923515ms)","trace[1425198113] 'applied index is now lower than readState.Index' (duration: 164.302127ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:01.09993Z","caller":"traceutil/trace.go:171","msg":"trace[454536795] transaction","detail":"{read_only:false; response_revision:25040; number_of_response:1; }","duration":"548.643315ms","start":"2026-06-30T18:41:00.551241Z","end":"2026-06-30T18:41:01.099885Z","steps":["trace[454536795] 'process raft request' (duration: 413.674332ms)","trace[454536795] 'compare' (duration: 134.550402ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:01.099967Z","caller":"traceutil/trace.go:171","msg":"trace[1459424731] transaction","detail":"{read_only:false; response_revision:25041; number_of_response:1; }","duration":"521.502563ms","start":"2026-06-30T18:41:00.578436Z","end":"2026-06-30T18:41:01.099938Z","steps":["trace[1459424731] 'process raft request' (duration: 521.333478ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:01.100088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:00.551226Z","time spent":"548.78153ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:41:01.100107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:00.578411Z","time spent":"521.618576ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:41:01.100102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.480109ms","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-06-30T18:41:01.100176Z","caller":"traceutil/trace.go:171","msg":"trace[1722158217] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25041; }","duration":"240.602183ms","start":"2026-06-30T18:41:00.859565Z","end":"2026-06-30T18:41:01.100167Z","steps":["trace[1722158217] 'agreement among raft nodes before linearized reading' (duration: 240.382137ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:01.709836Z","caller":"traceutil/trace.go:171","msg":"trace[674824409] linearizableReadLoop","detail":"{readStateIndex:25842; appliedIndex:25841; }","duration":"390.068947ms","start":"2026-06-30T18:41:01.319743Z","end":"2026-06-30T18:41:01.709812Z","steps":["trace[674824409] 'read index received' (duration: 389.939453ms)","trace[674824409] 'applied index is now lower than readState.Index' (duration: 128.284µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:01.709952Z","caller":"traceutil/trace.go:171","msg":"trace[1667442549] transaction","detail":"{read_only:false; response_revision:25044; number_of_response:1; }","duration":"422.433475ms","start":"2026-06-30T18:41:01.287496Z","end":"2026-06-30T18:41:01.70993Z","steps":["trace[1667442549] 'process raft request' (duration: 422.151327ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:01.710067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:01.287477Z","time spent":"422.527267ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:41:01.710092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.312343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-06-30T18:41:01.710126Z","caller":"traceutil/trace.go:171","msg":"trace[1898707329] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25044; }","duration":"390.404916ms","start":"2026-06-30T18:41:01.319711Z","end":"2026-06-30T18:41:01.710116Z","steps":["trace[1898707329] 'agreement among raft nodes before linearized reading' (duration: 390.221401ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:01.71017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:01.319697Z","time spent":"390.464708ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":597,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-06-30T18:41:01.727996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.831042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:41:01.728061Z","caller":"traceutil/trace.go:171","msg":"trace[1138030532] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:25045; }","duration":"384.970265ms","start":"2026-06-30T18:41:01.343076Z","end":"2026-06-30T18:41:01.728046Z","steps":["trace[1138030532] 'agreement among raft nodes before linearized reading' (duration: 384.845332ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:01.7281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:01.34306Z","time spent":"385.030197ms","remote":"127.0.0.1:58702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-06-30T18:41:01.728099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.943346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-06-30T18:41:01.728153Z","caller":"traceutil/trace.go:171","msg":"trace[1391341258] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25045; }","duration":"111.06849ms","start":"2026-06-30T18:41:01.617071Z","end":"2026-06-30T18:41:01.72814Z","steps":["trace[1391341258] 'agreement among raft nodes before linearized reading' (duration: 110.913945ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:01.728206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.790076ms","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-06-30T18:41:01.728199Z","caller":"traceutil/trace.go:171","msg":"trace[1706832414] transaction","detail":"{read_only:false; response_revision:25045; number_of_response:1; }","duration":"161.92718ms","start":"2026-06-30T18:41:01.566232Z","end":"2026-06-30T18:41:01.728159Z","steps":["trace[1706832414] 'process raft request' (duration: 161.56409ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:01.728285Z","caller":"traceutil/trace.go:171","msg":"trace[1572127271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25045; }","duration":"161.901669ms","start":"2026-06-30T18:41:01.566366Z","end":"2026-06-30T18:41:01.728267Z","steps":["trace[1572127271] 'agreement among raft nodes before linearized reading' (duration: 161.665213ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:01.728471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.450454ms","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-06-30T18:41:01.728663Z","caller":"traceutil/trace.go:171","msg":"trace[611840000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25045; }","duration":"162.684071ms","start":"2026-06-30T18:41:01.565945Z","end":"2026-06-30T18:41:01.728629Z","steps":["trace[611840000] 'agreement among raft nodes before linearized reading' (duration: 162.228979ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:02.034706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.059634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T18:41:02.034784Z","caller":"traceutil/trace.go:171","msg":"trace[992486796] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:25048; }","duration":"149.169357ms","start":"2026-06-30T18:41:01.885595Z","end":"2026-06-30T18:41:02.034765Z","steps":["trace[992486796] 'count revisions from in-memory index tree' (duration: 148.934851ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:02.034995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.989017ms","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-06-30T18:41:02.035134Z","caller":"traceutil/trace.go:171","msg":"trace[1187544227] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25048; }","duration":"191.137161ms","start":"2026-06-30T18:41:01.843938Z","end":"2026-06-30T18:41:02.035075Z","steps":["trace[1187544227] 'range keys from in-memory index tree' (duration: 190.692149ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:02.300392Z","caller":"traceutil/trace.go:171","msg":"trace[782128861] transaction","detail":"{read_only:false; response_revision:25050; number_of_response:1; }","duration":"260.079073ms","start":"2026-06-30T18:41:02.040285Z","end":"2026-06-30T18:41:02.300364Z","steps":["trace[782128861] 'process raft request' (duration: 197.710874ms)","trace[782128861] 'compare' (duration: 61.979859ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:02.300703Z","caller":"traceutil/trace.go:171","msg":"trace[2109963844] transaction","detail":"{read_only:false; response_revision:25051; number_of_response:1; }","duration":"113.102957ms","start":"2026-06-30T18:41:02.187573Z","end":"2026-06-30T18:41:02.300676Z","steps":["trace[2109963844] 'process raft request' (duration: 112.763878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:02.595009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.888012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:41:02.595179Z","caller":"traceutil/trace.go:171","msg":"trace[1244075367] transaction","detail":"{read_only:false; response_revision:25052; number_of_response:1; }","duration":"288.576373ms","start":"2026-06-30T18:41:02.30658Z","end":"2026-06-30T18:41:02.595156Z","steps":["trace[1244075367] 'process raft request' (duration: 127.478735ms)","trace[1244075367] 'compare' (duration: 160.713697ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:03.440876Z","caller":"traceutil/trace.go:171","msg":"trace[1316487163] transaction","detail":"{read_only:false; response_revision:25055; number_of_response:1; }","duration":"244.602294ms","start":"2026-06-30T18:41:03.196249Z","end":"2026-06-30T18:41:03.440851Z","steps":["trace[1316487163] 'process raft request' (duration: 244.44954ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:03.461059Z","caller":"traceutil/trace.go:171","msg":"trace[1928003629] transaction","detail":"{read_only:false; response_revision:25056; number_of_response:1; }","duration":"163.866224ms","start":"2026-06-30T18:41:03.297161Z","end":"2026-06-30T18:41:03.461028Z","steps":["trace[1928003629] 'process raft request' (duration: 163.70361ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:03.734708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.412188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:41:03.734802Z","caller":"traceutil/trace.go:171","msg":"trace[329041298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25056; }","duration":"196.554861ms","start":"2026-06-30T18:41:03.538231Z","end":"2026-06-30T18:41:03.734786Z","steps":["trace[329041298] 'range keys from in-memory index tree' (duration: 196.297554ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:03.971371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.130227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:41:03.971511Z","caller":"traceutil/trace.go:171","msg":"trace[790465363] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"226.385409ms","start":"2026-06-30T18:41:03.745101Z","end":"2026-06-30T18:41:03.971486Z","steps":["trace[790465363] 'process raft request' (duration: 95.035856ms)","trace[790465363] 'compare' (duration: 130.799928ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:04.182938Z","caller":"traceutil/trace.go:171","msg":"trace[1298224549] linearizableReadLoop","detail":"{readStateIndex:25859; appliedIndex:25856; }","duration":"357.570946ms","start":"2026-06-30T18:41:03.825339Z","end":"2026-06-30T18:41:04.18291Z","steps":["trace[1298224549] 'read index received' (duration: 14.811361ms)","trace[1298224549] 'applied index is now lower than readState.Index' (duration: 342.758045ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:04.183245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.876765ms","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-06-30T18:41:04.183347Z","caller":"traceutil/trace.go:171","msg":"trace[1279024550] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:25061; }","duration":"358.03278ms","start":"2026-06-30T18:41:03.825292Z","end":"2026-06-30T18:41:04.183325Z","steps":["trace[1279024550] 'agreement among raft nodes before linearized reading' (duration: 357.846965ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:04.183353Z","caller":"traceutil/trace.go:171","msg":"trace[465027702] transaction","detail":"{read_only:false; response_revision:25061; number_of_response:1; }","duration":"434.470519ms","start":"2026-06-30T18:41:03.748848Z","end":"2026-06-30T18:41:04.183319Z","steps":["trace[465027702] 'process raft request' (duration: 433.946004ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:04.183395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:03.825274Z","time spent":"358.108472ms","remote":"127.0.0.1:55156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-06-30T18:41:04.183384Z","caller":"traceutil/trace.go:171","msg":"trace[427026108] transaction","detail":"{read_only:false; response_revision:25060; number_of_response:1; }","duration":"434.696636ms","start":"2026-06-30T18:41:03.74862Z","end":"2026-06-30T18:41:04.183316Z","steps":["trace[427026108] 'process raft request' (duration: 376.378688ms)","trace[427026108] 'compare' (duration: 57.335701ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:04.183466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.557557ms","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-06-30T18:41:04.183509Z","caller":"traceutil/trace.go:171","msg":"trace[1073686031] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25061; }","duration":"123.65441ms","start":"2026-06-30T18:41:04.059841Z","end":"2026-06-30T18:41:04.183495Z","steps":["trace[1073686031] 'agreement among raft nodes before linearized reading' (duration: 123.469995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:04.183507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:03.748836Z","time spent":"434.587513ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:41:04.183559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:03.748587Z","time spent":"434.897191ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:41:12.475251Z","caller":"traceutil/trace.go:171","msg":"trace[2131069118] linearizableReadLoop","detail":"{readStateIndex:25919; appliedIndex:25918; }","duration":"445.865144ms","start":"2026-06-30T18:41:12.029363Z","end":"2026-06-30T18:41:12.475228Z","steps":["trace[2131069118] 'read index received' (duration: 445.784952ms)","trace[2131069118] 'applied index is now lower than readState.Index' (duration: 79.112µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:12.475424Z","caller":"traceutil/trace.go:171","msg":"trace[228831186] transaction","detail":"{read_only:false; response_revision:25119; number_of_response:1; }","duration":"462.521866ms","start":"2026-06-30T18:41:12.012877Z","end":"2026-06-30T18:41:12.475399Z","steps":["trace[228831186] 'process raft request' (duration: 462.193027ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:12.475479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.06745ms","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-06-30T18:41:12.47555Z","caller":"traceutil/trace.go:171","msg":"trace[1184029945] 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:25119; }","duration":"446.224505ms","start":"2026-06-30T18:41:12.029312Z","end":"2026-06-30T18:41:12.475536Z","steps":["trace[1184029945] 'agreement among raft nodes before linearized reading' (duration: 446.031369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:12.475644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:12.029294Z","time spent":"446.311886ms","remote":"127.0.0.1:45444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"warn","ts":"2026-06-30T18:41:12.475646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:12.012845Z","time spent":"462.66265ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:41:12.71059Z","caller":"traceutil/trace.go:171","msg":"trace[302801972] transaction","detail":"{read_only:false; response_revision:25120; number_of_response:1; }","duration":"648.69363ms","start":"2026-06-30T18:41:12.061877Z","end":"2026-06-30T18:41:12.71057Z","steps":["trace[302801972] 'process raft request' (duration: 637.89782ms)","trace[302801972] 'compare' (duration: 10.42991ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:12.71071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:12.06185Z","time spent":"648.791172ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:41:12.710702Z","caller":"traceutil/trace.go:171","msg":"trace[983937529] linearizableReadLoop","detail":"{readStateIndex:25921; appliedIndex:25919; }","duration":"235.358728ms","start":"2026-06-30T18:41:12.475317Z","end":"2026-06-30T18:41:12.710676Z","steps":["trace[983937529] 'read index received' (duration: 224.435715ms)","trace[983937529] 'applied index is now lower than readState.Index' (duration: 10.920853ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:12.710783Z","caller":"traceutil/trace.go:171","msg":"trace[1335977630] transaction","detail":"{read_only:false; response_revision:25121; number_of_response:1; }","duration":"297.043628ms","start":"2026-06-30T18:41:12.413719Z","end":"2026-06-30T18:41:12.710762Z","steps":["trace[1335977630] 'process raft request' (duration: 296.816542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:12.710819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.430494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-30T18:41:12.710855Z","caller":"traceutil/trace.go:171","msg":"trace[1006408634] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25121; }","duration":"268.511506ms","start":"2026-06-30T18:41:12.442332Z","end":"2026-06-30T18:41:12.710843Z","steps":["trace[1006408634] 'agreement among raft nodes before linearized reading' (duration: 268.374903ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:12.710862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.417393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T18:41:12.710898Z","caller":"traceutil/trace.go:171","msg":"trace[625186610] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:25121; }","duration":"229.478024ms","start":"2026-06-30T18:41:12.481408Z","end":"2026-06-30T18:41:12.710886Z","steps":["trace[625186610] 'agreement among raft nodes before linearized reading' (duration: 229.417372ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:12.710934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.36511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-30T18:41:12.71097Z","caller":"traceutil/trace.go:171","msg":"trace[730890459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25121; }","duration":"498.443293ms","start":"2026-06-30T18:41:12.212515Z","end":"2026-06-30T18:41:12.710958Z","steps":["trace[730890459] 'agreement among raft nodes before linearized reading' (duration: 498.287658ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:12.71095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.839876ms","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-06-30T18:41:12.711013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:12.2125Z","time spent":"498.502564ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-30T18:41:12.711026Z","caller":"traceutil/trace.go:171","msg":"trace[2066166436] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25121; }","duration":"391.963929ms","start":"2026-06-30T18:41:12.319045Z","end":"2026-06-30T18:41:12.711009Z","steps":["trace[2066166436] 'agreement among raft nodes before linearized reading' (duration: 391.726872ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:12.711058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:12.319022Z","time spent":"392.030061ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-30T18:41:12.711168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.385319ms","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-06-30T18:41:12.7112Z","caller":"traceutil/trace.go:171","msg":"trace[1292224549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25121; }","duration":"499.459321ms","start":"2026-06-30T18:41:12.211732Z","end":"2026-06-30T18:41:12.711192Z","steps":["trace[1292224549] 'agreement among raft nodes before linearized reading' (duration: 499.332748ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:12.711195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.630992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-30T18:41:12.711227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:12.211718Z","time spent":"499.502212ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-30T18:41:12.711234Z","caller":"traceutil/trace.go:171","msg":"trace[1824013685] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:25121; }","duration":"148.709714ms","start":"2026-06-30T18:41:12.562515Z","end":"2026-06-30T18:41:12.711225Z","steps":["trace[1824013685] 'agreement among raft nodes before linearized reading' (duration: 148.614871ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:12.711224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.534385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:41:12.711299Z","caller":"traceutil/trace.go:171","msg":"trace[1184695676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25121; }","duration":"172.672098ms","start":"2026-06-30T18:41:12.538606Z","end":"2026-06-30T18:41:12.711278Z","steps":["trace[1184695676] 'agreement among raft nodes before linearized reading' (duration: 172.540825ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:12.711445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.34154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-30T18:41:12.71152Z","caller":"traceutil/trace.go:171","msg":"trace[659663563] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:25121; }","duration":"163.477804ms","start":"2026-06-30T18:41:12.548026Z","end":"2026-06-30T18:41:12.711503Z","steps":["trace[659663563] 'agreement among raft nodes before linearized reading' (duration: 163.222767ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:13.236352Z","caller":"traceutil/trace.go:171","msg":"trace[1879662882] transaction","detail":"{read_only:false; response_revision:25123; number_of_response:1; }","duration":"519.698973ms","start":"2026-06-30T18:41:12.716631Z","end":"2026-06-30T18:41:13.23633Z","steps":["trace[1879662882] 'process raft request' (duration: 505.201391ms)","trace[1879662882] 'compare' (duration: 14.140272ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:13.236486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:12.716596Z","time spent":"519.821696ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:41:13.236556Z","caller":"traceutil/trace.go:171","msg":"trace[1661957370] transaction","detail":"{read_only:false; response_revision:25124; number_of_response:1; }","duration":"519.883608ms","start":"2026-06-30T18:41:12.716648Z","end":"2026-06-30T18:41:13.236532Z","steps":["trace[1661957370] 'process raft request' (duration: 519.637141ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:13.236636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:12.716637Z","time spent":"519.96447ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:41:13.236918Z","caller":"traceutil/trace.go:171","msg":"trace[1563497003] linearizableReadLoop","detail":"{readStateIndex:25926; appliedIndex:25921; }","duration":"517.131841ms","start":"2026-06-30T18:41:12.719765Z","end":"2026-06-30T18:41:13.236897Z","steps":["trace[1563497003] 'read index received' (duration: 12.049984ms)","trace[1563497003] 'applied index is now lower than readState.Index' (duration: 505.081027ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:13.237016Z","caller":"traceutil/trace.go:171","msg":"trace[2009416150] transaction","detail":"{read_only:false; response_revision:25125; number_of_response:1; }","duration":"520.357691ms","start":"2026-06-30T18:41:12.716644Z","end":"2026-06-30T18:41:13.237002Z","steps":["trace[2009416150] 'process raft request' (duration: 520.129985ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:13.237032Z","caller":"traceutil/trace.go:171","msg":"trace[1438797388] transaction","detail":"{read_only:false; response_revision:25126; number_of_response:1; }","duration":"520.30869ms","start":"2026-06-30T18:41:12.716711Z","end":"2026-06-30T18:41:13.23702Z","steps":["trace[1438797388] 'process raft request' (duration: 520.147125ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:13.237116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:12.716617Z","time spent":"520.434573ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:41:13.237167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:12.716695Z","time spent":"520.364411ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:41:13.237406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.642905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-06-30T18:41:13.237449Z","caller":"traceutil/trace.go:171","msg":"trace[345457647] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:25126; }","duration":"517.703737ms","start":"2026-06-30T18:41:12.719733Z","end":"2026-06-30T18:41:13.237437Z","steps":["trace[345457647] 'agreement among raft nodes before linearized reading' (duration: 517.566444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:13.237479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:12.719722Z","time spent":"517.750468ms","remote":"127.0.0.1:37984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1875,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" "} {"level":"info","ts":"2026-06-30T18:41:13.238907Z","caller":"traceutil/trace.go:171","msg":"trace[2009867142] transaction","detail":"{read_only:false; response_revision:25127; number_of_response:1; }","duration":"247.667819ms","start":"2026-06-30T18:41:12.99122Z","end":"2026-06-30T18:41:13.238888Z","steps":["trace[2009867142] 'process raft request' (duration: 247.578476ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:13.524515Z","caller":"traceutil/trace.go:171","msg":"trace[1647743569] linearizableReadLoop","detail":"{readStateIndex:25928; appliedIndex:25926; }","duration":"287.509674ms","start":"2026-06-30T18:41:13.236991Z","end":"2026-06-30T18:41:13.524501Z","steps":["trace[1647743569] 'read index received' (duration: 1.80132ms)","trace[1647743569] 'applied index is now lower than readState.Index' (duration: 285.707724ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:13.524617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.274903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:41:13.524635Z","caller":"traceutil/trace.go:171","msg":"trace[1472189370] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25128; }","duration":"752.345285ms","start":"2026-06-30T18:41:12.772284Z","end":"2026-06-30T18:41:13.524629Z","steps":["trace[1472189370] 'agreement among raft nodes before linearized reading' (duration: 752.284664ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:13.524652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:12.772262Z","time spent":"752.385286ms","remote":"127.0.0.1:45110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true "} {"level":"warn","ts":"2026-06-30T18:41:13.524963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.831297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:41:13.525026Z","caller":"traceutil/trace.go:171","msg":"trace[775644429] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:25128; }","duration":"545.963171ms","start":"2026-06-30T18:41:12.979047Z","end":"2026-06-30T18:41:13.525011Z","steps":["trace[775644429] 'agreement among raft nodes before linearized reading' (duration: 545.531689ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:13.525069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:12.979028Z","time spent":"546.029063ms","remote":"127.0.0.1:43712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true "} {"level":"info","ts":"2026-06-30T18:41:13.525456Z","caller":"traceutil/trace.go:171","msg":"trace[1133544404] transaction","detail":"{read_only:false; response_revision:25128; number_of_response:1; }","duration":"299.084004ms","start":"2026-06-30T18:41:13.225437Z","end":"2026-06-30T18:41:13.524521Z","steps":["trace[1133544404] 'process raft request' (duration: 224.140476ms)","trace[1133544404] 'compare' (duration: 74.751973ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:13.525578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:13.225411Z","time spent":"300.093803ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:41:13.534996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.693362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b3fcfc68-2757-4bd2-a6b9-f5afa64bc5a0\" ","response":"range_response_count:1 size:1318"} {"level":"info","ts":"2026-06-30T18:41:13.535022Z","caller":"traceutil/trace.go:171","msg":"trace[1027715842] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b3fcfc68-2757-4bd2-a6b9-f5afa64bc5a0; range_end:; response_count:1; response_revision:25129; }","duration":"294.746524ms","start":"2026-06-30T18:41:13.240269Z","end":"2026-06-30T18:41:13.535015Z","steps":["trace[1027715842] 'agreement among raft nodes before linearized reading' (duration: 294.648171ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:14.277029Z","caller":"traceutil/trace.go:171","msg":"trace[1770547394] transaction","detail":"{read_only:false; response_revision:25131; number_of_response:1; }","duration":"502.943479ms","start":"2026-06-30T18:41:13.774051Z","end":"2026-06-30T18:41:14.276994Z","steps":["trace[1770547394] 'process raft request' (duration: 502.712512ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:14.277293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:13.774021Z","time spent":"503.179104ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:41:14.284945Z","caller":"traceutil/trace.go:171","msg":"trace[116503389] linearizableReadLoop","detail":"{readStateIndex:25932; appliedIndex:25930; }","duration":"249.980362ms","start":"2026-06-30T18:41:14.034941Z","end":"2026-06-30T18:41:14.284921Z","steps":["trace[116503389] 'read index received' (duration: 241.847347ms)","trace[116503389] 'applied index is now lower than readState.Index' (duration: 8.131985ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:14.285053Z","caller":"traceutil/trace.go:171","msg":"trace[782232103] transaction","detail":"{read_only:false; response_revision:25132; number_of_response:1; }","duration":"510.012214ms","start":"2026-06-30T18:41:13.77502Z","end":"2026-06-30T18:41:14.285033Z","steps":["trace[782232103] 'process raft request' (duration: 509.752387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:14.2851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.115886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:41:14.285139Z","caller":"traceutil/trace.go:171","msg":"trace[1412974358] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:25132; }","duration":"250.239ms","start":"2026-06-30T18:41:14.034887Z","end":"2026-06-30T18:41:14.285126Z","steps":["trace[1412974358] 'agreement among raft nodes before linearized reading' (duration: 250.128867ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:14.285174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:13.774992Z","time spent":"510.128027ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:41:19.385613Z","caller":"traceutil/trace.go:171","msg":"trace[720274077] transaction","detail":"{read_only:false; response_revision:25222; number_of_response:1; }","duration":"125.196682ms","start":"2026-06-30T18:41:19.260386Z","end":"2026-06-30T18:41:19.385583Z","steps":["trace[720274077] 'process raft request' (duration: 124.997707ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:19.395313Z","caller":"traceutil/trace.go:171","msg":"trace[1034246781] linearizableReadLoop","detail":"{readStateIndex:26025; appliedIndex:26023; }","duration":"131.162738ms","start":"2026-06-30T18:41:19.264133Z","end":"2026-06-30T18:41:19.395296Z","steps":["trace[1034246781] 'read index received' (duration: 121.228102ms)","trace[1034246781] 'applied index is now lower than readState.Index' (duration: 9.933356ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:19.395455Z","caller":"traceutil/trace.go:171","msg":"trace[1286633698] transaction","detail":"{read_only:false; response_revision:25223; number_of_response:1; }","duration":"131.494227ms","start":"2026-06-30T18:41:19.263942Z","end":"2026-06-30T18:41:19.395437Z","steps":["trace[1286633698] 'process raft request' (duration: 131.275491ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:19.395534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.398015ms","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":"warn","ts":"2026-06-30T18:41:19.395566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.082896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-30T18:41:19.395587Z","caller":"traceutil/trace.go:171","msg":"trace[194219319] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25223; }","duration":"131.525467ms","start":"2026-06-30T18:41:19.264044Z","end":"2026-06-30T18:41:19.39557Z","steps":["trace[194219319] 'agreement among raft nodes before linearized reading' (duration: 131.349163ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:19.395598Z","caller":"traceutil/trace.go:171","msg":"trace[1125038174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25223; }","duration":"131.142567ms","start":"2026-06-30T18:41:19.264446Z","end":"2026-06-30T18:41:19.395589Z","steps":["trace[1125038174] 'agreement among raft nodes before linearized reading' (duration: 131.055485ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:19.395753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.25067ms","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-06-30T18:41:19.395795Z","caller":"traceutil/trace.go:171","msg":"trace[678477744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25223; }","duration":"131.313562ms","start":"2026-06-30T18:41:19.264472Z","end":"2026-06-30T18:41:19.395785Z","steps":["trace[678477744] 'agreement among raft nodes before linearized reading' (duration: 131.20807ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:19.629619Z","caller":"traceutil/trace.go:171","msg":"trace[196716025] transaction","detail":"{read_only:false; response_revision:25224; number_of_response:1; }","duration":"230.05686ms","start":"2026-06-30T18:41:19.399538Z","end":"2026-06-30T18:41:19.629595Z","steps":["trace[196716025] 'process raft request' (duration: 143.255412ms)","trace[196716025] 'compare' (duration: 86.600842ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:19.687519Z","caller":"traceutil/trace.go:171","msg":"trace[1945757646] transaction","detail":"{read_only:false; response_revision:25225; number_of_response:1; }","duration":"287.808251ms","start":"2026-06-30T18:41:19.399683Z","end":"2026-06-30T18:41:19.687491Z","steps":["trace[1945757646] 'process raft request' (duration: 287.608246ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:19.687509Z","caller":"traceutil/trace.go:171","msg":"trace[1183203350] transaction","detail":"{read_only:false; response_revision:25226; number_of_response:1; }","duration":"287.713738ms","start":"2026-06-30T18:41:19.399776Z","end":"2026-06-30T18:41:19.68749Z","steps":["trace[1183203350] 'process raft request' (duration: 287.633616ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:19.687622Z","caller":"traceutil/trace.go:171","msg":"trace[574988879] linearizableReadLoop","detail":"{readStateIndex:26029; appliedIndex:26025; }","duration":"284.090708ms","start":"2026-06-30T18:41:19.403509Z","end":"2026-06-30T18:41:19.6876Z","steps":["trace[574988879] 'read index received' (duration: 139.351574ms)","trace[574988879] 'applied index is now lower than readState.Index' (duration: 144.737614ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:19.687645Z","caller":"traceutil/trace.go:171","msg":"trace[2084620973] transaction","detail":"{read_only:false; response_revision:25227; number_of_response:1; }","duration":"284.134959ms","start":"2026-06-30T18:41:19.403481Z","end":"2026-06-30T18:41:19.687616Z","steps":["trace[2084620973] 'process raft request' (duration: 283.962374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:19.687745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.214841ms","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-06-30T18:41:19.687767Z","caller":"traceutil/trace.go:171","msg":"trace[470110656] 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:25227; }","duration":"284.298274ms","start":"2026-06-30T18:41:19.403462Z","end":"2026-06-30T18:41:19.68776Z","steps":["trace[470110656] 'agreement among raft nodes before linearized reading' (duration: 284.227342ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:19.790483Z","caller":"traceutil/trace.go:171","msg":"trace[1265971102] transaction","detail":"{read_only:false; response_revision:25228; number_of_response:1; }","duration":"243.214205ms","start":"2026-06-30T18:41:19.547249Z","end":"2026-06-30T18:41:19.790463Z","steps":["trace[1265971102] 'process raft request' (duration: 243.060481ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:19.79077Z","caller":"traceutil/trace.go:171","msg":"trace[1292958472] linearizableReadLoop","detail":"{readStateIndex:26031; appliedIndex:26029; }","duration":"103.080699ms","start":"2026-06-30T18:41:19.687667Z","end":"2026-06-30T18:41:19.790748Z","steps":["trace[1292958472] 'read index received' (duration: 102.571185ms)","trace[1292958472] 'applied index is now lower than readState.Index' (duration: 508.004µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:19.790869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.666588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:41:19.790887Z","caller":"traceutil/trace.go:171","msg":"trace[458161544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25229; }","duration":"252.74338ms","start":"2026-06-30T18:41:19.538137Z","end":"2026-06-30T18:41:19.790881Z","steps":["trace[458161544] 'agreement among raft nodes before linearized reading' (duration: 252.681028ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:19.7909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.889342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-senlin-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-06-30T18:41:19.790931Z","caller":"traceutil/trace.go:171","msg":"trace[296282591] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-senlin-server-0; range_end:; response_count:1; response_revision:25229; }","duration":"107.931824ms","start":"2026-06-30T18:41:19.682989Z","end":"2026-06-30T18:41:19.790921Z","steps":["trace[296282591] 'agreement among raft nodes before linearized reading' (duration: 107.81234ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:19.790913Z","caller":"traceutil/trace.go:171","msg":"trace[1615437718] transaction","detail":"{read_only:false; response_revision:25229; number_of_response:1; }","duration":"239.993976ms","start":"2026-06-30T18:41:19.550895Z","end":"2026-06-30T18:41:19.790889Z","steps":["trace[1615437718] 'process raft request' (duration: 239.532453ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:19.79107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.331226ms","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-06-30T18:41:19.79114Z","caller":"traceutil/trace.go:171","msg":"trace[1760850594] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25229; }","duration":"222.428529ms","start":"2026-06-30T18:41:19.568693Z","end":"2026-06-30T18:41:19.791122Z","steps":["trace[1760850594] 'agreement among raft nodes before linearized reading' (duration: 222.248154ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:20.051817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.624406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:41:20.051883Z","caller":"traceutil/trace.go:171","msg":"trace[1704910649] linearizableReadLoop","detail":"{readStateIndex:26032; appliedIndex:26031; }","duration":"256.461693ms","start":"2026-06-30T18:41:19.795411Z","end":"2026-06-30T18:41:20.051873Z","steps":["trace[1704910649] 'read index received' (duration: 131.709133ms)","trace[1704910649] 'applied index is now lower than readState.Index' (duration: 124.75176ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:20.052037Z","caller":"traceutil/trace.go:171","msg":"trace[1913954966] transaction","detail":"{read_only:false; response_revision:25230; number_of_response:1; }","duration":"257.0854ms","start":"2026-06-30T18:41:19.794943Z","end":"2026-06-30T18:41:20.052028Z","steps":["trace[1913954966] 'process raft request' (duration: 132.194166ms)","trace[1913954966] 'compare' (duration: 124.316688ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:20.052125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.488606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:41:20.052185Z","caller":"traceutil/trace.go:171","msg":"trace[2057792790] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:25230; }","duration":"127.598219ms","start":"2026-06-30T18:41:19.924573Z","end":"2026-06-30T18:41:20.052171Z","steps":["trace[2057792790] 'agreement among raft nodes before linearized reading' (duration: 127.485476ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:20.05219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.772351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2c2f880b-2b82-48f2-bcdc-0eb1c67dd308\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-06-30T18:41:20.052217Z","caller":"traceutil/trace.go:171","msg":"trace[901697234] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2c2f880b-2b82-48f2-bcdc-0eb1c67dd308; range_end:; response_count:1; response_revision:25230; }","duration":"256.818603ms","start":"2026-06-30T18:41:19.795389Z","end":"2026-06-30T18:41:20.052208Z","steps":["trace[901697234] 'agreement among raft nodes before linearized reading' (duration: 256.71897ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:20.208656Z","caller":"traceutil/trace.go:171","msg":"trace[874228658] linearizableReadLoop","detail":"{readStateIndex:26033; appliedIndex:26032; }","duration":"152.16709ms","start":"2026-06-30T18:41:20.056458Z","end":"2026-06-30T18:41:20.208625Z","steps":["trace[874228658] 'read index received' (duration: 126.506878ms)","trace[874228658] 'applied index is now lower than readState.Index' (duration: 25.658542ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:20.208832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.324544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:41:20.208917Z","caller":"traceutil/trace.go:171","msg":"trace[1305121688] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25230; }","duration":"152.456997ms","start":"2026-06-30T18:41:20.056442Z","end":"2026-06-30T18:41:20.208899Z","steps":["trace[1305121688] 'agreement among raft nodes before linearized reading' (duration: 152.287433ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:20.356613Z","caller":"traceutil/trace.go:171","msg":"trace[250004749] linearizableReadLoop","detail":"{readStateIndex:26034; appliedIndex:26033; }","duration":"140.726632ms","start":"2026-06-30T18:41:20.215862Z","end":"2026-06-30T18:41:20.356589Z","steps":["trace[250004749] 'read index received' (duration: 95.969331ms)","trace[250004749] 'applied index is now lower than readState.Index' (duration: 44.756011ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:20.356672Z","caller":"traceutil/trace.go:171","msg":"trace[1679844391] transaction","detail":"{read_only:false; response_revision:25231; number_of_response:1; }","duration":"146.811462ms","start":"2026-06-30T18:41:20.209826Z","end":"2026-06-30T18:41:20.356637Z","steps":["trace[1679844391] 'process raft request' (duration: 101.994909ms)","trace[1679844391] 'compare' (duration: 44.645718ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:20.356777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.899668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:41:20.356807Z","caller":"traceutil/trace.go:171","msg":"trace[1137441204] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25231; }","duration":"140.98514ms","start":"2026-06-30T18:41:20.215811Z","end":"2026-06-30T18:41:20.356796Z","steps":["trace[1137441204] 'agreement among raft nodes before linearized reading' (duration: 140.885867ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:20.358011Z","caller":"traceutil/trace.go:171","msg":"trace[794325327] transaction","detail":"{read_only:false; response_revision:25232; number_of_response:1; }","duration":"137.163164ms","start":"2026-06-30T18:41:20.220819Z","end":"2026-06-30T18:41:20.357982Z","steps":["trace[794325327] 'process raft request' (duration: 136.884556ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:20.358039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.696926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:41:20.358105Z","caller":"traceutil/trace.go:171","msg":"trace[1352885201] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:25234; }","duration":"125.806029ms","start":"2026-06-30T18:41:20.232282Z","end":"2026-06-30T18:41:20.358088Z","steps":["trace[1352885201] 'agreement among raft nodes before linearized reading' (duration: 125.633584ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:21.766661Z","caller":"traceutil/trace.go:171","msg":"trace[742929547] transaction","detail":"{read_only:false; response_revision:25242; number_of_response:1; }","duration":"129.605875ms","start":"2026-06-30T18:41:21.637036Z","end":"2026-06-30T18:41:21.766642Z","steps":["trace[742929547] 'process raft request' (duration: 129.342577ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:25.573419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.39407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:41:25.573592Z","caller":"traceutil/trace.go:171","msg":"trace[1751336479] transaction","detail":"{read_only:false; response_revision:25289; number_of_response:1; }","duration":"149.63886ms","start":"2026-06-30T18:41:25.423938Z","end":"2026-06-30T18:41:25.573577Z","steps":["trace[1751336479] 'process raft request' (duration: 38.020825ms)","trace[1751336479] 'compare' (duration: 111.306196ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:25.783496Z","caller":"traceutil/trace.go:171","msg":"trace[968601575] linearizableReadLoop","detail":"{readStateIndex:26094; appliedIndex:26093; }","duration":"198.154855ms","start":"2026-06-30T18:41:25.585321Z","end":"2026-06-30T18:41:25.783475Z","steps":["trace[968601575] 'read index received' (duration: 197.988831ms)","trace[968601575] 'applied index is now lower than readState.Index' (duration: 164.954µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:25.783561Z","caller":"traceutil/trace.go:171","msg":"trace[1302498135] transaction","detail":"{read_only:false; response_revision:25290; number_of_response:1; }","duration":"200.326946ms","start":"2026-06-30T18:41:25.583206Z","end":"2026-06-30T18:41:25.783533Z","steps":["trace[1302498135] 'process raft request' (duration: 200.11421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:25.783892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.558817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-38b71196-782f-41e1-bc0d-5fbcf71edf77\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-06-30T18:41:25.784028Z","caller":"traceutil/trace.go:171","msg":"trace[283653576] range","detail":"{range_begin:/registry/persistentvolumes/pvc-38b71196-782f-41e1-bc0d-5fbcf71edf77; range_end:; response_count:1; response_revision:25290; }","duration":"198.719551ms","start":"2026-06-30T18:41:25.585286Z","end":"2026-06-30T18:41:25.784005Z","steps":["trace[283653576] 'agreement among raft nodes before linearized reading' (duration: 198.32205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:26.045819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.870201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:41:26.04594Z","caller":"traceutil/trace.go:171","msg":"trace[842590509] transaction","detail":"{read_only:false; response_revision:25291; number_of_response:1; }","duration":"258.217072ms","start":"2026-06-30T18:41:25.787702Z","end":"2026-06-30T18:41:26.045919Z","steps":["trace[842590509] 'process raft request' (duration: 66.163915ms)","trace[842590509] 'compare' (duration: 191.746778ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:26.084261Z","caller":"traceutil/trace.go:171","msg":"trace[224890798] transaction","detail":"{read_only:false; response_revision:25292; number_of_response:1; }","duration":"295.931047ms","start":"2026-06-30T18:41:25.788287Z","end":"2026-06-30T18:41:26.084218Z","steps":["trace[224890798] 'process raft request' (duration: 295.67693ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:26.084271Z","caller":"traceutil/trace.go:171","msg":"trace[2112056896] transaction","detail":"{read_only:false; response_revision:25293; number_of_response:1; }","duration":"294.858027ms","start":"2026-06-30T18:41:25.789387Z","end":"2026-06-30T18:41:26.084245Z","steps":["trace[2112056896] 'process raft request' (duration: 294.724744ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:26.084574Z","caller":"traceutil/trace.go:171","msg":"trace[725944213] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"295.137355ms","start":"2026-06-30T18:41:25.789417Z","end":"2026-06-30T18:41:26.084554Z","steps":["trace[725944213] 'process raft request' (duration: 294.746084ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:26.085009Z","caller":"traceutil/trace.go:171","msg":"trace[255805827] transaction","detail":"{read_only:false; response_revision:25295; number_of_response:1; }","duration":"295.299319ms","start":"2026-06-30T18:41:25.789694Z","end":"2026-06-30T18:41:26.084994Z","steps":["trace[255805827] 'process raft request' (duration: 294.752404ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:26.085184Z","caller":"traceutil/trace.go:171","msg":"trace[1921380433] linearizableReadLoop","detail":"{readStateIndex:26103; appliedIndex:26094; }","duration":"256.663247ms","start":"2026-06-30T18:41:25.82849Z","end":"2026-06-30T18:41:26.085153Z","steps":["trace[1921380433] 'read index received' (duration: 25.399564ms)","trace[1921380433] 'applied index is now lower than readState.Index' (duration: 231.262403ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:26.08529Z","caller":"traceutil/trace.go:171","msg":"trace[450997735] transaction","detail":"{read_only:false; response_revision:25297; number_of_response:1; }","duration":"294.460056ms","start":"2026-06-30T18:41:25.790817Z","end":"2026-06-30T18:41:26.085278Z","steps":["trace[450997735] 'process raft request' (duration: 294.114596ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:26.085366Z","caller":"traceutil/trace.go:171","msg":"trace[1057562355] transaction","detail":"{read_only:false; response_revision:25299; number_of_response:1; }","duration":"276.094977ms","start":"2026-06-30T18:41:25.809255Z","end":"2026-06-30T18:41:26.08535Z","steps":["trace[1057562355] 'process raft request' (duration: 275.808299ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:26.085333Z","caller":"traceutil/trace.go:171","msg":"trace[301805205] transaction","detail":"{read_only:false; response_revision:25298; number_of_response:1; }","duration":"278.158034ms","start":"2026-06-30T18:41:25.807125Z","end":"2026-06-30T18:41:26.085284Z","steps":["trace[301805205] 'process raft request' (duration: 277.889057ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:26.085362Z","caller":"traceutil/trace.go:171","msg":"trace[2023731692] transaction","detail":"{read_only:false; response_revision:25296; number_of_response:1; }","duration":"295.216718ms","start":"2026-06-30T18:41:25.790123Z","end":"2026-06-30T18:41:26.085339Z","steps":["trace[2023731692] 'process raft request' (duration: 294.695263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:26.085453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.118645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:41:26.085507Z","caller":"traceutil/trace.go:171","msg":"trace[1830445032] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:25299; }","duration":"136.229258ms","start":"2026-06-30T18:41:25.949262Z","end":"2026-06-30T18:41:26.085492Z","steps":["trace[1830445032] 'agreement among raft nodes before linearized reading' (duration: 136.130505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:26.085601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.66701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-06-30T18:41:26.085633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.13108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-06-30T18:41:26.085645Z","caller":"traceutil/trace.go:171","msg":"trace[668936512] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25299; }","duration":"105.762113ms","start":"2026-06-30T18:41:25.979873Z","end":"2026-06-30T18:41:26.085635Z","steps":["trace[668936512] 'agreement among raft nodes before linearized reading' (duration: 105.66995ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:26.085671Z","caller":"traceutil/trace.go:171","msg":"trace[1935852530] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25299; }","duration":"257.188102ms","start":"2026-06-30T18:41:25.828463Z","end":"2026-06-30T18:41:26.085651Z","steps":["trace[1935852530] 'agreement among raft nodes before linearized reading' (duration: 257.069329ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:51.163181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.342989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:41:51.163285Z","caller":"traceutil/trace.go:171","msg":"trace[1073206064] linearizableReadLoop","detail":"{readStateIndex:26315; appliedIndex:26314; }","duration":"230.320917ms","start":"2026-06-30T18:41:50.932949Z","end":"2026-06-30T18:41:51.163269Z","steps":["trace[1073206064] 'read index received' (duration: 70.716001ms)","trace[1073206064] 'applied index is now lower than readState.Index' (duration: 159.603856ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:51.163326Z","caller":"traceutil/trace.go:171","msg":"trace[1356401239] transaction","detail":"{read_only:false; response_revision:25506; number_of_response:1; }","duration":"301.280465ms","start":"2026-06-30T18:41:50.862021Z","end":"2026-06-30T18:41:51.163302Z","steps":["trace[1356401239] 'process raft request' (duration: 141.737711ms)","trace[1356401239] 'compare' (duration: 158.950048ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:51.163403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.446061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T18:41:51.163446Z","caller":"traceutil/trace.go:171","msg":"trace[387386079] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:25506; }","duration":"230.525743ms","start":"2026-06-30T18:41:50.93291Z","end":"2026-06-30T18:41:51.163435Z","steps":["trace[387386079] 'agreement among raft nodes before linearized reading' (duration: 230.447011ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:51.163546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:50.862002Z","time spent":"301.41205ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:41:51.164098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.855783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418759"} {"level":"info","ts":"2026-06-30T18:41:51.164167Z","caller":"traceutil/trace.go:171","msg":"trace[660183689] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25506; }","duration":"168.929905ms","start":"2026-06-30T18:41:50.995222Z","end":"2026-06-30T18:41:51.164152Z","steps":["trace[660183689] 'agreement among raft nodes before linearized reading' (duration: 168.640187ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:51.164434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.906412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-30T18:41:51.164532Z","caller":"traceutil/trace.go:171","msg":"trace[602568965] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25506; }","duration":"223.036486ms","start":"2026-06-30T18:41:50.941478Z","end":"2026-06-30T18:41:51.164514Z","steps":["trace[602568965] 'agreement among raft nodes before linearized reading' (duration: 221.983147ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:51.806685Z","caller":"traceutil/trace.go:171","msg":"trace[1145870910] transaction","detail":"{read_only:false; response_revision:25509; number_of_response:1; }","duration":"265.284376ms","start":"2026-06-30T18:41:51.541372Z","end":"2026-06-30T18:41:51.806657Z","steps":["trace[1145870910] 'process raft request' (duration: 265.111881ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:51.9759Z","caller":"traceutil/trace.go:171","msg":"trace[430815341] linearizableReadLoop","detail":"{readStateIndex:26319; appliedIndex:26317; }","duration":"345.48774ms","start":"2026-06-30T18:41:51.630378Z","end":"2026-06-30T18:41:51.975866Z","steps":["trace[430815341] 'read index received' (duration: 175.937918ms)","trace[430815341] 'applied index is now lower than readState.Index' (duration: 169.548612ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:51.976188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.791088ms","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-06-30T18:41:51.976263Z","caller":"traceutil/trace.go:171","msg":"trace[1350494179] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25510; }","duration":"345.869401ms","start":"2026-06-30T18:41:51.630373Z","end":"2026-06-30T18:41:51.976242Z","steps":["trace[1350494179] 'agreement among raft nodes before linearized reading' (duration: 345.745177ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:51.976276Z","caller":"traceutil/trace.go:171","msg":"trace[2010800063] transaction","detail":"{read_only:false; response_revision:25510; number_of_response:1; }","duration":"410.756711ms","start":"2026-06-30T18:41:51.565484Z","end":"2026-06-30T18:41:51.97624Z","steps":["trace[2010800063] 'process raft request' (duration: 358.692727ms)","trace[2010800063] 'compare' (duration: 51.397435ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:51.976308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:51.630332Z","time spent":"345.966653ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-06-30T18:41:51.976314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.949813ms","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-06-30T18:41:51.976387Z","caller":"traceutil/trace.go:171","msg":"trace[1256642771] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25510; }","duration":"271.026265ms","start":"2026-06-30T18:41:51.70534Z","end":"2026-06-30T18:41:51.976366Z","steps":["trace[1256642771] 'agreement among raft nodes before linearized reading' (duration: 270.823639ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:51.97642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:51.565461Z","time spent":"410.882323ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418817,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:41:52.444938Z","caller":"traceutil/trace.go:171","msg":"trace[1959055820] linearizableReadLoop","detail":"{readStateIndex:26323; appliedIndex:26321; }","duration":"179.682403ms","start":"2026-06-30T18:41:52.265232Z","end":"2026-06-30T18:41:52.444914Z","steps":["trace[1959055820] 'read index received' (duration: 7.397806ms)","trace[1959055820] 'applied index is now lower than readState.Index' (duration: 172.283337ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:52.445014Z","caller":"traceutil/trace.go:171","msg":"trace[1692764693] transaction","detail":"{read_only:false; response_revision:25514; number_of_response:1; }","duration":"183.389826ms","start":"2026-06-30T18:41:52.261584Z","end":"2026-06-30T18:41:52.444974Z","steps":["trace[1692764693] 'process raft request' (duration: 159.074032ms)","trace[1692764693] 'compare' (duration: 24.113988ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:52.445202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.286533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-06-30T18:41:52.445228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.977191ms","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-06-30T18:41:52.445292Z","caller":"traceutil/trace.go:171","msg":"trace[169477157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25514; }","duration":"180.054283ms","start":"2026-06-30T18:41:52.265224Z","end":"2026-06-30T18:41:52.445279Z","steps":["trace[169477157] 'agreement among raft nodes before linearized reading' (duration: 179.827267ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:52.445331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.024177ms","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-06-30T18:41:52.445369Z","caller":"traceutil/trace.go:171","msg":"trace[974394489] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25514; }","duration":"165.087248ms","start":"2026-06-30T18:41:52.280273Z","end":"2026-06-30T18:41:52.44536Z","steps":["trace[974394489] 'agreement among raft nodes before linearized reading' (duration: 164.988605ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:52.445243Z","caller":"traceutil/trace.go:171","msg":"trace[1390613238] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25514; }","duration":"170.373915ms","start":"2026-06-30T18:41:52.274859Z","end":"2026-06-30T18:41:52.445232Z","steps":["trace[1390613238] 'agreement among raft nodes before linearized reading' (duration: 170.19829ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:52.708694Z","caller":"traceutil/trace.go:171","msg":"trace[1223238] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"258.675794ms","start":"2026-06-30T18:41:52.449994Z","end":"2026-06-30T18:41:52.708669Z","steps":["trace[1223238] 'process raft request' (duration: 207.924507ms)","trace[1223238] 'compare' (duration: 50.630694ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:52.708886Z","caller":"traceutil/trace.go:171","msg":"trace[170741272] transaction","detail":"{read_only:false; response_revision:25517; number_of_response:1; }","duration":"258.299243ms","start":"2026-06-30T18:41:52.450579Z","end":"2026-06-30T18:41:52.708879Z","steps":["trace[170741272] 'process raft request' (duration: 258.058656ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:52.708985Z","caller":"traceutil/trace.go:171","msg":"trace[1299251354] linearizableReadLoop","detail":"{readStateIndex:26327; appliedIndex:26324; }","duration":"223.702724ms","start":"2026-06-30T18:41:52.485276Z","end":"2026-06-30T18:41:52.708979Z","steps":["trace[1299251354] 'read index received' (duration: 172.658528ms)","trace[1299251354] 'applied index is now lower than readState.Index' (duration: 51.043466ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:52.709042Z","caller":"traceutil/trace.go:171","msg":"trace[1875702005] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"227.654313ms","start":"2026-06-30T18:41:52.481382Z","end":"2026-06-30T18:41:52.709036Z","steps":["trace[1875702005] 'process raft request' (duration: 227.466498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:52.709148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.852898ms","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-06-30T18:41:52.709172Z","caller":"traceutil/trace.go:171","msg":"trace[1022983925] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25518; }","duration":"223.92047ms","start":"2026-06-30T18:41:52.485243Z","end":"2026-06-30T18:41:52.709164Z","steps":["trace[1022983925] 'agreement among raft nodes before linearized reading' (duration: 223.861848ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:53.041617Z","caller":"traceutil/trace.go:171","msg":"trace[480363446] transaction","detail":"{read_only:false; response_revision:25519; number_of_response:1; }","duration":"553.764987ms","start":"2026-06-30T18:41:52.487824Z","end":"2026-06-30T18:41:53.041589Z","steps":["trace[480363446] 'process raft request' (duration: 553.600243ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:53.041779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:52.4878Z","time spent":"553.896591ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:41:53.041654Z","caller":"traceutil/trace.go:171","msg":"trace[423698170] linearizableReadLoop","detail":"{readStateIndex:26328; appliedIndex:26328; }","duration":"332.626365ms","start":"2026-06-30T18:41:52.708999Z","end":"2026-06-30T18:41:53.041625Z","steps":["trace[423698170] 'read index received' (duration: 332.616015ms)","trace[423698170] 'applied index is now lower than readState.Index' (duration: 7.95µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:53.041871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.241518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17940"} {"level":"warn","ts":"2026-06-30T18:41:53.041949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.274148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:41:53.041987Z","caller":"traceutil/trace.go:171","msg":"trace[478423932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25519; }","duration":"503.35183ms","start":"2026-06-30T18:41:52.538624Z","end":"2026-06-30T18:41:53.041975Z","steps":["trace[478423932] 'agreement among raft nodes before linearized reading' (duration: 503.284668ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:53.042015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:52.538568Z","time spent":"503.440452ms","remote":"127.0.0.1:37812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-30T18:41:53.042016Z","caller":"traceutil/trace.go:171","msg":"trace[1256985888] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25519; }","duration":"401.381322ms","start":"2026-06-30T18:41:52.6406Z","end":"2026-06-30T18:41:53.041981Z","steps":["trace[1256985888] 'agreement among raft nodes before linearized reading' (duration: 401.063783ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:53.042131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:52.64055Z","time spent":"401.541396ms","remote":"127.0.0.1:38004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":17963,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-30T18:41:53.042257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.097335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-06-30T18:41:53.042361Z","caller":"traceutil/trace.go:171","msg":"trace[2097432240] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25519; }","duration":"312.225409ms","start":"2026-06-30T18:41:52.730117Z","end":"2026-06-30T18:41:53.042343Z","steps":["trace[2097432240] 'agreement among raft nodes before linearized reading' (duration: 311.842248ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:53.042469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:52.730104Z","time spent":"312.350802ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":597,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-06-30T18:41:53.42606Z","caller":"traceutil/trace.go:171","msg":"trace[426873998] linearizableReadLoop","detail":"{readStateIndex:26331; appliedIndex:26330; }","duration":"171.021603ms","start":"2026-06-30T18:41:53.255008Z","end":"2026-06-30T18:41:53.42603Z","steps":["trace[426873998] 'read index received' (duration: 169.46855ms)","trace[426873998] 'applied index is now lower than readState.Index' (duration: 1.550853ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:53.426143Z","caller":"traceutil/trace.go:171","msg":"trace[1255151446] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"190.868483ms","start":"2026-06-30T18:41:53.235239Z","end":"2026-06-30T18:41:53.426108Z","steps":["trace[1255151446] 'process raft request' (duration: 189.258439ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:53.426204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.174307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:41:53.426246Z","caller":"traceutil/trace.go:171","msg":"trace[642755432] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:25522; }","duration":"171.282301ms","start":"2026-06-30T18:41:53.254951Z","end":"2026-06-30T18:41:53.426234Z","steps":["trace[642755432] 'agreement among raft nodes before linearized reading' (duration: 171.188048ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:54.140292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.986652ms","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-06-30T18:41:54.140381Z","caller":"traceutil/trace.go:171","msg":"trace[311022254] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25523; }","duration":"149.128786ms","start":"2026-06-30T18:41:53.991232Z","end":"2026-06-30T18:41:54.140361Z","steps":["trace[311022254] 'range keys from in-memory index tree' (duration: 148.764996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:54.371095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.336376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-30T18:41:54.371161Z","caller":"traceutil/trace.go:171","msg":"trace[1287629877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25524; }","duration":"112.455929ms","start":"2026-06-30T18:41:54.258689Z","end":"2026-06-30T18:41:54.371145Z","steps":["trace[1287629877] 'range keys from in-memory index tree' (duration: 112.186141ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:54.380984Z","caller":"traceutil/trace.go:171","msg":"trace[877393656] transaction","detail":"{read_only:false; response_revision:25525; number_of_response:1; }","duration":"101.953998ms","start":"2026-06-30T18:41:54.279014Z","end":"2026-06-30T18:41:54.380968Z","steps":["trace[877393656] 'process raft request' (duration: 101.822924ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:54.549366Z","caller":"traceutil/trace.go:171","msg":"trace[722669461] transaction","detail":"{read_only:false; response_revision:25526; number_of_response:1; }","duration":"172.403652ms","start":"2026-06-30T18:41:54.376925Z","end":"2026-06-30T18:41:54.549329Z","steps":["trace[722669461] 'process raft request' (duration: 105.970469ms)","trace[722669461] 'compare' (duration: 66.013521ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:54.554778Z","caller":"traceutil/trace.go:171","msg":"trace[678935757] transaction","detail":"{read_only:false; response_revision:25527; number_of_response:1; }","duration":"103.771298ms","start":"2026-06-30T18:41:54.450977Z","end":"2026-06-30T18:41:54.554748Z","steps":["trace[678935757] 'process raft request' (duration: 103.601923ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:55.242788Z","caller":"traceutil/trace.go:171","msg":"trace[996587608] transaction","detail":"{read_only:false; response_revision:25530; number_of_response:1; }","duration":"525.266297ms","start":"2026-06-30T18:41:54.717486Z","end":"2026-06-30T18:41:55.242752Z","steps":["trace[996587608] 'process raft request' (duration: 490.863743ms)","trace[996587608] 'compare' (duration: 34.140317ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:55.242914Z","caller":"traceutil/trace.go:171","msg":"trace[510085287] linearizableReadLoop","detail":"{readStateIndex:26340; appliedIndex:26338; }","duration":"261.748419ms","start":"2026-06-30T18:41:54.981137Z","end":"2026-06-30T18:41:55.242886Z","steps":["trace[510085287] 'read index received' (duration: 227.226752ms)","trace[510085287] 'applied index is now lower than readState.Index' (duration: 34.519917ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:55.243003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:54.717466Z","time spent":"525.427681ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:41:55.243038Z","caller":"traceutil/trace.go:171","msg":"trace[384566244] transaction","detail":"{read_only:false; response_revision:25531; number_of_response:1; }","duration":"524.884977ms","start":"2026-06-30T18:41:54.718109Z","end":"2026-06-30T18:41:55.242994Z","steps":["trace[384566244] 'process raft request' (duration: 524.576319ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:55.243214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:54.718104Z","time spent":"525.018061ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:41:55.243078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.921814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:41:55.243347Z","caller":"traceutil/trace.go:171","msg":"trace[1565162290] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:25531; }","duration":"262.269594ms","start":"2026-06-30T18:41:54.981064Z","end":"2026-06-30T18:41:55.243333Z","steps":["trace[1565162290] 'agreement among raft nodes before linearized reading' (duration: 261.928894ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:55.62632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.261654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:41:55.626625Z","caller":"traceutil/trace.go:171","msg":"trace[53410658] transaction","detail":"{read_only:false; response_revision:25532; number_of_response:1; }","duration":"578.408931ms","start":"2026-06-30T18:41:55.048179Z","end":"2026-06-30T18:41:55.626588Z","steps":["trace[53410658] 'process raft request' (duration: 302.789037ms)","trace[53410658] 'compare' (duration: 274.515983ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:55.626811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:55.048158Z","time spent":"578.552264ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:41:55.834982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.529103ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-30T18:41:55.835522Z","caller":"traceutil/trace.go:171","msg":"trace[2034868788] linearizableReadLoop","detail":"{readStateIndex:26343; appliedIndex:26340; }","duration":"592.527632ms","start":"2026-06-30T18:41:55.242965Z","end":"2026-06-30T18:41:55.835493Z","steps":["trace[2034868788] 'read index received' (duration: 107.952133ms)","trace[2034868788] 'applied index is now lower than readState.Index' (duration: 484.573079ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:55.835552Z","caller":"traceutil/trace.go:171","msg":"trace[1793611839] transaction","detail":"{read_only:false; response_revision:25533; number_of_response:1; }","duration":"630.081884ms","start":"2026-06-30T18:41:55.205425Z","end":"2026-06-30T18:41:55.835506Z","steps":["trace[1793611839] 'process raft request' (duration: 629.673912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:55.835737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:55.205396Z","time spent":"630.240167ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:41:56.024432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.609937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:41:56.024504Z","caller":"traceutil/trace.go:171","msg":"trace[48206757] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25533; }","duration":"388.771802ms","start":"2026-06-30T18:41:55.63571Z","end":"2026-06-30T18:41:56.024482Z","steps":["trace[48206757] 'agreement among raft nodes before linearized reading' (duration: 200.015717ms)","trace[48206757] 'count revisions from in-memory index tree' (duration: 188.643042ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:56.024561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:55.635685Z","time spent":"388.838754ms","remote":"127.0.0.1:45394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"warn","ts":"2026-06-30T18:41:56.024773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.637491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:41:56.024814Z","caller":"traceutil/trace.go:171","msg":"trace[1972425716] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:25533; }","duration":"792.719814ms","start":"2026-06-30T18:41:55.232081Z","end":"2026-06-30T18:41:56.0248Z","steps":["trace[1972425716] 'agreement among raft nodes before linearized reading' (duration: 603.452675ms)","trace[1972425716] 'count revisions from in-memory index tree' (duration: 189.179907ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:56.024797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.854727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"warn","ts":"2026-06-30T18:41:56.024847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:55.232058Z","time spent":"792.778705ms","remote":"127.0.0.1:51954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-06-30T18:41:56.024829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.737578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-06-30T18:41:56.024828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.970709ms","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:6"} {"level":"warn","ts":"2026-06-30T18:41:56.024882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.714719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-06-30T18:41:56.024884Z","caller":"traceutil/trace.go:171","msg":"trace[2128415722] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25533; }","duration":"669.996101ms","start":"2026-06-30T18:41:55.354865Z","end":"2026-06-30T18:41:56.024861Z","steps":["trace[2128415722] 'agreement among raft nodes before linearized reading' (duration: 480.810944ms)","trace[2128415722] 'range keys from in-memory index tree' (duration: 188.965111ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:56.0249Z","caller":"traceutil/trace.go:171","msg":"trace[1078060294] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25533; }","duration":"592.885953ms","start":"2026-06-30T18:41:55.431995Z","end":"2026-06-30T18:41:56.024881Z","steps":["trace[1078060294] 'agreement among raft nodes before linearized reading' (duration: 403.732997ms)","trace[1078060294] 'range keys from in-memory index tree' (duration: 188.971261ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:56.024916Z","caller":"traceutil/trace.go:171","msg":"trace[650747053] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:25533; }","duration":"212.109783ms","start":"2026-06-30T18:41:55.812782Z","end":"2026-06-30T18:41:56.024892Z","steps":["trace[650747053] 'agreement among raft nodes before linearized reading' (duration: 22.911786ms)","trace[650747053] 'count revisions from in-memory index tree' (duration: 189.059354ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:56.024931Z","caller":"traceutil/trace.go:171","msg":"trace[1596320110] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25533; }","duration":"931.854783ms","start":"2026-06-30T18:41:55.093061Z","end":"2026-06-30T18:41:56.024915Z","steps":["trace[1596320110] 'agreement among raft nodes before linearized reading' (duration: 742.583453ms)","trace[1596320110] 'range keys from in-memory index tree' (duration: 189.141035ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:56.024937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:55.431963Z","time spent":"592.967555ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-30T18:41:56.02494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:55.354849Z","time spent":"670.081434ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":584,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-06-30T18:41:56.024961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:55.093037Z","time spent":"931.914235ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":597,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-06-30T18:41:56.025146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.142538ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:41:56.025207Z","caller":"traceutil/trace.go:171","msg":"trace[777662788] transaction","detail":"{read_only:false; response_revision:25534; number_of_response:1; }","duration":"143.644574ms","start":"2026-06-30T18:41:55.881549Z","end":"2026-06-30T18:41:56.025193Z","steps":["trace[777662788] 'process raft request' (duration: 17.363302ms)","trace[777662788] 'compare' (duration: 125.566672ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:56.025388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.414242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-06-30T18:41:56.025433Z","caller":"traceutil/trace.go:171","msg":"trace[1777875663] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:25533; }","duration":"379.498104ms","start":"2026-06-30T18:41:55.645921Z","end":"2026-06-30T18:41:56.025419Z","steps":["trace[1777875663] 'agreement among raft nodes before linearized reading' (duration: 189.795263ms)","trace[1777875663] 'range keys from in-memory index tree' (duration: 188.974751ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:56.025466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:55.6459Z","time spent":"379.557827ms","remote":"127.0.0.1:37984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" "} {"level":"warn","ts":"2026-06-30T18:41:56.359291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.849684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:41:56.35982Z","caller":"traceutil/trace.go:171","msg":"trace[1243470993] transaction","detail":"{read_only:false; response_revision:25537; number_of_response:1; }","duration":"328.787429ms","start":"2026-06-30T18:41:56.031013Z","end":"2026-06-30T18:41:56.359801Z","steps":["trace[1243470993] 'process raft request' (duration: 328.727487ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:56.35984Z","caller":"traceutil/trace.go:171","msg":"trace[553030126] transaction","detail":"{read_only:false; response_revision:25535; number_of_response:1; }","duration":"330.446045ms","start":"2026-06-30T18:41:56.029358Z","end":"2026-06-30T18:41:56.359804Z","steps":["trace[553030126] 'process raft request' (duration: 147.977694ms)","trace[553030126] 'compare' (duration: 181.739111ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:56.360025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:56.030994Z","time spent":"328.876521ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:41:56.360072Z","caller":"traceutil/trace.go:171","msg":"trace[886998029] linearizableReadLoop","detail":"{readStateIndex:26345; appliedIndex:26344; }","duration":"330.439164ms","start":"2026-06-30T18:41:56.029598Z","end":"2026-06-30T18:41:56.360037Z","steps":["trace[886998029] 'read index received' (duration: 147.868951ms)","trace[886998029] 'applied index is now lower than readState.Index' (duration: 182.567123ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:56.36012Z","caller":"traceutil/trace.go:171","msg":"trace[1112066471] transaction","detail":"{read_only:false; response_revision:25536; number_of_response:1; }","duration":"329.20003ms","start":"2026-06-30T18:41:56.030885Z","end":"2026-06-30T18:41:56.360085Z","steps":["trace[1112066471] 'process raft request' (duration: 328.508561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:56.360184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:56.029323Z","time spent":"330.63672ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:41:56.360276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:56.030865Z","time spent":"329.330884ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:41:56.360471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.838485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-351d2afe-3390-425b-b56b-140d40add5a6\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-06-30T18:41:56.360588Z","caller":"traceutil/trace.go:171","msg":"trace[2027239251] range","detail":"{range_begin:/registry/persistentvolumes/pvc-351d2afe-3390-425b-b56b-140d40add5a6; range_end:; response_count:1; response_revision:25537; }","duration":"331.042961ms","start":"2026-06-30T18:41:56.029527Z","end":"2026-06-30T18:41:56.36057Z","steps":["trace[2027239251] 'agreement among raft nodes before linearized reading' (duration: 330.62707ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:56.360694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:56.029498Z","time spent":"331.168705ms","remote":"127.0.0.1:37972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-351d2afe-3390-425b-b56b-140d40add5a6\" "} {"level":"warn","ts":"2026-06-30T18:41:56.379495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.852202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-06-30T18:41:56.37955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.045628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T18:41:56.379608Z","caller":"traceutil/trace.go:171","msg":"trace[581178329] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25538; }","duration":"182.147141ms","start":"2026-06-30T18:41:56.197451Z","end":"2026-06-30T18:41:56.379598Z","steps":["trace[581178329] 'agreement among raft nodes before linearized reading' (duration: 182.050118ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:56.37961Z","caller":"traceutil/trace.go:171","msg":"trace[76053635] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25538; }","duration":"270.989655ms","start":"2026-06-30T18:41:56.108588Z","end":"2026-06-30T18:41:56.379578Z","steps":["trace[76053635] 'agreement among raft nodes before linearized reading' (duration: 270.811051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:56.379561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.597807ms","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-06-30T18:41:56.379658Z","caller":"traceutil/trace.go:171","msg":"trace[1927782612] transaction","detail":"{read_only:false; response_revision:25538; number_of_response:1; }","duration":"136.201098ms","start":"2026-06-30T18:41:56.243421Z","end":"2026-06-30T18:41:56.379622Z","steps":["trace[1927782612] 'process raft request' (duration: 135.874029ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:56.379706Z","caller":"traceutil/trace.go:171","msg":"trace[1507536521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25538; }","duration":"163.766702ms","start":"2026-06-30T18:41:56.215922Z","end":"2026-06-30T18:41:56.379689Z","steps":["trace[1507536521] 'agreement among raft nodes before linearized reading' (duration: 163.559396ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:56.518634Z","caller":"traceutil/trace.go:171","msg":"trace[645263793] transaction","detail":"{read_only:false; response_revision:25540; number_of_response:1; }","duration":"135.144908ms","start":"2026-06-30T18:41:56.383461Z","end":"2026-06-30T18:41:56.518606Z","steps":["trace[645263793] 'process raft request' (duration: 95.406946ms)","trace[645263793] 'compare' (duration: 39.320871ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:56.51867Z","caller":"traceutil/trace.go:171","msg":"trace[604934005] transaction","detail":"{read_only:false; response_revision:25541; number_of_response:1; }","duration":"133.275876ms","start":"2026-06-30T18:41:56.385378Z","end":"2026-06-30T18:41:56.518654Z","steps":["trace[604934005] 'process raft request' (duration: 132.940177ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:56.735395Z","caller":"traceutil/trace.go:171","msg":"trace[1922710966] transaction","detail":"{read_only:false; response_revision:25544; number_of_response:1; }","duration":"119.319339ms","start":"2026-06-30T18:41:56.616047Z","end":"2026-06-30T18:41:56.735367Z","steps":["trace[1922710966] 'process raft request' (duration: 94.393118ms)","trace[1922710966] 'compare' (duration: 24.711306ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:56.999542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.657686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:41:56.999659Z","caller":"traceutil/trace.go:171","msg":"trace[2077568492] transaction","detail":"{read_only:false; response_revision:25545; number_of_response:1; }","duration":"217.147873ms","start":"2026-06-30T18:41:56.782491Z","end":"2026-06-30T18:41:56.999639Z","steps":["trace[2077568492] 'process raft request' (duration: 74.307041ms)","trace[2077568492] 'compare' (duration: 142.4039ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:57.260917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.473209ms","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-06-30T18:41:57.261006Z","caller":"traceutil/trace.go:171","msg":"trace[1352362840] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25545; }","duration":"112.620623ms","start":"2026-06-30T18:41:57.148359Z","end":"2026-06-30T18:41:57.260979Z","steps":["trace[1352362840] 'range keys from in-memory index tree' (duration: 112.395967ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:57.418107Z","caller":"traceutil/trace.go:171","msg":"trace[459039012] transaction","detail":"{read_only:false; response_revision:25546; number_of_response:1; }","duration":"153.515027ms","start":"2026-06-30T18:41:57.264566Z","end":"2026-06-30T18:41:57.418081Z","steps":["trace[459039012] 'process raft request' (duration: 153.372703ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:57.583725Z","caller":"traceutil/trace.go:171","msg":"trace[1411245731] linearizableReadLoop","detail":"{readStateIndex:26357; appliedIndex:26355; }","duration":"314.449861ms","start":"2026-06-30T18:41:57.269255Z","end":"2026-06-30T18:41:57.583705Z","steps":["trace[1411245731] 'read index received' (duration: 148.806757ms)","trace[1411245731] 'applied index is now lower than readState.Index' (duration: 165.642244ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:41:57.583765Z","caller":"traceutil/trace.go:171","msg":"trace[134942179] transaction","detail":"{read_only:false; response_revision:25547; number_of_response:1; }","duration":"319.11308ms","start":"2026-06-30T18:41:57.264608Z","end":"2026-06-30T18:41:57.583721Z","steps":["trace[134942179] 'process raft request' (duration: 232.947261ms)","trace[134942179] 'compare' (duration: 85.965463ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:41:57.583899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.628395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-06-30T18:41:57.583945Z","caller":"traceutil/trace.go:171","msg":"trace[702950018] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25547; }","duration":"314.699017ms","start":"2026-06-30T18:41:57.269236Z","end":"2026-06-30T18:41:57.583935Z","steps":["trace[702950018] 'agreement among raft nodes before linearized reading' (duration: 314.548263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:41:57.583969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:57.269228Z","time spent":"314.735959ms","remote":"127.0.0.1:38014","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-06-30T18:41:57.583948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:41:57.26459Z","time spent":"319.266054ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:41:59.553546Z","caller":"traceutil/trace.go:171","msg":"trace[1885921379] transaction","detail":"{read_only:false; response_revision:25562; number_of_response:1; }","duration":"105.699962ms","start":"2026-06-30T18:41:59.447813Z","end":"2026-06-30T18:41:59.553513Z","steps":["trace[1885921379] 'process raft request' (duration: 105.444005ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:59.796409Z","caller":"traceutil/trace.go:171","msg":"trace[511262370] transaction","detail":"{read_only:false; response_revision:25564; number_of_response:1; }","duration":"112.963522ms","start":"2026-06-30T18:41:59.683426Z","end":"2026-06-30T18:41:59.79639Z","steps":["trace[511262370] 'process raft request' (duration: 112.922981ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:41:59.79666Z","caller":"traceutil/trace.go:171","msg":"trace[1428453541] transaction","detail":"{read_only:false; response_revision:25563; number_of_response:1; }","duration":"203.901645ms","start":"2026-06-30T18:41:59.59275Z","end":"2026-06-30T18:41:59.796652Z","steps":["trace[1428453541] 'process raft request' (duration: 109.023854ms)","trace[1428453541] 'compare' (duration: 94.255544ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:42:03.163961Z","caller":"traceutil/trace.go:171","msg":"trace[743618264] linearizableReadLoop","detail":"{readStateIndex:26400; appliedIndex:26399; }","duration":"118.392383ms","start":"2026-06-30T18:42:03.045544Z","end":"2026-06-30T18:42:03.163936Z","steps":["trace[743618264] 'read index received' (duration: 118.136046ms)","trace[743618264] 'applied index is now lower than readState.Index' (duration: 254.957µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:42:03.163997Z","caller":"traceutil/trace.go:171","msg":"trace[1011335617] transaction","detail":"{read_only:false; response_revision:25589; number_of_response:1; }","duration":"149.230998ms","start":"2026-06-30T18:42:03.014739Z","end":"2026-06-30T18:42:03.16397Z","steps":["trace[1011335617] 'process raft request' (duration: 148.982151ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:42:03.164173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.619629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-06-30T18:42:03.164224Z","caller":"traceutil/trace.go:171","msg":"trace[1534113933] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25589; }","duration":"118.736483ms","start":"2026-06-30T18:42:03.045476Z","end":"2026-06-30T18:42:03.164213Z","steps":["trace[1534113933] 'agreement among raft nodes before linearized reading' (duration: 118.574268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:42:03.164513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.737548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T18:42:03.164637Z","caller":"traceutil/trace.go:171","msg":"trace[332039305] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:25589; }","duration":"116.940213ms","start":"2026-06-30T18:42:03.047671Z","end":"2026-06-30T18:42:03.164611Z","steps":["trace[332039305] 'agreement among raft nodes before linearized reading' (duration: 116.692006ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:42:04.884678Z","caller":"traceutil/trace.go:171","msg":"trace[1874407462] transaction","detail":"{read_only:false; response_revision:25601; number_of_response:1; }","duration":"112.209812ms","start":"2026-06-30T18:42:04.772425Z","end":"2026-06-30T18:42:04.884635Z","steps":["trace[1874407462] 'process raft request' (duration: 112.093979ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:42:20.232079Z","caller":"traceutil/trace.go:171","msg":"trace[316022379] linearizableReadLoop","detail":"{readStateIndex:26538; appliedIndex:26537; }","duration":"111.573764ms","start":"2026-06-30T18:42:20.120466Z","end":"2026-06-30T18:42:20.232039Z","steps":["trace[316022379] 'read index received' (duration: 68.411237ms)","trace[316022379] 'applied index is now lower than readState.Index' (duration: 43.160787ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:42:20.232276Z","caller":"traceutil/trace.go:171","msg":"trace[549679118] transaction","detail":"{read_only:false; response_revision:25723; number_of_response:1; }","duration":"130.375266ms","start":"2026-06-30T18:42:20.101805Z","end":"2026-06-30T18:42:20.23218Z","steps":["trace[549679118] 'process raft request' (duration: 87.058325ms)","trace[549679118] 'compare' (duration: 42.716154ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:42:20.232284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.787041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:42:20.232379Z","caller":"traceutil/trace.go:171","msg":"trace[1584275895] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:25723; }","duration":"111.951245ms","start":"2026-06-30T18:42:20.120407Z","end":"2026-06-30T18:42:20.232359Z","steps":["trace[1584275895] 'agreement among raft nodes before linearized reading' (duration: 111.77091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:42:25.589736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.316373ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-30T18:43:36.862647Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23594} {"level":"info","ts":"2026-06-30T18:43:36.999388Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23594,"took":"133.303197ms","hash":2796759224,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":38998016,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-06-30T18:43:36.999446Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2796759224,"revision":23594,"compact-revision":20435} {"level":"info","ts":"2026-06-30T18:48:24.506081Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e46287fb5aa4785","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-30T18:48:24.510508Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-30T18:48:24.510584Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-30T18:48:36.868681Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26513} {"level":"info","ts":"2026-06-30T18:48:37.012061Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26513,"took":"138.578733ms","hash":294549181,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":35426304,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-30T18:48:37.012133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":294549181,"revision":26513,"compact-revision":23594} {"level":"info","ts":"2026-06-30T18:53:36.875233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29192} {"level":"info","ts":"2026-06-30T18:53:37.008946Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29192,"took":"128.79639ms","hash":3507746083,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":31227904,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-30T18:53:37.009045Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3507746083,"revision":29192,"compact-revision":26513} {"level":"info","ts":"2026-06-30T18:58:36.882211Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31450} {"level":"info","ts":"2026-06-30T18:58:37.008492Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31450,"took":"121.568579ms","hash":199730194,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":31354880,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-30T18:58:37.00856Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":199730194,"revision":31450,"compact-revision":29192} {"level":"warn","ts":"2026-06-30T19:00:37.096342Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153700281174930067,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-30T19:00:37.596452Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153700281174930067,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-30T19:00:38.097126Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153700281174930067,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-30T19:00:38.555953Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.26926232s","expected-duration":"1s"} {"level":"info","ts":"2026-06-30T19:00:38.556435Z","caller":"traceutil/trace.go:171","msg":"trace[2145787806] transaction","detail":"{read_only:false; response_revision:34728; number_of_response:1; }","duration":"2.269819495s","start":"2026-06-30T19:00:36.286593Z","end":"2026-06-30T19:00:38.556413Z","steps":["trace[2145787806] 'process raft request' (duration: 2.269670461s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T19:00:38.556645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T19:00:36.286576Z","time spent":"2.269915208s","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T19:00:38.561489Z","caller":"traceutil/trace.go:171","msg":"trace[1559745382] transaction","detail":"{read_only:false; response_revision:34729; number_of_response:1; }","duration":"2.079265369s","start":"2026-06-30T19:00:36.482178Z","end":"2026-06-30T19:00:38.561443Z","steps":["trace[1559745382] 'process raft request' (duration: 2.079041282s)"],"step_count":1} {"level":"info","ts":"2026-06-30T19:00:38.561571Z","caller":"traceutil/trace.go:171","msg":"trace[1827937135] transaction","detail":"{read_only:false; response_revision:34733; number_of_response:1; }","duration":"888.403076ms","start":"2026-06-30T19:00:37.673148Z","end":"2026-06-30T19:00:38.561551Z","steps":["trace[1827937135] 'process raft request' (duration: 888.312923ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T19:00:38.561655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T19:00:36.482155Z","time spent":"2.079417242s","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T19:00:38.561705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T19:00:37.67306Z","time spent":"888.553929ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T19:00:38.561791Z","caller":"traceutil/trace.go:171","msg":"trace[1632884551] transaction","detail":"{read_only:false; response_revision:34731; number_of_response:1; }","duration":"2.044782269s","start":"2026-06-30T19:00:36.516998Z","end":"2026-06-30T19:00:38.561781Z","steps":["trace[1632884551] 'process raft request' (duration: 2.044382578s)"],"step_count":1} {"level":"info","ts":"2026-06-30T19:00:38.561821Z","caller":"traceutil/trace.go:171","msg":"trace[1974006745] transaction","detail":"{read_only:false; response_revision:34732; number_of_response:1; }","duration":"1.453830063s","start":"2026-06-30T19:00:37.10798Z","end":"2026-06-30T19:00:38.56181Z","steps":["trace[1974006745] 'process raft request' (duration: 1.453441532s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T19:00:38.561863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T19:00:36.516992Z","time spent":"2.044827641s","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T19:00:38.561881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T19:00:37.107957Z","time spent":"1.453895314s","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T19:00:38.561988Z","caller":"traceutil/trace.go:171","msg":"trace[591270953] transaction","detail":"{read_only:false; response_revision:34730; number_of_response:1; }","duration":"2.048012569s","start":"2026-06-30T19:00:36.513963Z","end":"2026-06-30T19:00:38.561975Z","steps":["trace[591270953] 'process raft request' (duration: 2.047374392s)"],"step_count":1} {"level":"info","ts":"2026-06-30T19:00:38.562013Z","caller":"traceutil/trace.go:171","msg":"trace[1303964136] transaction","detail":"{read_only:false; response_revision:34734; number_of_response:1; }","duration":"845.933725ms","start":"2026-06-30T19:00:37.716058Z","end":"2026-06-30T19:00:38.561991Z","steps":["trace[1303964136] 'process raft request' (duration: 845.438761ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T19:00:38.562058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T19:00:36.513943Z","time spent":"2.048076472s","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T19:00:38.562147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T19:00:37.716042Z","time spent":"846.037217ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T19:00:38.562347Z","caller":"traceutil/trace.go:171","msg":"trace[139223512] linearizableReadLoop","detail":"{readStateIndex:35800; appliedIndex:35791; }","duration":"1.966604057s","start":"2026-06-30T19:00:36.595735Z","end":"2026-06-30T19:00:38.562339Z","steps":["trace[139223512] 'read index received' (duration: 1.960522838s)","trace[139223512] 'applied index is now lower than readState.Index' (duration: 6.080599ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T19:00:38.562362Z","caller":"traceutil/trace.go:171","msg":"trace[254404446] transaction","detail":"{read_only:false; response_revision:34735; number_of_response:1; }","duration":"596.241044ms","start":"2026-06-30T19:00:37.966108Z","end":"2026-06-30T19:00:38.562349Z","steps":["trace[254404446] 'process raft request' (duration: 595.825082ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T19:00:38.562424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T19:00:37.966058Z","time spent":"596.332146ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T19:00:38.562456Z","caller":"traceutil/trace.go:171","msg":"trace[655617127] transaction","detail":"{read_only:false; response_revision:34736; number_of_response:1; }","duration":"536.143324ms","start":"2026-06-30T19:00:38.026271Z","end":"2026-06-30T19:00:38.562414Z","steps":["trace[655617127] 'process raft request' (duration: 536.01379ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T19:00:38.562526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.966788192s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-06-30T19:00:38.562554Z","caller":"traceutil/trace.go:171","msg":"trace[1348584795] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:34736; }","duration":"1.966853443s","start":"2026-06-30T19:00:36.595692Z","end":"2026-06-30T19:00:38.562545Z","steps":["trace[1348584795] 'agreement among raft nodes before linearized reading' (duration: 1.96673132s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T19:00:38.562576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T19:00:36.595662Z","time spent":"1.966909355s","remote":"127.0.0.1:37984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1873,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" "} {"level":"warn","ts":"2026-06-30T19:00:38.562601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T19:00:38.026252Z","time spent":"536.286407ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T19:00:38.562604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.775177ms","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-06-30T19:00:38.562657Z","caller":"traceutil/trace.go:171","msg":"trace[873502218] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:34736; }","duration":"724.871469ms","start":"2026-06-30T19:00:37.837771Z","end":"2026-06-30T19:00:38.562642Z","steps":["trace[873502218] 'agreement among raft nodes before linearized reading' (duration: 724.724165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T19:00:38.562701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T19:00:37.837749Z","time spent":"724.942031ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-30T19:00:38.562735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.687573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-30T19:00:38.562764Z","caller":"traceutil/trace.go:171","msg":"trace[1552142989] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:34736; }","duration":"602.742104ms","start":"2026-06-30T19:00:37.960013Z","end":"2026-06-30T19:00:38.562755Z","steps":["trace[1552142989] 'agreement among raft nodes before linearized reading' (duration: 602.679292ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T19:00:38.562787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T19:00:37.959999Z","time spent":"602.781726ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-30T19:00:38.562772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.343204988s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-30T19:00:38.562786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025395234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T19:00:38.562843Z","caller":"traceutil/trace.go:171","msg":"trace[631935010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:34736; }","duration":"1.34331053s","start":"2026-06-30T19:00:37.219513Z","end":"2026-06-30T19:00:38.562824Z","steps":["trace[631935010] 'agreement among raft nodes before linearized reading' (duration: 1.343031632s)"],"step_count":1} {"level":"info","ts":"2026-06-30T19:00:38.562851Z","caller":"traceutil/trace.go:171","msg":"trace[1049220161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:34736; }","duration":"1.025500196s","start":"2026-06-30T19:00:37.537331Z","end":"2026-06-30T19:00:38.562832Z","steps":["trace[1049220161] 'agreement among raft nodes before linearized reading' (duration: 1.025375693s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T19:00:38.56288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T19:00:37.219499Z","time spent":"1.343375702s","remote":"127.0.0.1:37812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-30T19:00:38.562883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T19:00:37.53731Z","time spent":"1.025565787s","remote":"127.0.0.1:37816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-30T19:00:38.562911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.869020264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T19:00:38.562953Z","caller":"traceutil/trace.go:171","msg":"trace[1149114662] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:34736; }","duration":"1.869067985s","start":"2026-06-30T19:00:36.693875Z","end":"2026-06-30T19:00:38.562943Z","steps":["trace[1149114662] 'agreement among raft nodes before linearized reading' (duration: 1.868990923s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T19:00:38.562975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T19:00:36.69383Z","time spent":"1.869139617s","remote":"127.0.0.1:48396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true "} {"level":"warn","ts":"2026-06-30T19:00:38.563048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.289716ms","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-06-30T19:00:38.563092Z","caller":"traceutil/trace.go:171","msg":"trace[1221444005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:34736; }","duration":"650.370448ms","start":"2026-06-30T19:00:37.912711Z","end":"2026-06-30T19:00:38.563081Z","steps":["trace[1221444005] 'agreement among raft nodes before linearized reading' (duration: 650.056559ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T19:00:38.563121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T19:00:37.912689Z","time spent":"650.42454ms","remote":"127.0.0.1:38076","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-06-30T19:00:38.563134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.20335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-30T19:00:38.563174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.343486795s","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-06-30T19:00:38.563237Z","caller":"traceutil/trace.go:171","msg":"trace[1136230247] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:34736; }","duration":"157.314723ms","start":"2026-06-30T19:00:38.405902Z","end":"2026-06-30T19:00:38.563216Z","steps":["trace[1136230247] 'agreement among raft nodes before linearized reading' (duration: 156.951143ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T19:00:38.563164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.309175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-06-30T19:00:38.563402Z","caller":"traceutil/trace.go:171","msg":"trace[37940133] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:34736; }","duration":"419.640255ms","start":"2026-06-30T19:00:38.143743Z","end":"2026-06-30T19:00:38.563384Z","steps":["trace[37940133] 'agreement among raft nodes before linearized reading' (duration: 418.870603ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T19:00:38.563408Z","caller":"traceutil/trace.go:171","msg":"trace[865241305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:34736; }","duration":"1.343703411s","start":"2026-06-30T19:00:37.219622Z","end":"2026-06-30T19:00:38.563326Z","steps":["trace[865241305] 'agreement among raft nodes before linearized reading' (duration: 1.343123515s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T19:00:38.563449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T19:00:38.143334Z","time spent":"420.102618ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":597,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-06-30T19:00:38.563482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T19:00:37.219618Z","time spent":"1.343828514s","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-30T19:00:38.565685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.67064938s","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-06-30T19:00:38.565855Z","caller":"traceutil/trace.go:171","msg":"trace[601259823] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:34736; }","duration":"1.670849425s","start":"2026-06-30T19:00:36.894987Z","end":"2026-06-30T19:00:38.565836Z","steps":["trace[601259823] 'agreement among raft nodes before linearized reading' (duration: 1.667635625s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T19:00:38.565949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.922588413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-30T19:00:38.566112Z","caller":"traceutil/trace.go:171","msg":"trace[1197097094] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:34736; }","duration":"1.922776668s","start":"2026-06-30T19:00:36.643322Z","end":"2026-06-30T19:00:38.566099Z","steps":["trace[1197097094] 'agreement among raft nodes before linearized reading' (duration: 1.919161868s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T19:00:38.566149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T19:00:36.643307Z","time spent":"1.922830989s","remote":"127.0.0.1:38014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":211,"response size":32,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-06-30T19:00:38.566038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T19:00:36.894972Z","time spent":"1.670912507s","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-30T19:03:36.887743Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33756} {"level":"info","ts":"2026-06-30T19:03:37.04994Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33756,"took":"157.645342ms","hash":17286467,"current-db-size-bytes":59949056,"current-db-size":"60 MB","current-db-size-in-use-bytes":32481280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-30T19:03:37.050071Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":17286467,"revision":33756,"compact-revision":31450} {"level":"info","ts":"2026-06-30T19:08:36.903281Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36035} {"level":"info","ts":"2026-06-30T19:08:37.046784Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36035,"took":"138.901801ms","hash":2974832821,"current-db-size-bytes":60461056,"current-db-size":"60 MB","current-db-size-in-use-bytes":33017856,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-30T19:08:37.046832Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2974832821,"revision":36035,"compact-revision":33756} {"level":"info","ts":"2026-06-30T19:09:23.178332Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e46287fb5aa4785","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-30T19:09:23.182315Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-30T19:09:23.182542Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}