{"level":"warn","ts":"2026-05-11T10:50:39.4045Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T10:50:39.404649Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-11T10:50:39.404751Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T10:50:39.404772Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-05-11T10:50:39.404797Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T10:50:39.405674Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-05-11T10:50:39.405858Z","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.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-11T10:50:39.411135Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.241208ms"} {"level":"info","ts":"2026-05-11T10:50:39.425332Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-05-11T10:50:39.425454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T10:50:39.425481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-05-11T10:50:39.425496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T10:50:39.425513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-05-11T10:50:39.425554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-05-11T10:50:39.434297Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T10:50:39.436225Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T10:50:39.437583Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-11T10:50:39.440669Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T10:50:39.440832Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T10:50:39.440985Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T10:50:39.44111Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T10:50:39.441128Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T10:50:39.442545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-05-11T10:50:39.443271Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-05-11T10:50:39.445115Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T10:50:39.445238Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-05-11T10:50:39.445281Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-05-11T10:50:39.445411Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T10:50:39.445465Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T10:50:40.426601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T10:50:40.42667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T10:50:40.426712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-05-11T10:50:40.42679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-05-11T10:50:40.426809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-05-11T10:50:40.426827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-05-11T10:50:40.426879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-05-11T10:50:40.427924Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T10:50:40.428876Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T10:50:40.428893Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T10:50:40.429097Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T10:50:40.42927Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T10:50:40.429388Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T10:50:40.42979Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T10:50:40.429941Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T10:50:40.429991Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T10:50:40.43275Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-05-11T10:50:40.432882Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T10:50:59.806762Z","caller":"traceutil/trace.go:171","msg":"trace[207414998] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"105.320771ms","start":"2026-05-11T10:50:59.701412Z","end":"2026-05-11T10:50:59.806733Z","steps":["trace[207414998] 'process raft request' (duration: 105.142626ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:51:17.619805Z","caller":"traceutil/trace.go:171","msg":"trace[680566264] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"163.082374ms","start":"2026-05-11T10:51:17.45669Z","end":"2026-05-11T10:51:17.619773Z","steps":["trace[680566264] 'process raft request' (duration: 118.950319ms)","trace[680566264] 'compare' (duration: 43.770599ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:51:17.89961Z","caller":"traceutil/trace.go:171","msg":"trace[1641152219] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"207.609527ms","start":"2026-05-11T10:51:17.691925Z","end":"2026-05-11T10:51:17.899534Z","steps":["trace[1641152219] 'process raft request' (duration: 128.65587ms)","trace[1641152219] 'compare' (duration: 78.678142ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:51:18.295057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.857614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-11T10:51:18.295107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.30993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-11T10:51:18.295194Z","caller":"traceutil/trace.go:171","msg":"trace[2056424154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"215.034116ms","start":"2026-05-11T10:51:18.080112Z","end":"2026-05-11T10:51:18.295146Z","steps":["trace[2056424154] 'range keys from in-memory index tree' (duration: 214.733271ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:51:18.295217Z","caller":"traceutil/trace.go:171","msg":"trace[967701123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"224.465152ms","start":"2026-05-11T10:51:18.070733Z","end":"2026-05-11T10:51:18.295198Z","steps":["trace[967701123] 'range keys from in-memory index tree' (duration: 224.110967ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:51:18.450389Z","caller":"traceutil/trace.go:171","msg":"trace[72844417] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"151.540762ms","start":"2026-05-11T10:51:18.298816Z","end":"2026-05-11T10:51:18.450357Z","steps":["trace[72844417] 'process raft request' (duration: 115.732733ms)","trace[72844417] 'compare' (duration: 35.678377ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:51:18.45767Z","caller":"traceutil/trace.go:171","msg":"trace[2132024512] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"109.643775ms","start":"2026-05-11T10:51:18.348Z","end":"2026-05-11T10:51:18.457644Z","steps":["trace[2132024512] 'process raft request' (duration: 109.082696ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:52:54.200708Z","caller":"traceutil/trace.go:171","msg":"trace[794550419] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"157.582874ms","start":"2026-05-11T10:52:54.043089Z","end":"2026-05-11T10:52:54.200672Z","steps":["trace[794550419] 'process raft request' (duration: 157.37995ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:09.13428Z","caller":"traceutil/trace.go:171","msg":"trace[1034337101] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"109.690286ms","start":"2026-05-11T10:53:09.024535Z","end":"2026-05-11T10:53:09.134225Z","steps":["trace[1034337101] 'process raft request' (duration: 103.205604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:43.046637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.725872ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:53:43.046795Z","caller":"traceutil/trace.go:171","msg":"trace[1493056626] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2138; }","duration":"168.920196ms","start":"2026-05-11T10:53:42.87785Z","end":"2026-05-11T10:53:43.04677Z","steps":["trace[1493056626] 'range keys from in-memory index tree' (duration: 168.704312ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:43.397802Z","caller":"traceutil/trace.go:171","msg":"trace[830230097] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2218; }","duration":"117.459431ms","start":"2026-05-11T10:53:43.280319Z","end":"2026-05-11T10:53:43.397778Z","steps":["trace[830230097] 'read index received' (duration: 18.931914ms)","trace[830230097] 'applied index is now lower than readState.Index' (duration: 98.526497ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:53:43.397863Z","caller":"traceutil/trace.go:171","msg":"trace[1378931633] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"145.547867ms","start":"2026-05-11T10:53:43.25229Z","end":"2026-05-11T10:53:43.397838Z","steps":["trace[1378931633] 'process raft request' (duration: 144.14291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:43.39806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.732286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:53:43.398138Z","caller":"traceutil/trace.go:171","msg":"trace[581172665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2140; }","duration":"117.861188ms","start":"2026-05-11T10:53:43.280264Z","end":"2026-05-11T10:53:43.398125Z","steps":["trace[581172665] 'agreement among raft nodes before linearized reading' (duration: 117.696185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:43.398432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.171427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-11T10:53:43.398529Z","caller":"traceutil/trace.go:171","msg":"trace[2007391774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2140; }","duration":"108.304649ms","start":"2026-05-11T10:53:43.290203Z","end":"2026-05-11T10:53:43.398508Z","steps":["trace[2007391774] 'agreement among raft nodes before linearized reading' (duration: 107.953273ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:57:23.247373Z","caller":"traceutil/trace.go:171","msg":"trace[28714041] linearizableReadLoop","detail":"{readStateIndex:3845; appliedIndex:3844; }","duration":"143.96931ms","start":"2026-05-11T10:57:23.103379Z","end":"2026-05-11T10:57:23.247349Z","steps":["trace[28714041] 'read index received' (duration: 143.913269ms)","trace[28714041] 'applied index is now lower than readState.Index' (duration: 54.791µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:57:23.247715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.307317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-g7vms\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-11T10:57:23.247781Z","caller":"traceutil/trace.go:171","msg":"trace[1817545707] range","detail":"{range_begin:/registry/pods/openstack/keepalived-g7vms; range_end:; response_count:1; response_revision:3706; }","duration":"144.423898ms","start":"2026-05-11T10:57:23.103341Z","end":"2026-05-11T10:57:23.247765Z","steps":["trace[1817545707] 'agreement among raft nodes before linearized reading' (duration: 144.114482ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:57:23.24797Z","caller":"traceutil/trace.go:171","msg":"trace[372421999] transaction","detail":"{read_only:false; response_revision:3706; number_of_response:1; }","duration":"206.731379ms","start":"2026-05-11T10:57:23.041208Z","end":"2026-05-11T10:57:23.24794Z","steps":["trace[372421999] 'process raft request' (duration: 205.970964ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:57:23.300879Z","caller":"traceutil/trace.go:171","msg":"trace[2042827037] transaction","detail":"{read_only:false; response_revision:3707; number_of_response:1; }","duration":"112.490355ms","start":"2026-05-11T10:57:23.188365Z","end":"2026-05-11T10:57:23.300855Z","steps":["trace[2042827037] 'process raft request' (duration: 109.000898ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:58:46.937924Z","caller":"traceutil/trace.go:171","msg":"trace[395391282] linearizableReadLoop","detail":"{readStateIndex:4934; appliedIndex:4932; }","duration":"106.99697ms","start":"2026-05-11T10:58:46.830904Z","end":"2026-05-11T10:58:46.937901Z","steps":["trace[395391282] 'read index received' (duration: 97.53972ms)","trace[395391282] 'applied index is now lower than readState.Index' (duration: 9.45643ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:58:46.938048Z","caller":"traceutil/trace.go:171","msg":"trace[1659575135] transaction","detail":"{read_only:false; response_revision:4752; number_of_response:1; }","duration":"108.213724ms","start":"2026-05-11T10:58:46.829823Z","end":"2026-05-11T10:58:46.938037Z","steps":["trace[1659575135] 'process raft request' (duration: 108.01503ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:58:46.938078Z","caller":"traceutil/trace.go:171","msg":"trace[951276795] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"108.282645ms","start":"2026-05-11T10:58:46.829768Z","end":"2026-05-11T10:58:46.938051Z","steps":["trace[951276795] 'process raft request' (duration: 98.695753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:58:46.93818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.831128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-11T10:58:46.938247Z","caller":"traceutil/trace.go:171","msg":"trace[380364025] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4752; }","duration":"105.90522ms","start":"2026-05-11T10:58:46.832324Z","end":"2026-05-11T10:58:46.938229Z","steps":["trace[380364025] 'agreement among raft nodes before linearized reading' (duration: 105.739176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:58:46.938562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.651982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-88dkk-tls\" ","response":"range_response_count:1 size:2129"} {"level":"info","ts":"2026-05-11T10:58:46.938591Z","caller":"traceutil/trace.go:171","msg":"trace[1420340765] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-88dkk-tls; range_end:; response_count:1; response_revision:4752; }","duration":"107.702794ms","start":"2026-05-11T10:58:46.830879Z","end":"2026-05-11T10:58:46.938582Z","steps":["trace[1420340765] 'agreement among raft nodes before linearized reading' (duration: 107.582342ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:58:47.098678Z","caller":"traceutil/trace.go:171","msg":"trace[1387996645] transaction","detail":"{read_only:false; response_revision:4755; number_of_response:1; }","duration":"122.544317ms","start":"2026-05-11T10:58:46.976096Z","end":"2026-05-11T10:58:47.09864Z","steps":["trace[1387996645] 'process raft request' (duration: 89.67331ms)","trace[1387996645] 'compare' (duration: 32.679283ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:58:47.098818Z","caller":"traceutil/trace.go:171","msg":"trace[804196757] linearizableReadLoop","detail":"{readStateIndex:4938; appliedIndex:4936; }","duration":"109.03931ms","start":"2026-05-11T10:58:46.989753Z","end":"2026-05-11T10:58:47.098792Z","steps":["trace[804196757] 'read index received' (duration: 75.908928ms)","trace[804196757] 'applied index is now lower than readState.Index' (duration: 33.128582ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:58:47.098914Z","caller":"traceutil/trace.go:171","msg":"trace[1072588803] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"120.539839ms","start":"2026-05-11T10:58:46.978337Z","end":"2026-05-11T10:58:47.098877Z","steps":["trace[1072588803] 'process raft request' (duration: 120.234303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:58:47.099034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.267435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:58:47.099122Z","caller":"traceutil/trace.go:171","msg":"trace[128545985] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4756; }","duration":"109.403858ms","start":"2026-05-11T10:58:46.989693Z","end":"2026-05-11T10:58:47.099097Z","steps":["trace[128545985] 'agreement among raft nodes before linearized reading' (duration: 109.202543ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:58:52.618105Z","caller":"traceutil/trace.go:171","msg":"trace[1789301524] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"100.64328ms","start":"2026-05-11T10:58:52.51743Z","end":"2026-05-11T10:58:52.618073Z","steps":["trace[1789301524] 'process raft request' (duration: 100.458577ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:59:11.536181Z","caller":"traceutil/trace.go:171","msg":"trace[1844189379] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"136.42949ms","start":"2026-05-11T10:59:11.399715Z","end":"2026-05-11T10:59:11.536145Z","steps":["trace[1844189379] 'process raft request' (duration: 81.13423ms)","trace[1844189379] 'compare' (duration: 55.168277ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:00:40.461454Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2975} {"level":"info","ts":"2026-05-11T11:00:40.530612Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2975,"took":"67.147659ms","hash":750130214,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":33910784,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-11T11:00:40.530676Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":750130214,"revision":2975,"compact-revision":-1} {"level":"warn","ts":"2026-05-11T11:05:14.812488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.742872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T11:05:14.812596Z","caller":"traceutil/trace.go:171","msg":"trace[352422150] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:8140; }","duration":"105.945176ms","start":"2026-05-11T11:05:14.706628Z","end":"2026-05-11T11:05:14.812574Z","steps":["trace[352422150] 'count revisions from in-memory index tree' (duration: 105.541557ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:05:27.120183Z","caller":"traceutil/trace.go:171","msg":"trace[807270769] transaction","detail":"{read_only:false; response_revision:8204; number_of_response:1; }","duration":"160.041074ms","start":"2026-05-11T11:05:26.960114Z","end":"2026-05-11T11:05:27.120155Z","steps":["trace[807270769] 'process raft request' (duration: 159.791249ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:05:27.249762Z","caller":"traceutil/trace.go:171","msg":"trace[1118234358] transaction","detail":"{read_only:false; response_revision:8205; number_of_response:1; }","duration":"125.27485ms","start":"2026-05-11T11:05:27.124464Z","end":"2026-05-11T11:05:27.249738Z","steps":["trace[1118234358] 'process raft request' (duration: 65.215678ms)","trace[1118234358] 'compare' (duration: 59.728626ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:05:40.468771Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6101} {"level":"info","ts":"2026-05-11T11:05:40.573439Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6101,"took":"102.616563ms","hash":2487925287,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":23670784,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-11T11:05:40.57351Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2487925287,"revision":6101,"compact-revision":2975} {"level":"info","ts":"2026-05-11T11:05:54.969042Z","caller":"traceutil/trace.go:171","msg":"trace[1588053591] linearizableReadLoop","detail":"{readStateIndex:8706; appliedIndex:8705; }","duration":"432.657938ms","start":"2026-05-11T11:05:54.536361Z","end":"2026-05-11T11:05:54.969019Z","steps":["trace[1588053591] 'read index received' (duration: 432.482555ms)","trace[1588053591] 'applied index is now lower than readState.Index' (duration: 174.443µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:05:54.969178Z","caller":"traceutil/trace.go:171","msg":"trace[1992550120] transaction","detail":"{read_only:false; response_revision:8409; number_of_response:1; }","duration":"448.69711ms","start":"2026-05-11T11:05:54.520471Z","end":"2026-05-11T11:05:54.969168Z","steps":["trace[1992550120] 'process raft request' (duration: 448.398105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:05:54.969527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.157108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-11T11:05:54.969619Z","caller":"traceutil/trace.go:171","msg":"trace[320437873] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8409; }","duration":"433.286171ms","start":"2026-05-11T11:05:54.536305Z","end":"2026-05-11T11:05:54.969591Z","steps":["trace[320437873] 'agreement among raft nodes before linearized reading' (duration: 433.078627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:05:54.969673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:05:54.536282Z","time spent":"433.377342ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T11:05:54.969683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:05:54.520457Z","time spent":"448.749761ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:05:54.969989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.602506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-11T11:05:54.970054Z","caller":"traceutil/trace.go:171","msg":"trace[1931609614] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8409; }","duration":"433.712798ms","start":"2026-05-11T11:05:54.536326Z","end":"2026-05-11T11:05:54.970039Z","steps":["trace[1931609614] 'agreement among raft nodes before linearized reading' (duration: 433.571886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:05:54.970096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:05:54.536289Z","time spent":"433.79525ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T11:05:54.970385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.222369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-11T11:05:54.970472Z","caller":"traceutil/trace.go:171","msg":"trace[187426092] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8409; }","duration":"355.337382ms","start":"2026-05-11T11:05:54.615119Z","end":"2026-05-11T11:05:54.970456Z","steps":["trace[187426092] 'agreement among raft nodes before linearized reading' (duration: 355.027676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:05:54.970566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:05:54.615102Z","time spent":"355.438353ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-11T11:05:57.639609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.502311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T11:05:57.639689Z","caller":"traceutil/trace.go:171","msg":"trace[378470613] linearizableReadLoop","detail":"{readStateIndex:8722; appliedIndex:8721; }","duration":"189.761094ms","start":"2026-05-11T11:05:57.449916Z","end":"2026-05-11T11:05:57.639677Z","steps":["trace[378470613] 'read index received' (duration: 54.011798ms)","trace[378470613] 'applied index is now lower than readState.Index' (duration: 135.748416ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:05:57.639804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.883097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-11T11:05:57.639823Z","caller":"traceutil/trace.go:171","msg":"trace[34519305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8425; }","duration":"189.951918ms","start":"2026-05-11T11:05:57.449864Z","end":"2026-05-11T11:05:57.639816Z","steps":["trace[34519305] 'agreement among raft nodes before linearized reading' (duration: 189.844336ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:05:57.639836Z","caller":"traceutil/trace.go:171","msg":"trace[1190180220] transaction","detail":"{read_only:false; response_revision:8425; number_of_response:1; }","duration":"255.314849ms","start":"2026-05-11T11:05:57.384507Z","end":"2026-05-11T11:05:57.639822Z","steps":["trace[1190180220] 'process raft request' (duration: 119.41967ms)","trace[1190180220] 'compare' (duration: 135.382989ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:06:07.82931Z","caller":"traceutil/trace.go:171","msg":"trace[1497645817] transaction","detail":"{read_only:false; response_revision:8521; number_of_response:1; }","duration":"134.986143ms","start":"2026-05-11T11:06:07.694295Z","end":"2026-05-11T11:06:07.829281Z","steps":["trace[1497645817] 'process raft request' (duration: 134.771698ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:06:28.657845Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002371.wal"} {"level":"info","ts":"2026-05-11T11:08:18.483938Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T11:08:18.489417Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-11T11:08:18.489514Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-11T11:10:40.475329Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8280} {"level":"info","ts":"2026-05-11T11:10:40.549659Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8280,"took":"71.478699ms","hash":2078134274,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":26624000,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-11T11:10:40.54976Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2078134274,"revision":8280,"compact-revision":6101} {"level":"info","ts":"2026-05-11T11:15:40.482839Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10962} {"level":"info","ts":"2026-05-11T11:15:40.564873Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10962,"took":"79.247085ms","hash":2008369169,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":28164096,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-11T11:15:40.564943Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2008369169,"revision":10962,"compact-revision":8280} {"level":"info","ts":"2026-05-11T11:17:27.288025Z","caller":"traceutil/trace.go:171","msg":"trace[1670044541] transaction","detail":"{read_only:false; response_revision:13685; number_of_response:1; }","duration":"118.890756ms","start":"2026-05-11T11:17:27.169085Z","end":"2026-05-11T11:17:27.287976Z","steps":["trace[1670044541] 'process raft request' (duration: 118.699603ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:17:28.081153Z","caller":"traceutil/trace.go:171","msg":"trace[828393642] transaction","detail":"{read_only:false; response_revision:13688; number_of_response:1; }","duration":"116.709055ms","start":"2026-05-11T11:17:27.964416Z","end":"2026-05-11T11:17:28.081125Z","steps":["trace[828393642] 'process raft request' (duration: 116.534971ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:17:28.275301Z","caller":"traceutil/trace.go:171","msg":"trace[1018375459] linearizableReadLoop","detail":"{readStateIndex:14156; appliedIndex:14155; }","duration":"296.90926ms","start":"2026-05-11T11:17:27.978362Z","end":"2026-05-11T11:17:28.275271Z","steps":["trace[1018375459] 'read index received' (duration: 103.279714ms)","trace[1018375459] 'applied index is now lower than readState.Index' (duration: 193.628016ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:17:28.275507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.128065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T11:17:28.275566Z","caller":"traceutil/trace.go:171","msg":"trace[109074852] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:13689; }","duration":"297.251418ms","start":"2026-05-11T11:17:27.978295Z","end":"2026-05-11T11:17:28.275547Z","steps":["trace[109074852] 'agreement among raft nodes before linearized reading' (duration: 297.090155ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:17:28.275932Z","caller":"traceutil/trace.go:171","msg":"trace[2032247766] transaction","detail":"{read_only:false; response_revision:13689; number_of_response:1; }","duration":"309.219511ms","start":"2026-05-11T11:17:27.966696Z","end":"2026-05-11T11:17:28.275916Z","steps":["trace[2032247766] 'process raft request' (duration: 306.050302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:17:28.276056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:17:27.966688Z","time spent":"309.289792ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:17:37.126685Z","caller":"traceutil/trace.go:171","msg":"trace[916157443] transaction","detail":"{read_only:false; response_revision:13855; number_of_response:1; }","duration":"241.190379ms","start":"2026-05-11T11:17:36.885456Z","end":"2026-05-11T11:17:37.126646Z","steps":["trace[916157443] 'process raft request' (duration: 241.001494ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:17:37.18567Z","caller":"traceutil/trace.go:171","msg":"trace[800081661] transaction","detail":"{read_only:false; response_revision:13856; number_of_response:1; }","duration":"258.858929ms","start":"2026-05-11T11:17:36.92679Z","end":"2026-05-11T11:17:37.185649Z","steps":["trace[800081661] 'process raft request' (duration: 258.545962ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:17:37.186763Z","caller":"traceutil/trace.go:171","msg":"trace[294239500] transaction","detail":"{read_only:false; response_revision:13857; number_of_response:1; }","duration":"192.491635ms","start":"2026-05-11T11:17:36.993354Z","end":"2026-05-11T11:17:37.185846Z","steps":["trace[294239500] 'process raft request' (duration: 192.092528ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:19:21.369285Z","caller":"traceutil/trace.go:171","msg":"trace[1335266857] linearizableReadLoop","detail":"{readStateIndex:15468; appliedIndex:15467; }","duration":"141.658188ms","start":"2026-05-11T11:19:21.227599Z","end":"2026-05-11T11:19:21.369257Z","steps":["trace[1335266857] 'read index received' (duration: 71.118065ms)","trace[1335266857] 'applied index is now lower than readState.Index' (duration: 70.539083ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:19:21.369549Z","caller":"traceutil/trace.go:171","msg":"trace[1461079293] transaction","detail":"{read_only:false; response_revision:14964; number_of_response:1; }","duration":"152.802937ms","start":"2026-05-11T11:19:21.216732Z","end":"2026-05-11T11:19:21.369535Z","steps":["trace[1461079293] 'process raft request' (duration: 82.02915ms)","trace[1461079293] 'compare' (duration: 70.243618ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:19:21.369805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.191528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T11:19:21.370185Z","caller":"traceutil/trace.go:171","msg":"trace[567793104] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:14964; }","duration":"142.617076ms","start":"2026-05-11T11:19:21.227547Z","end":"2026-05-11T11:19:21.370165Z","steps":["trace[567793104] 'agreement among raft nodes before linearized reading' (duration: 142.184428ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:19:22.1297Z","caller":"traceutil/trace.go:171","msg":"trace[261534493] transaction","detail":"{read_only:false; response_revision:14966; number_of_response:1; }","duration":"162.55988ms","start":"2026-05-11T11:19:21.967118Z","end":"2026-05-11T11:19:22.129678Z","steps":["trace[261534493] 'process raft request' (duration: 162.396717ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:19:22.135046Z","caller":"traceutil/trace.go:171","msg":"trace[426629458] transaction","detail":"{read_only:false; response_revision:14967; number_of_response:1; }","duration":"166.128126ms","start":"2026-05-11T11:19:21.968904Z","end":"2026-05-11T11:19:22.135032Z","steps":["trace[426629458] 'process raft request' (duration: 166.042045ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:20:40.490435Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12985} {"level":"info","ts":"2026-05-11T11:20:40.567739Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12985,"took":"74.327824ms","hash":1938659742,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":31358976,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-11T11:20:40.56781Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1938659742,"revision":12985,"compact-revision":10962} {"level":"info","ts":"2026-05-11T11:25:01.847794Z","caller":"traceutil/trace.go:171","msg":"trace[254126379] transaction","detail":"{read_only:false; response_revision:17568; number_of_response:1; }","duration":"132.988853ms","start":"2026-05-11T11:25:01.71476Z","end":"2026-05-11T11:25:01.847748Z","steps":["trace[254126379] 'process raft request' (duration: 132.83459ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:25:02.034959Z","caller":"traceutil/trace.go:171","msg":"trace[896363637] transaction","detail":"{read_only:false; response_revision:17569; number_of_response:1; }","duration":"305.100221ms","start":"2026-05-11T11:25:01.729832Z","end":"2026-05-11T11:25:02.034932Z","steps":["trace[896363637] 'process raft request' (duration: 303.699045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:25:02.035123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:25:01.729809Z","time spent":"305.229743ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:25:02.538983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.884033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T11:25:02.539248Z","caller":"traceutil/trace.go:171","msg":"trace[1274842798] transaction","detail":"{read_only:false; response_revision:17571; number_of_response:1; }","duration":"498.345804ms","start":"2026-05-11T11:25:02.040862Z","end":"2026-05-11T11:25:02.539208Z","steps":["trace[1274842798] 'process raft request' (duration: 262.134605ms)","trace[1274842798] 'compare' (duration: 235.638908ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:25:02.539349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:25:02.04084Z","time spent":"498.465956ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:25:02.53953Z","caller":"traceutil/trace.go:171","msg":"trace[1251722409] linearizableReadLoop","detail":"{readStateIndex:18159; appliedIndex:18157; }","duration":"258.786702ms","start":"2026-05-11T11:25:02.280691Z","end":"2026-05-11T11:25:02.539477Z","steps":["trace[1251722409] 'read index received' (duration: 22.200776ms)","trace[1251722409] 'applied index is now lower than readState.Index' (duration: 236.584906ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:25:02.539614Z","caller":"traceutil/trace.go:171","msg":"trace[1127335459] transaction","detail":"{read_only:false; response_revision:17572; number_of_response:1; }","duration":"487.084053ms","start":"2026-05-11T11:25:02.052501Z","end":"2026-05-11T11:25:02.539585Z","steps":["trace[1127335459] 'process raft request' (duration: 486.587844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:25:02.539677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.997496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:25:02.539751Z","caller":"traceutil/trace.go:171","msg":"trace[1948691493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17572; }","duration":"259.102147ms","start":"2026-05-11T11:25:02.28063Z","end":"2026-05-11T11:25:02.539732Z","steps":["trace[1948691493] 'agreement among raft nodes before linearized reading' (duration: 259.001356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:25:02.539765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:25:02.052471Z","time spent":"487.212955ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:25:02.540497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.344349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:25:02.54055Z","caller":"traceutil/trace.go:171","msg":"trace[918491881] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:17572; }","duration":"214.40573ms","start":"2026-05-11T11:25:02.32613Z","end":"2026-05-11T11:25:02.540536Z","steps":["trace[918491881] 'agreement among raft nodes before linearized reading' (duration: 213.577614ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:25:40.497586Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15496} {"level":"info","ts":"2026-05-11T11:25:40.606906Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15496,"took":"105.473669ms","hash":630391334,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":33771520,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-11T11:25:40.606968Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":630391334,"revision":15496,"compact-revision":12985} {"level":"info","ts":"2026-05-11T11:28:42.390581Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T11:28:42.396268Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-11T11:28:42.396427Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-11T11:30:40.50329Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18071} {"level":"info","ts":"2026-05-11T11:30:40.612457Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18071,"took":"105.496581ms","hash":2657125951,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":29310976,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-11T11:30:40.612575Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2657125951,"revision":18071,"compact-revision":15496} {"level":"warn","ts":"2026-05-11T11:31:52.413013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.51529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T11:31:52.413135Z","caller":"traceutil/trace.go:171","msg":"trace[1628499362] linearizableReadLoop","detail":"{readStateIndex:21158; appliedIndex:21157; }","duration":"134.207998ms","start":"2026-05-11T11:31:52.278913Z","end":"2026-05-11T11:31:52.413121Z","steps":["trace[1628499362] 'read index received' (duration: 12.506273ms)","trace[1628499362] 'applied index is now lower than readState.Index' (duration: 121.700895ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:31:52.413186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.273919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:31:52.413207Z","caller":"traceutil/trace.go:171","msg":"trace[818448527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20469; }","duration":"134.32825ms","start":"2026-05-11T11:31:52.27887Z","end":"2026-05-11T11:31:52.413198Z","steps":["trace[818448527] 'agreement among raft nodes before linearized reading' (duration: 134.285069ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:31:52.413268Z","caller":"traceutil/trace.go:171","msg":"trace[1445835260] transaction","detail":"{read_only:false; response_revision:20469; number_of_response:1; }","duration":"249.996772ms","start":"2026-05-11T11:31:52.16323Z","end":"2026-05-11T11:31:52.413227Z","steps":["trace[1445835260] 'process raft request' (duration: 128.187505ms)","trace[1445835260] 'compare' (duration: 121.431629ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:31:52.723575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.046648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T11:31:52.72371Z","caller":"traceutil/trace.go:171","msg":"trace[630452328] linearizableReadLoop","detail":"{readStateIndex:21159; appliedIndex:21158; }","duration":"309.512183ms","start":"2026-05-11T11:31:52.414178Z","end":"2026-05-11T11:31:52.723691Z","steps":["trace[630452328] 'read index received' (duration: 169.855084ms)","trace[630452328] 'applied index is now lower than readState.Index' (duration: 139.655319ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:31:52.72373Z","caller":"traceutil/trace.go:171","msg":"trace[796198591] transaction","detail":"{read_only:false; response_revision:20470; number_of_response:1; }","duration":"397.273292ms","start":"2026-05-11T11:31:52.326418Z","end":"2026-05-11T11:31:52.723691Z","steps":["trace[796198591] 'process raft request' (duration: 257.581083ms)","trace[796198591] 'compare' (duration: 138.66903ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:31:52.723849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.667065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T11:31:52.723887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:31:52.326398Z","time spent":"397.414595ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:31:52.723919Z","caller":"traceutil/trace.go:171","msg":"trace[1251144123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20470; }","duration":"309.799438ms","start":"2026-05-11T11:31:52.4141Z","end":"2026-05-11T11:31:52.723899Z","steps":["trace[1251144123] 'agreement among raft nodes before linearized reading' (duration: 309.662325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:31:52.723983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:31:52.414083Z","time spent":"309.89303ms","remote":"127.0.0.1:57622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T11:31:52.724047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.995084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-11T11:31:52.724153Z","caller":"traceutil/trace.go:171","msg":"trace[1720722752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20470; }","duration":"103.163478ms","start":"2026-05-11T11:31:52.620962Z","end":"2026-05-11T11:31:52.724125Z","steps":["trace[1720722752] 'agreement among raft nodes before linearized reading' (duration: 102.847461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:31:53.03881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.151826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-11T11:31:53.038888Z","caller":"traceutil/trace.go:171","msg":"trace[53960095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20471; }","duration":"158.276317ms","start":"2026-05-11T11:31:52.880593Z","end":"2026-05-11T11:31:53.038869Z","steps":["trace[53960095] 'range keys from in-memory index tree' (duration: 157.961562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:31:53.038946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.587677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-11T11:31:53.039025Z","caller":"traceutil/trace.go:171","msg":"trace[244598721] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:20471; }","duration":"185.71344ms","start":"2026-05-11T11:31:52.853292Z","end":"2026-05-11T11:31:53.039005Z","steps":["trace[244598721] 'count revisions from in-memory index tree' (duration: 185.500286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:31:53.038886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.230514ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T11:31:53.039094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.898897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T11:31:53.039118Z","caller":"traceutil/trace.go:171","msg":"trace[237270261] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:20471; }","duration":"160.497909ms","start":"2026-05-11T11:31:52.878612Z","end":"2026-05-11T11:31:53.03911Z","steps":["trace[237270261] 'range keys from in-memory index tree' (duration: 160.209084ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:31:53.039146Z","caller":"traceutil/trace.go:171","msg":"trace[1497029565] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20471; }","duration":"144.975069ms","start":"2026-05-11T11:31:52.894155Z","end":"2026-05-11T11:31:53.039131Z","steps":["trace[1497029565] 'range keys from in-memory index tree' (duration: 144.766736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:31:53.278381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.211142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T11:31:53.278545Z","caller":"traceutil/trace.go:171","msg":"trace[2050338961] transaction","detail":"{read_only:false; response_revision:20474; number_of_response:1; }","duration":"142.38466ms","start":"2026-05-11T11:31:53.13609Z","end":"2026-05-11T11:31:53.278475Z","steps":["trace[2050338961] 'process raft request' (duration: 11.977234ms)","trace[2050338961] 'compare' (duration: 130.06577ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:35:40.511401Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20103} {"level":"info","ts":"2026-05-11T11:35:40.626912Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20103,"took":"112.191976ms","hash":1871498901,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":39071744,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-05-11T11:35:40.627023Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1871498901,"revision":20103,"compact-revision":18071} {"level":"info","ts":"2026-05-11T11:35:42.514845Z","caller":"traceutil/trace.go:171","msg":"trace[1783682625] linearizableReadLoop","detail":"{readStateIndex:23623; appliedIndex:23622; }","duration":"223.320274ms","start":"2026-05-11T11:35:42.291498Z","end":"2026-05-11T11:35:42.514818Z","steps":["trace[1783682625] 'read index received' (duration: 183.205783ms)","trace[1783682625] 'applied index is now lower than readState.Index' (duration: 40.113261ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:35:42.51504Z","caller":"traceutil/trace.go:171","msg":"trace[790276167] transaction","detail":"{read_only:false; response_revision:22862; number_of_response:1; }","duration":"267.031521ms","start":"2026-05-11T11:35:42.247998Z","end":"2026-05-11T11:35:42.51503Z","steps":["trace[790276167] 'process raft request' (duration: 226.640785ms)","trace[790276167] 'compare' (duration: 40.06331ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:35:42.515203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.69063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T11:35:42.515296Z","caller":"traceutil/trace.go:171","msg":"trace[2139653963] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:22862; }","duration":"223.851303ms","start":"2026-05-11T11:35:42.291427Z","end":"2026-05-11T11:35:42.515279Z","steps":["trace[2139653963] 'agreement among raft nodes before linearized reading' (duration: 223.67274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:35:42.587742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.975721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T11:35:42.58785Z","caller":"traceutil/trace.go:171","msg":"trace[1873098799] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22863; }","duration":"196.149965ms","start":"2026-05-11T11:35:42.391675Z","end":"2026-05-11T11:35:42.587825Z","steps":["trace[1873098799] 'agreement among raft nodes before linearized reading' (duration: 195.89221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:35:42.587983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.785155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-11T11:35:42.588079Z","caller":"traceutil/trace.go:171","msg":"trace[547347818] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22863; }","duration":"228.956909ms","start":"2026-05-11T11:35:42.359095Z","end":"2026-05-11T11:35:42.588052Z","steps":["trace[547347818] 'agreement among raft nodes before linearized reading' (duration: 228.686064ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:35:42.624788Z","caller":"traceutil/trace.go:171","msg":"trace[1517448091] transaction","detail":"{read_only:false; response_revision:22864; number_of_response:1; }","duration":"100.04194ms","start":"2026-05-11T11:35:42.524728Z","end":"2026-05-11T11:35:42.62477Z","steps":["trace[1517448091] 'process raft request' (duration: 99.613172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:35:42.625044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.730027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager\" ","response":"range_response_count:1 size:5182"} {"level":"info","ts":"2026-05-11T11:35:42.62512Z","caller":"traceutil/trace.go:171","msg":"trace[1833629159] range","detail":"{range_begin:/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager; range_end:; response_count:1; response_revision:22865; }","duration":"104.815208ms","start":"2026-05-11T11:35:42.520287Z","end":"2026-05-11T11:35:42.625102Z","steps":["trace[1833629159] 'agreement among raft nodes before linearized reading' (duration: 104.681736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:51.849911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.12472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T11:38:51.850453Z","caller":"traceutil/trace.go:171","msg":"trace[197908816] transaction","detail":"{read_only:false; response_revision:24824; number_of_response:1; }","duration":"162.004735ms","start":"2026-05-11T11:38:51.688156Z","end":"2026-05-11T11:38:51.850161Z","steps":["trace[197908816] 'process raft request' (duration: 161.899773ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:51.850984Z","caller":"traceutil/trace.go:171","msg":"trace[1056453407] transaction","detail":"{read_only:false; response_revision:24823; number_of_response:1; }","duration":"256.715279ms","start":"2026-05-11T11:38:51.59425Z","end":"2026-05-11T11:38:51.850965Z","steps":["trace[1056453407] 'process raft request' (duration: 134.411978ms)","trace[1056453407] 'compare' (duration: 120.770472ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:51.851162Z","caller":"traceutil/trace.go:171","msg":"trace[1289781632] linearizableReadLoop","detail":"{readStateIndex:25633; appliedIndex:25632; }","duration":"236.184183ms","start":"2026-05-11T11:38:51.614965Z","end":"2026-05-11T11:38:51.85115Z","steps":["trace[1289781632] 'read index received' (duration: 113.675828ms)","trace[1289781632] 'applied index is now lower than readState.Index' (duration: 122.506905ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:51.851728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.727373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-11T11:38:51.851848Z","caller":"traceutil/trace.go:171","msg":"trace[126938442] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24824; }","duration":"236.860015ms","start":"2026-05-11T11:38:51.614956Z","end":"2026-05-11T11:38:51.851816Z","steps":["trace[126938442] 'agreement among raft nodes before linearized reading' (duration: 236.508339ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:52.13123Z","caller":"traceutil/trace.go:171","msg":"trace[183516760] transaction","detail":"{read_only:false; response_revision:24825; number_of_response:1; }","duration":"372.041008ms","start":"2026-05-11T11:38:51.75917Z","end":"2026-05-11T11:38:52.131211Z","steps":["trace[183516760] 'process raft request' (duration: 358.010736ms)","trace[183516760] 'compare' (duration: 13.608594ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:52.131342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:51.75915Z","time spent":"372.1339ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:38:52.13156Z","caller":"traceutil/trace.go:171","msg":"trace[1341637758] linearizableReadLoop","detail":"{readStateIndex:25637; appliedIndex:25634; }","duration":"280.32797ms","start":"2026-05-11T11:38:51.851201Z","end":"2026-05-11T11:38:52.131529Z","steps":["trace[1341637758] 'read index received' (duration: 265.90978ms)","trace[1341637758] 'applied index is now lower than readState.Index' (duration: 14.41684ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:52.131709Z","caller":"traceutil/trace.go:171","msg":"trace[27667992] transaction","detail":"{read_only:false; response_revision:24826; number_of_response:1; }","duration":"330.174314ms","start":"2026-05-11T11:38:51.801523Z","end":"2026-05-11T11:38:52.131697Z","steps":["trace[27667992] 'process raft request' (duration: 329.626824ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:52.131704Z","caller":"traceutil/trace.go:171","msg":"trace[1766034235] transaction","detail":"{read_only:false; response_revision:24827; number_of_response:1; }","duration":"323.795533ms","start":"2026-05-11T11:38:51.807862Z","end":"2026-05-11T11:38:52.131658Z","steps":["trace[1766034235] 'process raft request' (duration: 323.497798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:52.131764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:51.801501Z","time spent":"330.236285ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:38:52.131766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.25129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-11T11:38:52.131844Z","caller":"traceutil/trace.go:171","msg":"trace[1799166724] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24827; }","duration":"444.381303ms","start":"2026-05-11T11:38:51.687444Z","end":"2026-05-11T11:38:52.131825Z","steps":["trace[1799166724] 'agreement among raft nodes before linearized reading' (duration: 444.196639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:52.131875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:51.807842Z","time spent":"323.950536ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:38:52.131892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:51.687419Z","time spent":"444.464494ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-11T11:38:52.132186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.382627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-11T11:38:52.132185Z","caller":"traceutil/trace.go:171","msg":"trace[432524132] transaction","detail":"{read_only:false; response_revision:24828; number_of_response:1; }","duration":"274.301407ms","start":"2026-05-11T11:38:51.85784Z","end":"2026-05-11T11:38:52.132141Z","steps":["trace[432524132] 'process raft request' (duration: 274.094534ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:52.132263Z","caller":"traceutil/trace.go:171","msg":"trace[130209148] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24827; }","duration":"512.510389ms","start":"2026-05-11T11:38:51.619731Z","end":"2026-05-11T11:38:52.132241Z","steps":["trace[130209148] 'agreement among raft nodes before linearized reading' (duration: 512.236944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:52.132311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:51.619716Z","time spent":"512.58614ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T11:38:52.13256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.739229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:52.132623Z","caller":"traceutil/trace.go:171","msg":"trace[1522475729] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:24828; }","duration":"232.80543ms","start":"2026-05-11T11:38:51.899799Z","end":"2026-05-11T11:38:52.132604Z","steps":["trace[1522475729] 'agreement among raft nodes before linearized reading' (duration: 232.705548ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:52.284851Z","caller":"traceutil/trace.go:171","msg":"trace[190771586] transaction","detail":"{read_only:false; response_revision:24830; number_of_response:1; }","duration":"146.631387ms","start":"2026-05-11T11:38:52.138187Z","end":"2026-05-11T11:38:52.284818Z","steps":["trace[190771586] 'process raft request' (duration: 146.515565ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:52.285028Z","caller":"traceutil/trace.go:171","msg":"trace[31139661] transaction","detail":"{read_only:false; response_revision:24829; number_of_response:1; }","duration":"148.536362ms","start":"2026-05-11T11:38:52.136457Z","end":"2026-05-11T11:38:52.284994Z","steps":["trace[31139661] 'process raft request' (duration: 144.117078ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:52.438989Z","caller":"traceutil/trace.go:171","msg":"trace[1932992383] transaction","detail":"{read_only:false; response_revision:24831; number_of_response:1; }","duration":"125.159734ms","start":"2026-05-11T11:38:52.313804Z","end":"2026-05-11T11:38:52.438963Z","steps":["trace[1932992383] 'process raft request' (duration: 125.003401ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:56.573819Z","caller":"traceutil/trace.go:171","msg":"trace[1614380272] transaction","detail":"{read_only:false; response_revision:24860; number_of_response:1; }","duration":"121.331132ms","start":"2026-05-11T11:38:56.452461Z","end":"2026-05-11T11:38:56.573792Z","steps":["trace[1614380272] 'process raft request' (duration: 121.112768ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:56.85028Z","caller":"traceutil/trace.go:171","msg":"trace[1217003181] transaction","detail":"{read_only:false; response_revision:24862; number_of_response:1; }","duration":"119.801293ms","start":"2026-05-11T11:38:56.730456Z","end":"2026-05-11T11:38:56.850257Z","steps":["trace[1217003181] 'process raft request' (duration: 119.645949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:57.116672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.12203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-11T11:38:57.116829Z","caller":"traceutil/trace.go:171","msg":"trace[1183430484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24862; }","duration":"104.318104ms","start":"2026-05-11T11:38:57.012491Z","end":"2026-05-11T11:38:57.116809Z","steps":["trace[1183430484] 'range keys from in-memory index tree' (duration: 103.955538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:57.501349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.708343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:57.501485Z","caller":"traceutil/trace.go:171","msg":"trace[131598467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24863; }","duration":"220.864806ms","start":"2026-05-11T11:38:57.28059Z","end":"2026-05-11T11:38:57.501455Z","steps":["trace[131598467] 'range keys from in-memory index tree' (duration: 220.603422ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:57.614781Z","caller":"traceutil/trace.go:171","msg":"trace[1395344970] transaction","detail":"{read_only:false; response_revision:24864; number_of_response:1; }","duration":"298.805466ms","start":"2026-05-11T11:38:57.315947Z","end":"2026-05-11T11:38:57.614752Z","steps":["trace[1395344970] 'process raft request' (duration: 298.621132ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:57.728598Z","caller":"traceutil/trace.go:171","msg":"trace[953859268] transaction","detail":"{read_only:false; response_revision:24865; number_of_response:1; }","duration":"410.460497ms","start":"2026-05-11T11:38:57.318099Z","end":"2026-05-11T11:38:57.72856Z","steps":["trace[953859268] 'process raft request' (duration: 402.272013ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:57.728647Z","caller":"traceutil/trace.go:171","msg":"trace[757687712] transaction","detail":"{read_only:false; response_revision:24866; number_of_response:1; }","duration":"122.694218ms","start":"2026-05-11T11:38:57.605903Z","end":"2026-05-11T11:38:57.728598Z","steps":["trace[757687712] 'process raft request' (duration: 122.575976ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:57.728656Z","caller":"traceutil/trace.go:171","msg":"trace[1037274436] linearizableReadLoop","detail":"{readStateIndex:25676; appliedIndex:25674; }","duration":"225.758728ms","start":"2026-05-11T11:38:57.502799Z","end":"2026-05-11T11:38:57.728557Z","steps":["trace[1037274436] 'read index received' (duration: 111.752553ms)","trace[1037274436] 'applied index is now lower than readState.Index' (duration: 114.003745ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:57.728733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.924932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:57.729006Z","caller":"traceutil/trace.go:171","msg":"trace[1348812312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24866; }","duration":"226.205717ms","start":"2026-05-11T11:38:57.502749Z","end":"2026-05-11T11:38:57.728955Z","steps":["trace[1348812312] 'agreement among raft nodes before linearized reading' (duration: 225.907461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:57.728769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:57.318056Z","time spent":"410.61961ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:38:57.729695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.546933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:57.729795Z","caller":"traceutil/trace.go:171","msg":"trace[506077155] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:24866; }","duration":"209.705437ms","start":"2026-05-11T11:38:57.520073Z","end":"2026-05-11T11:38:57.729779Z","steps":["trace[506077155] 'agreement among raft nodes before linearized reading' (duration: 209.531044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:58.035202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.237841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T11:38:58.035424Z","caller":"traceutil/trace.go:171","msg":"trace[1210763001] linearizableReadLoop","detail":"{readStateIndex:25679; appliedIndex:25677; }","duration":"157.639153ms","start":"2026-05-11T11:38:57.877765Z","end":"2026-05-11T11:38:58.035405Z","steps":["trace[1210763001] 'read index received' (duration: 13.166907ms)","trace[1210763001] 'applied index is now lower than readState.Index' (duration: 144.471206ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:58.035508Z","caller":"traceutil/trace.go:171","msg":"trace[784005308] transaction","detail":"{read_only:false; response_revision:24868; number_of_response:1; }","duration":"164.44495ms","start":"2026-05-11T11:38:57.871028Z","end":"2026-05-11T11:38:58.035473Z","steps":["trace[784005308] 'process raft request' (duration: 164.309098ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:58.035629Z","caller":"traceutil/trace.go:171","msg":"trace[1428346273] transaction","detail":"{read_only:false; response_revision:24867; number_of_response:1; }","duration":"208.027867ms","start":"2026-05-11T11:38:57.827555Z","end":"2026-05-11T11:38:58.035583Z","steps":["trace[1428346273] 'process raft request' (duration: 63.636422ms)","trace[1428346273] 'compare' (duration: 143.732002ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:58.035688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.904669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T11:38:58.035753Z","caller":"traceutil/trace.go:171","msg":"trace[1912656936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24868; }","duration":"158.040761ms","start":"2026-05-11T11:38:57.877693Z","end":"2026-05-11T11:38:58.035734Z","steps":["trace[1912656936] 'agreement among raft nodes before linearized reading' (duration: 157.803357ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:58.310577Z","caller":"traceutil/trace.go:171","msg":"trace[1166829723] transaction","detail":"{read_only:false; response_revision:24870; number_of_response:1; }","duration":"154.260069ms","start":"2026-05-11T11:38:58.155382Z","end":"2026-05-11T11:38:58.309642Z","steps":["trace[1166829723] 'process raft request' (duration: 153.827301ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:58.322044Z","caller":"traceutil/trace.go:171","msg":"trace[668018718] linearizableReadLoop","detail":"{readStateIndex:25682; appliedIndex:25680; }","duration":"164.075713ms","start":"2026-05-11T11:38:58.157929Z","end":"2026-05-11T11:38:58.322005Z","steps":["trace[668018718] 'read index received' (duration: 151.401445ms)","trace[668018718] 'applied index is now lower than readState.Index' (duration: 12.672818ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:58.322192Z","caller":"traceutil/trace.go:171","msg":"trace[1822449198] transaction","detail":"{read_only:false; response_revision:24871; number_of_response:1; }","duration":"164.676214ms","start":"2026-05-11T11:38:58.1575Z","end":"2026-05-11T11:38:58.322176Z","steps":["trace[1822449198] 'process raft request' (duration: 164.321818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:58.322406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.723219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:58.322509Z","caller":"traceutil/trace.go:171","msg":"trace[325477607] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:24871; }","duration":"122.881742ms","start":"2026-05-11T11:38:58.199598Z","end":"2026-05-11T11:38:58.32248Z","steps":["trace[325477607] 'agreement among raft nodes before linearized reading' (duration: 122.723029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:58.322536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.596193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-11T11:38:58.322618Z","caller":"traceutil/trace.go:171","msg":"trace[1940337212] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24871; }","duration":"164.737256ms","start":"2026-05-11T11:38:58.157859Z","end":"2026-05-11T11:38:58.322596Z","steps":["trace[1940337212] 'agreement among raft nodes before linearized reading' (duration: 164.513152ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:58.856786Z","caller":"traceutil/trace.go:171","msg":"trace[1721400791] linearizableReadLoop","detail":"{readStateIndex:25686; appliedIndex:25685; }","duration":"178.414432ms","start":"2026-05-11T11:38:58.678352Z","end":"2026-05-11T11:38:58.856767Z","steps":["trace[1721400791] 'read index received' (duration: 178.29702ms)","trace[1721400791] 'applied index is now lower than readState.Index' (duration: 116.752µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:58.856847Z","caller":"traceutil/trace.go:171","msg":"trace[448768392] transaction","detail":"{read_only:false; response_revision:24875; number_of_response:1; }","duration":"277.202552ms","start":"2026-05-11T11:38:58.579613Z","end":"2026-05-11T11:38:58.856815Z","steps":["trace[448768392] 'process raft request' (duration: 277.027308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:58.856985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.606226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:58.85707Z","caller":"traceutil/trace.go:171","msg":"trace[38278362] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:24875; }","duration":"178.767959ms","start":"2026-05-11T11:38:58.678283Z","end":"2026-05-11T11:38:58.857051Z","steps":["trace[38278362] 'agreement among raft nodes before linearized reading' (duration: 178.559495ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:59.154015Z","caller":"traceutil/trace.go:171","msg":"trace[410825911] linearizableReadLoop","detail":"{readStateIndex:25688; appliedIndex:25687; }","duration":"141.927068ms","start":"2026-05-11T11:38:59.01206Z","end":"2026-05-11T11:38:59.153987Z","steps":["trace[410825911] 'read index received' (duration: 115.85258ms)","trace[410825911] 'applied index is now lower than readState.Index' (duration: 26.072778ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:59.154622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.792171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:59.15468Z","caller":"traceutil/trace.go:171","msg":"trace[635302812] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24876; }","duration":"137.922992ms","start":"2026-05-11T11:38:59.016745Z","end":"2026-05-11T11:38:59.154668Z","steps":["trace[635302812] 'agreement among raft nodes before linearized reading' (duration: 137.78272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:59.154868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.777444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-11T11:38:59.154924Z","caller":"traceutil/trace.go:171","msg":"trace[867196125] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:24876; }","duration":"142.910957ms","start":"2026-05-11T11:38:59.011998Z","end":"2026-05-11T11:38:59.154909Z","steps":["trace[867196125] 'agreement among raft nodes before linearized reading' (duration: 142.504779ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:59.293666Z","caller":"traceutil/trace.go:171","msg":"trace[2055311826] linearizableReadLoop","detail":"{readStateIndex:25689; appliedIndex:25688; }","duration":"124.052934ms","start":"2026-05-11T11:38:59.169574Z","end":"2026-05-11T11:38:59.293627Z","steps":["trace[2055311826] 'read index received' (duration: 123.675937ms)","trace[2055311826] 'applied index is now lower than readState.Index' (duration: 375.747µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:59.293897Z","caller":"traceutil/trace.go:171","msg":"trace[390297459] transaction","detail":"{read_only:false; response_revision:24877; number_of_response:1; }","duration":"133.114283ms","start":"2026-05-11T11:38:59.160767Z","end":"2026-05-11T11:38:59.293881Z","steps":["trace[390297459] 'process raft request' (duration: 132.592513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:59.294262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.661945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:59.294373Z","caller":"traceutil/trace.go:171","msg":"trace[277383816] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24877; }","duration":"124.833628ms","start":"2026-05-11T11:38:59.169512Z","end":"2026-05-11T11:38:59.294346Z","steps":["trace[277383816] 'agreement among raft nodes before linearized reading' (duration: 124.651565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:59.615292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.152342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:59.615877Z","caller":"traceutil/trace.go:171","msg":"trace[127162872] 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:24877; }","duration":"174.292915ms","start":"2026-05-11T11:38:59.441083Z","end":"2026-05-11T11:38:59.615376Z","steps":["trace[127162872] 'range keys from in-memory index tree' (duration: 174.03789ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:59.749692Z","caller":"traceutil/trace.go:171","msg":"trace[1515017615] transaction","detail":"{read_only:false; response_revision:24878; number_of_response:1; }","duration":"127.782314ms","start":"2026-05-11T11:38:59.621882Z","end":"2026-05-11T11:38:59.749665Z","steps":["trace[1515017615] 'process raft request' (duration: 127.578609ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:59.749982Z","caller":"traceutil/trace.go:171","msg":"trace[1527347922] linearizableReadLoop","detail":"{readStateIndex:25690; appliedIndex:25689; }","duration":"127.04283ms","start":"2026-05-11T11:38:59.622582Z","end":"2026-05-11T11:38:59.749625Z","steps":["trace[1527347922] 'read index received' (duration: 126.816425ms)","trace[1527347922] 'applied index is now lower than readState.Index' (duration: 222.835µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:59.750022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.380226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:59.750162Z","caller":"traceutil/trace.go:171","msg":"trace[775373332] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24878; }","duration":"127.5831ms","start":"2026-05-11T11:38:59.622545Z","end":"2026-05-11T11:38:59.750129Z","steps":["trace[775373332] 'agreement among raft nodes before linearized reading' (duration: 127.309475ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:00.012868Z","caller":"traceutil/trace.go:171","msg":"trace[784298451] linearizableReadLoop","detail":"{readStateIndex:25692; appliedIndex:25690; }","duration":"262.830132ms","start":"2026-05-11T11:38:59.750017Z","end":"2026-05-11T11:39:00.012847Z","steps":["trace[784298451] 'read index received' (duration: 262.614338ms)","trace[784298451] 'applied index is now lower than readState.Index' (duration: 215.134µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:39:00.01297Z","caller":"traceutil/trace.go:171","msg":"trace[1072490085] transaction","detail":"{read_only:false; response_revision:24880; number_of_response:1; }","duration":"272.742319ms","start":"2026-05-11T11:38:59.74022Z","end":"2026-05-11T11:39:00.012963Z","steps":["trace[1072490085] 'process raft request' (duration: 272.587155ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:00.012969Z","caller":"traceutil/trace.go:171","msg":"trace[1603620273] transaction","detail":"{read_only:false; response_revision:24879; number_of_response:1; }","duration":"278.410964ms","start":"2026-05-11T11:38:59.734516Z","end":"2026-05-11T11:39:00.012927Z","steps":["trace[1603620273] 'process raft request' (duration: 278.139949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:00.013166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.532989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:39:00.013229Z","caller":"traceutil/trace.go:171","msg":"trace[2026093909] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24880; }","duration":"245.657331ms","start":"2026-05-11T11:38:59.767555Z","end":"2026-05-11T11:39:00.013213Z","steps":["trace[2026093909] 'agreement among raft nodes before linearized reading' (duration: 245.550639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:00.013764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.583534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T11:39:00.013829Z","caller":"traceutil/trace.go:171","msg":"trace[2140470089] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:24880; }","duration":"353.229155ms","start":"2026-05-11T11:38:59.660584Z","end":"2026-05-11T11:39:00.013814Z","steps":["trace[2140470089] 'agreement among raft nodes before linearized reading' (duration: 352.573313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:00.013865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:59.66057Z","time spent":"353.283656ms","remote":"127.0.0.1:57734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":19,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-05-11T11:39:00.185953Z","caller":"traceutil/trace.go:171","msg":"trace[330379763] linearizableReadLoop","detail":"{readStateIndex:25693; appliedIndex:25692; }","duration":"114.854861ms","start":"2026-05-11T11:39:00.071079Z","end":"2026-05-11T11:39:00.185934Z","steps":["trace[330379763] 'read index received' (duration: 73.952606ms)","trace[330379763] 'applied index is now lower than readState.Index' (duration: 40.901645ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:39:00.186078Z","caller":"traceutil/trace.go:171","msg":"trace[1799153160] transaction","detail":"{read_only:false; response_revision:24881; number_of_response:1; }","duration":"145.1987ms","start":"2026-05-11T11:39:00.040862Z","end":"2026-05-11T11:39:00.186061Z","steps":["trace[1799153160] 'process raft request' (duration: 104.143712ms)","trace[1799153160] 'compare' (duration: 40.509828ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:39:00.186117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.032194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T11:39:00.186136Z","caller":"traceutil/trace.go:171","msg":"trace[1605977060] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24881; }","duration":"115.097025ms","start":"2026-05-11T11:39:00.071032Z","end":"2026-05-11T11:39:00.186129Z","steps":["trace[1605977060] 'agreement among raft nodes before linearized reading' (duration: 114.994543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:00.436866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.409061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T11:39:00.437071Z","caller":"traceutil/trace.go:171","msg":"trace[1808556923] transaction","detail":"{read_only:false; response_revision:24882; number_of_response:1; }","duration":"246.442926ms","start":"2026-05-11T11:39:00.190595Z","end":"2026-05-11T11:39:00.437038Z","steps":["trace[1808556923] 'process raft request' (duration: 114.77816ms)","trace[1808556923] 'compare' (duration: 131.102616ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:39:00.437118Z","caller":"traceutil/trace.go:171","msg":"trace[1325809543] transaction","detail":"{read_only:false; response_revision:24884; number_of_response:1; }","duration":"110.352256ms","start":"2026-05-11T11:39:00.326751Z","end":"2026-05-11T11:39:00.437103Z","steps":["trace[1325809543] 'process raft request' (duration: 110.310085ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:00.437214Z","caller":"traceutil/trace.go:171","msg":"trace[1529903121] linearizableReadLoop","detail":"{readStateIndex:25694; appliedIndex:25693; }","duration":"217.097517ms","start":"2026-05-11T11:39:00.220108Z","end":"2026-05-11T11:39:00.437205Z","steps":["trace[1529903121] 'read index received' (duration: 85.194837ms)","trace[1529903121] 'applied index is now lower than readState.Index' (duration: 131.90188ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:39:00.437373Z","caller":"traceutil/trace.go:171","msg":"trace[740585934] transaction","detail":"{read_only:false; response_revision:24883; number_of_response:1; }","duration":"121.113268ms","start":"2026-05-11T11:39:00.31625Z","end":"2026-05-11T11:39:00.437363Z","steps":["trace[740585934] 'process raft request' (duration: 120.727301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:00.437483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.377512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T11:39:00.437519Z","caller":"traceutil/trace.go:171","msg":"trace[1911781905] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24884; }","duration":"217.458074ms","start":"2026-05-11T11:39:00.220049Z","end":"2026-05-11T11:39:00.437507Z","steps":["trace[1911781905] 'agreement among raft nodes before linearized reading' (duration: 217.396962ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:00.710082Z","caller":"traceutil/trace.go:171","msg":"trace[1317765710] transaction","detail":"{read_only:false; response_revision:24887; number_of_response:1; }","duration":"265.40028ms","start":"2026-05-11T11:39:00.444655Z","end":"2026-05-11T11:39:00.710056Z","steps":["trace[1317765710] 'process raft request' (duration: 265.362449ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:00.710429Z","caller":"traceutil/trace.go:171","msg":"trace[2121804395] transaction","detail":"{read_only:false; response_revision:24885; number_of_response:1; }","duration":"267.625361ms","start":"2026-05-11T11:39:00.442793Z","end":"2026-05-11T11:39:00.710418Z","steps":["trace[2121804395] 'process raft request' (duration: 239.213319ms)","trace[2121804395] 'compare' (duration: 27.815431ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:39:00.710516Z","caller":"traceutil/trace.go:171","msg":"trace[1649363121] transaction","detail":"{read_only:false; response_revision:24886; number_of_response:1; }","duration":"266.38235ms","start":"2026-05-11T11:39:00.444127Z","end":"2026-05-11T11:39:00.710509Z","steps":["trace[1649363121] 'process raft request' (duration: 265.844049ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:01.426377Z","caller":"traceutil/trace.go:171","msg":"trace[1245669718] transaction","detail":"{read_only:false; response_revision:24890; number_of_response:1; }","duration":"123.477592ms","start":"2026-05-11T11:39:01.302875Z","end":"2026-05-11T11:39:01.426353Z","steps":["trace[1245669718] 'process raft request' (duration: 123.333239ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:01.948488Z","caller":"traceutil/trace.go:171","msg":"trace[1743862168] linearizableReadLoop","detail":"{readStateIndex:25703; appliedIndex:25702; }","duration":"302.687408ms","start":"2026-05-11T11:39:01.645776Z","end":"2026-05-11T11:39:01.948463Z","steps":["trace[1743862168] 'read index received' (duration: 302.492475ms)","trace[1743862168] 'applied index is now lower than readState.Index' (duration: 192.183µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:39:01.948529Z","caller":"traceutil/trace.go:171","msg":"trace[1191240406] transaction","detail":"{read_only:false; response_revision:24891; number_of_response:1; }","duration":"335.131586ms","start":"2026-05-11T11:39:01.613374Z","end":"2026-05-11T11:39:01.948506Z","steps":["trace[1191240406] 'process raft request' (duration: 334.917272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:01.948698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.922384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-11T11:39:01.948742Z","caller":"traceutil/trace.go:171","msg":"trace[464032378] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24891; }","duration":"303.034696ms","start":"2026-05-11T11:39:01.645697Z","end":"2026-05-11T11:39:01.948732Z","steps":["trace[464032378] 'agreement among raft nodes before linearized reading' (duration: 302.889303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:01.948776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:01.645654Z","time spent":"303.111148ms","remote":"127.0.0.1:57914","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-05-11T11:39:01.948784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:01.613355Z","time spent":"335.223528ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:39:02.066054Z","caller":"traceutil/trace.go:171","msg":"trace[656561053] transaction","detail":"{read_only:false; response_revision:24892; number_of_response:1; }","duration":"308.660331ms","start":"2026-05-11T11:39:01.757357Z","end":"2026-05-11T11:39:02.066017Z","steps":["trace[656561053] 'process raft request' (duration: 267.960599ms)","trace[656561053] 'compare' (duration: 39.646773ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:39:02.066261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:01.757317Z","time spent":"308.821353ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:39:02.066374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.433142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:39:02.066309Z","caller":"traceutil/trace.go:171","msg":"trace[1506566219] linearizableReadLoop","detail":"{readStateIndex:25704; appliedIndex:25703; }","duration":"117.682404ms","start":"2026-05-11T11:39:01.948563Z","end":"2026-05-11T11:39:02.066245Z","steps":["trace[1506566219] 'read index received' (duration: 76.701797ms)","trace[1506566219] 'applied index is now lower than readState.Index' (duration: 40.978267ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:39:02.066448Z","caller":"traceutil/trace.go:171","msg":"trace[1393923226] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:24892; }","duration":"163.565055ms","start":"2026-05-11T11:39:01.902866Z","end":"2026-05-11T11:39:02.066431Z","steps":["trace[1393923226] 'agreement among raft nodes before linearized reading' (duration: 163.412281ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:02.144075Z","caller":"traceutil/trace.go:171","msg":"trace[1076794397] transaction","detail":"{read_only:false; response_revision:24893; number_of_response:1; }","duration":"190.158262ms","start":"2026-05-11T11:39:01.953896Z","end":"2026-05-11T11:39:02.144054Z","steps":["trace[1076794397] 'process raft request' (duration: 189.971078ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:02.144572Z","caller":"traceutil/trace.go:171","msg":"trace[86416935] transaction","detail":"{read_only:false; response_revision:24894; number_of_response:1; }","duration":"126.190973ms","start":"2026-05-11T11:39:02.018364Z","end":"2026-05-11T11:39:02.144555Z","steps":["trace[86416935] 'process raft request' (duration: 125.634942ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:02.144592Z","caller":"traceutil/trace.go:171","msg":"trace[78738078] transaction","detail":"{read_only:false; response_revision:24895; number_of_response:1; }","duration":"125.054902ms","start":"2026-05-11T11:39:02.019497Z","end":"2026-05-11T11:39:02.144552Z","steps":["trace[78738078] 'process raft request' (duration: 124.822387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:02.144868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.762487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:39:02.144909Z","caller":"traceutil/trace.go:171","msg":"trace[2075419578] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:24895; }","duration":"163.858819ms","start":"2026-05-11T11:39:01.981039Z","end":"2026-05-11T11:39:02.144898Z","steps":["trace[2075419578] 'agreement among raft nodes before linearized reading' (duration: 163.782058ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:02.358592Z","caller":"traceutil/trace.go:171","msg":"trace[780077144] transaction","detail":"{read_only:false; response_revision:24896; number_of_response:1; }","duration":"165.487559ms","start":"2026-05-11T11:39:02.193037Z","end":"2026-05-11T11:39:02.358525Z","steps":["trace[780077144] 'process raft request' (duration: 164.941169ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:02.414779Z","caller":"traceutil/trace.go:171","msg":"trace[1681532947] transaction","detail":"{read_only:false; response_revision:24897; number_of_response:1; }","duration":"110.935588ms","start":"2026-05-11T11:39:02.303812Z","end":"2026-05-11T11:39:02.414748Z","steps":["trace[1681532947] 'process raft request' (duration: 74.879462ms)","trace[1681532947] 'compare' (duration: 35.785251ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:39:02.875494Z","caller":"traceutil/trace.go:171","msg":"trace[728906485] transaction","detail":"{read_only:false; response_revision:24901; number_of_response:1; }","duration":"139.354469ms","start":"2026-05-11T11:39:02.736112Z","end":"2026-05-11T11:39:02.875467Z","steps":["trace[728906485] 'process raft request' (duration: 139.169316ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:02.887763Z","caller":"traceutil/trace.go:171","msg":"trace[1106711490] transaction","detail":"{read_only:false; response_revision:24902; number_of_response:1; }","duration":"151.61985ms","start":"2026-05-11T11:39:02.736126Z","end":"2026-05-11T11:39:02.887746Z","steps":["trace[1106711490] 'process raft request' (duration: 151.474577ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:02.887961Z","caller":"traceutil/trace.go:171","msg":"trace[1223084850] transaction","detail":"{read_only:false; response_revision:24903; number_of_response:1; }","duration":"151.316724ms","start":"2026-05-11T11:39:02.736621Z","end":"2026-05-11T11:39:02.887938Z","steps":["trace[1223084850] 'process raft request' (duration: 151.07978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:08.780374Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219755530605075,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T11:39:09.28063Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219755530605075,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T11:39:09.313508Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.146387481s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T11:39:09.313985Z","caller":"traceutil/trace.go:171","msg":"trace[1648911806] transaction","detail":"{read_only:false; response_revision:24939; number_of_response:1; }","duration":"1.146957851s","start":"2026-05-11T11:39:08.167Z","end":"2026-05-11T11:39:09.313958Z","steps":["trace[1648911806] 'process raft request' (duration: 1.146791618s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:09.31419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:08.166973Z","time spent":"1.147127765s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:39:09.403619Z","caller":"traceutil/trace.go:171","msg":"trace[1058865286] transaction","detail":"{read_only:false; response_revision:24940; number_of_response:1; }","duration":"1.236137422s","start":"2026-05-11T11:39:08.167444Z","end":"2026-05-11T11:39:09.403581Z","steps":["trace[1058865286] 'process raft request' (duration: 1.23502445s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:09.403829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:08.167428Z","time spent":"1.236294645s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:39:09.404349Z","caller":"traceutil/trace.go:171","msg":"trace[12160395] transaction","detail":"{read_only:false; response_revision:24941; number_of_response:1; }","duration":"1.026376882s","start":"2026-05-11T11:39:08.377927Z","end":"2026-05-11T11:39:09.404304Z","steps":["trace[12160395] 'process raft request' (duration: 1.025517466s)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:09.40442Z","caller":"traceutil/trace.go:171","msg":"trace[1611269715] transaction","detail":"{read_only:false; response_revision:24942; number_of_response:1; }","duration":"937.416057ms","start":"2026-05-11T11:39:08.466975Z","end":"2026-05-11T11:39:09.404391Z","steps":["trace[1611269715] 'process raft request' (duration: 936.811176ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:09.404434Z","caller":"traceutil/trace.go:171","msg":"trace[516724340] transaction","detail":"{read_only:false; response_revision:24943; number_of_response:1; }","duration":"922.785682ms","start":"2026-05-11T11:39:08.481598Z","end":"2026-05-11T11:39:09.404384Z","steps":["trace[516724340] 'process raft request' (duration: 922.252892ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:09.404473Z","caller":"traceutil/trace.go:171","msg":"trace[109401014] transaction","detail":"{read_only:false; response_revision:24944; number_of_response:1; }","duration":"483.592788ms","start":"2026-05-11T11:39:08.920803Z","end":"2026-05-11T11:39:09.404395Z","steps":["trace[109401014] 'process raft request' (duration: 483.098699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:09.404546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:08.466957Z","time spent":"937.52912ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:39:09.404653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:08.481573Z","time spent":"922.963266ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:39:09.404671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:08.377897Z","time spent":"1.026589576s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:39:09.404724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:08.920778Z","time spent":"483.829392ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:39:09.40748Z","caller":"traceutil/trace.go:171","msg":"trace[510230073] linearizableReadLoop","detail":"{readStateIndex:25758; appliedIndex:25751; }","duration":"1.127599308s","start":"2026-05-11T11:39:08.279858Z","end":"2026-05-11T11:39:09.407457Z","steps":["trace[510230073] 'read index received' (duration: 1.033973815s)","trace[510230073] 'applied index is now lower than readState.Index' (duration: 93.624293ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:39:09.407604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127747341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:39:09.407651Z","caller":"traceutil/trace.go:171","msg":"trace[1734982370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24944; }","duration":"1.127824212s","start":"2026-05-11T11:39:08.279815Z","end":"2026-05-11T11:39:09.407639Z","steps":["trace[1734982370] 'agreement among raft nodes before linearized reading' (duration: 1.12774162s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:09.407713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.389034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:39:09.407774Z","caller":"traceutil/trace.go:171","msg":"trace[588591291] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24944; }","duration":"404.510886ms","start":"2026-05-11T11:39:09.003248Z","end":"2026-05-11T11:39:09.407759Z","steps":["trace[588591291] 'agreement among raft nodes before linearized reading' (duration: 404.406914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:09.407791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.998506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-11T11:39:09.407794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.993129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-11T11:39:09.407819Z","caller":"traceutil/trace.go:171","msg":"trace[374994072] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24944; }","duration":"506.065388ms","start":"2026-05-11T11:39:08.901744Z","end":"2026-05-11T11:39:09.407809Z","steps":["trace[374994072] 'agreement among raft nodes before linearized reading' (duration: 506.006427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:09.407727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.523249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T11:39:09.407843Z","caller":"traceutil/trace.go:171","msg":"trace[1938156814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24944; }","duration":"228.077461ms","start":"2026-05-11T11:39:09.179757Z","end":"2026-05-11T11:39:09.407834Z","steps":["trace[1938156814] 'agreement among raft nodes before linearized reading' (duration: 227.949379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:09.407848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:08.901698Z","time spent":"506.144588ms","remote":"127.0.0.1:57914","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":"info","ts":"2026-05-11T11:39:09.407854Z","caller":"traceutil/trace.go:171","msg":"trace[34332393] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24944; }","duration":"890.699091ms","start":"2026-05-11T11:39:08.517145Z","end":"2026-05-11T11:39:09.407844Z","steps":["trace[34332393] 'agreement among raft nodes before linearized reading' (duration: 890.446197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:09.407882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:08.517129Z","time spent":"890.745133ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T11:39:09.407899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.458036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-05-11T11:39:09.407812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:09.003213Z","time spent":"404.589857ms","remote":"127.0.0.1:58792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-11T11:39:09.407952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.909675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:39:09.407969Z","caller":"traceutil/trace.go:171","msg":"trace[1740667297] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:24944; }","duration":"858.950027ms","start":"2026-05-11T11:39:08.549014Z","end":"2026-05-11T11:39:09.407964Z","steps":["trace[1740667297] 'agreement among raft nodes before linearized reading' (duration: 858.907626ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:09.407962Z","caller":"traceutil/trace.go:171","msg":"trace[1660822971] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24944; }","duration":"498.558327ms","start":"2026-05-11T11:39:08.909385Z","end":"2026-05-11T11:39:09.407943Z","steps":["trace[1660822971] 'agreement among raft nodes before linearized reading' (duration: 498.407225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:09.407993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:08.549003Z","time spent":"858.985367ms","remote":"127.0.0.1:57848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-05-11T11:39:09.408005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:08.909375Z","time spent":"498.621549ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-11T11:39:09.407674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:08.279797Z","time spent":"1.127872963s","remote":"127.0.0.1:57612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T11:39:09.408146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.776226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-11T11:39:09.408486Z","caller":"traceutil/trace.go:171","msg":"trace[452948449] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24944; }","duration":"503.954099ms","start":"2026-05-11T11:39:08.904349Z","end":"2026-05-11T11:39:09.408303Z","steps":["trace[452948449] 'agreement among raft nodes before linearized reading' (duration: 503.46401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:09.409992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:08.90427Z","time spent":"505.686191ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T11:39:14.642833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.156209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a345fc7e-83de-45f2-ad44-3a2eca86f070\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-05-11T11:39:14.642905Z","caller":"traceutil/trace.go:171","msg":"trace[40499607] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a345fc7e-83de-45f2-ad44-3a2eca86f070; range_end:; response_count:1; response_revision:25039; }","duration":"153.275431ms","start":"2026-05-11T11:39:14.489611Z","end":"2026-05-11T11:39:14.642886Z","steps":["trace[40499607] 'range keys from in-memory index tree' (duration: 152.962996ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:15.459812Z","caller":"traceutil/trace.go:171","msg":"trace[433456343] transaction","detail":"{read_only:false; response_revision:25040; number_of_response:1; }","duration":"222.512268ms","start":"2026-05-11T11:39:15.237273Z","end":"2026-05-11T11:39:15.459785Z","steps":["trace[433456343] 'process raft request' (duration: 222.321044ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:15.580499Z","caller":"traceutil/trace.go:171","msg":"trace[1635582257] transaction","detail":"{read_only:false; response_revision:25041; number_of_response:1; }","duration":"245.917265ms","start":"2026-05-11T11:39:15.334562Z","end":"2026-05-11T11:39:15.580479Z","steps":["trace[1635582257] 'process raft request' (duration: 242.47189ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:15.580783Z","caller":"traceutil/trace.go:171","msg":"trace[866587252] transaction","detail":"{read_only:false; response_revision:25042; number_of_response:1; }","duration":"192.429964ms","start":"2026-05-11T11:39:15.388338Z","end":"2026-05-11T11:39:15.580768Z","steps":["trace[866587252] 'process raft request' (duration: 192.097618ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:15.580831Z","caller":"traceutil/trace.go:171","msg":"trace[1274560754] transaction","detail":"{read_only:false; response_revision:25043; number_of_response:1; }","duration":"154.250568ms","start":"2026-05-11T11:39:15.426556Z","end":"2026-05-11T11:39:15.580807Z","steps":["trace[1274560754] 'process raft request' (duration: 154.101336ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:15.581112Z","caller":"traceutil/trace.go:171","msg":"trace[1356233545] transaction","detail":"{read_only:false; response_revision:25045; number_of_response:1; }","duration":"147.091766ms","start":"2026-05-11T11:39:15.434005Z","end":"2026-05-11T11:39:15.581096Z","steps":["trace[1356233545] 'process raft request' (duration: 146.993074ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:15.581145Z","caller":"traceutil/trace.go:171","msg":"trace[1295795247] transaction","detail":"{read_only:false; response_revision:25044; number_of_response:1; }","duration":"148.210275ms","start":"2026-05-11T11:39:15.432916Z","end":"2026-05-11T11:39:15.581126Z","steps":["trace[1295795247] 'process raft request' (duration: 147.843709ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:15.581246Z","caller":"traceutil/trace.go:171","msg":"trace[335885284] linearizableReadLoop","detail":"{readStateIndex:25862; appliedIndex:25854; }","duration":"146.066575ms","start":"2026-05-11T11:39:15.435159Z","end":"2026-05-11T11:39:15.581225Z","steps":["trace[335885284] 'read index received' (duration: 24.452887ms)","trace[335885284] 'applied index is now lower than readState.Index' (duration: 121.612788ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:39:15.581278Z","caller":"traceutil/trace.go:171","msg":"trace[1534009111] transaction","detail":"{read_only:false; response_revision:25047; number_of_response:1; }","duration":"146.362261ms","start":"2026-05-11T11:39:15.43487Z","end":"2026-05-11T11:39:15.581233Z","steps":["trace[1534009111] 'process raft request' (duration: 146.29539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:15.58224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.313543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-11T11:39:15.581275Z","caller":"traceutil/trace.go:171","msg":"trace[346189541] transaction","detail":"{read_only:false; response_revision:25046; number_of_response:1; }","duration":"147.082435ms","start":"2026-05-11T11:39:15.434154Z","end":"2026-05-11T11:39:15.581236Z","steps":["trace[346189541] 'process raft request' (duration: 146.910062ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:15.582289Z","caller":"traceutil/trace.go:171","msg":"trace[1166635118] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25047; }","duration":"143.393405ms","start":"2026-05-11T11:39:15.43888Z","end":"2026-05-11T11:39:15.582274Z","steps":["trace[1166635118] 'agreement among raft nodes before linearized reading' (duration: 143.285313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:15.581426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.249858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-11T11:39:15.582377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.545544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T11:39:15.582411Z","caller":"traceutil/trace.go:171","msg":"trace[1169515907] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25047; }","duration":"147.275178ms","start":"2026-05-11T11:39:15.435119Z","end":"2026-05-11T11:39:15.582394Z","steps":["trace[1169515907] 'agreement among raft nodes before linearized reading' (duration: 146.177527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:15.582411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.774648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a345fc7e-83de-45f2-ad44-3a2eca86f070\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-05-11T11:39:15.58243Z","caller":"traceutil/trace.go:171","msg":"trace[1346545199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25047; }","duration":"139.628465ms","start":"2026-05-11T11:39:15.442786Z","end":"2026-05-11T11:39:15.582414Z","steps":["trace[1346545199] 'agreement among raft nodes before linearized reading' (duration: 139.486322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:15.582451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.693055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-11T11:39:15.582472Z","caller":"traceutil/trace.go:171","msg":"trace[752434028] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a345fc7e-83de-45f2-ad44-3a2eca86f070; range_end:; response_count:1; response_revision:25047; }","duration":"108.856829ms","start":"2026-05-11T11:39:15.4736Z","end":"2026-05-11T11:39:15.582457Z","steps":["trace[752434028] 'agreement among raft nodes before linearized reading' (duration: 108.731906ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:15.582515Z","caller":"traceutil/trace.go:171","msg":"trace[371352087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25047; }","duration":"140.829138ms","start":"2026-05-11T11:39:15.441675Z","end":"2026-05-11T11:39:15.582504Z","steps":["trace[371352087] 'agreement among raft nodes before linearized reading' (duration: 140.666175ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:17.715998Z","caller":"traceutil/trace.go:171","msg":"trace[206728913] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"128.522667ms","start":"2026-05-11T11:39:17.587449Z","end":"2026-05-11T11:39:17.715972Z","steps":["trace[206728913] 'process raft request' (duration: 128.300563ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:17.716276Z","caller":"traceutil/trace.go:171","msg":"trace[580142610] transaction","detail":"{read_only:false; response_revision:25060; number_of_response:1; }","duration":"128.499956ms","start":"2026-05-11T11:39:17.58775Z","end":"2026-05-11T11:39:17.71625Z","steps":["trace[580142610] 'process raft request' (duration: 128.17771ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:17.716546Z","caller":"traceutil/trace.go:171","msg":"trace[620996373] transaction","detail":"{read_only:false; response_revision:25061; number_of_response:1; }","duration":"128.288392ms","start":"2026-05-11T11:39:17.588245Z","end":"2026-05-11T11:39:17.716533Z","steps":["trace[620996373] 'process raft request' (duration: 127.918706ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:17.716733Z","caller":"traceutil/trace.go:171","msg":"trace[1479489425] transaction","detail":"{read_only:false; response_revision:25062; number_of_response:1; }","duration":"128.319413ms","start":"2026-05-11T11:39:17.588405Z","end":"2026-05-11T11:39:17.716724Z","steps":["trace[1479489425] 'process raft request' (duration: 128.091069ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:17.716883Z","caller":"traceutil/trace.go:171","msg":"trace[401612654] linearizableReadLoop","detail":"{readStateIndex:25878; appliedIndex:25872; }","duration":"124.185176ms","start":"2026-05-11T11:39:17.592688Z","end":"2026-05-11T11:39:17.716873Z","steps":["trace[401612654] 'read index received' (duration: 6.247197ms)","trace[401612654] 'applied index is now lower than readState.Index' (duration: 117.936939ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:39:17.717114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.40344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-11T11:39:17.717169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.983274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-11T11:39:17.717184Z","caller":"traceutil/trace.go:171","msg":"trace[150839586] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25063; }","duration":"124.492641ms","start":"2026-05-11T11:39:17.592667Z","end":"2026-05-11T11:39:17.717159Z","steps":["trace[150839586] 'agreement among raft nodes before linearized reading' (duration: 124.315908ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:17.71721Z","caller":"traceutil/trace.go:171","msg":"trace[180813010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25063; }","duration":"123.050365ms","start":"2026-05-11T11:39:17.594149Z","end":"2026-05-11T11:39:17.7172Z","steps":["trace[180813010] 'agreement among raft nodes before linearized reading' (duration: 122.908832ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:17.717362Z","caller":"traceutil/trace.go:171","msg":"trace[1566956603] transaction","detail":"{read_only:false; response_revision:25063; number_of_response:1; }","duration":"127.524367ms","start":"2026-05-11T11:39:17.589825Z","end":"2026-05-11T11:39:17.71735Z","steps":["trace[1566956603] 'process raft request' (duration: 126.857465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:17.717558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.062026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T11:39:17.717589Z","caller":"traceutil/trace.go:171","msg":"trace[1973617305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25063; }","duration":"122.109828ms","start":"2026-05-11T11:39:17.595469Z","end":"2026-05-11T11:39:17.717579Z","steps":["trace[1973617305] 'agreement among raft nodes before linearized reading' (duration: 122.023715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:17.717738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.25987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T11:39:17.717765Z","caller":"traceutil/trace.go:171","msg":"trace[729516983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25063; }","duration":"122.326421ms","start":"2026-05-11T11:39:17.595431Z","end":"2026-05-11T11:39:17.717758Z","steps":["trace[729516983] 'agreement among raft nodes before linearized reading' (duration: 122.223479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:17.717875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.469044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-11T11:39:17.717902Z","caller":"traceutil/trace.go:171","msg":"trace[906525613] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25063; }","duration":"122.504314ms","start":"2026-05-11T11:39:17.595386Z","end":"2026-05-11T11:39:17.71789Z","steps":["trace[906525613] 'agreement among raft nodes before linearized reading' (duration: 122.439563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:17.717936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.084205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-11T11:39:17.717991Z","caller":"traceutil/trace.go:171","msg":"trace[368075748] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25063; }","duration":"123.182937ms","start":"2026-05-11T11:39:17.594795Z","end":"2026-05-11T11:39:17.717978Z","steps":["trace[368075748] 'agreement among raft nodes before linearized reading' (duration: 123.042404ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:49.132794Z","caller":"traceutil/trace.go:171","msg":"trace[2064039458] transaction","detail":"{read_only:false; response_revision:25338; number_of_response:1; }","duration":"404.773881ms","start":"2026-05-11T11:39:48.727963Z","end":"2026-05-11T11:39:49.132737Z","steps":["trace[2064039458] 'process raft request' (duration: 404.607928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:49.132977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:48.727951Z","time spent":"404.935444ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:39:49.67211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.30051ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-11T11:39:49.672241Z","caller":"traceutil/trace.go:171","msg":"trace[1317358449] linearizableReadLoop","detail":"{readStateIndex:26162; appliedIndex:26161; }","duration":"393.730644ms","start":"2026-05-11T11:39:49.278488Z","end":"2026-05-11T11:39:49.672219Z","steps":["trace[1317358449] 'read index received' (duration: 30.461µs)","trace[1317358449] 'applied index is now lower than readState.Index' (duration: 393.698513ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:39:49.672504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.995479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:39:49.672592Z","caller":"traceutil/trace.go:171","msg":"trace[56882188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25338; }","duration":"394.116791ms","start":"2026-05-11T11:39:49.278454Z","end":"2026-05-11T11:39:49.672571Z","steps":["trace[56882188] 'agreement among raft nodes before linearized reading' (duration: 393.973779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:49.672639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:49.278434Z","time spent":"394.192392ms","remote":"127.0.0.1:57612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T11:39:49.673325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.533895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T11:39:49.673414Z","caller":"traceutil/trace.go:171","msg":"trace[1988416124] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:25338; }","duration":"351.389491ms","start":"2026-05-11T11:39:49.322006Z","end":"2026-05-11T11:39:49.673396Z","steps":["trace[1988416124] 'agreement among raft nodes before linearized reading' (duration: 350.497714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:49.673581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:49.321987Z","time spent":"351.514034ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":19,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-05-11T11:39:49.828009Z","caller":"traceutil/trace.go:171","msg":"trace[1509565245] transaction","detail":"{read_only:false; response_revision:25339; number_of_response:1; }","duration":"115.015784ms","start":"2026-05-11T11:39:49.71297Z","end":"2026-05-11T11:39:49.827985Z","steps":["trace[1509565245] 'process raft request' (duration: 114.888682ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:50.095321Z","caller":"traceutil/trace.go:171","msg":"trace[1537740499] transaction","detail":"{read_only:false; response_revision:25340; number_of_response:1; }","duration":"265.805838ms","start":"2026-05-11T11:39:49.829486Z","end":"2026-05-11T11:39:50.095292Z","steps":["trace[1537740499] 'process raft request' (duration: 265.617725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:50.47444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.81271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T11:39:50.474621Z","caller":"traceutil/trace.go:171","msg":"trace[95323976] transaction","detail":"{read_only:false; response_revision:25341; number_of_response:1; }","duration":"633.988975ms","start":"2026-05-11T11:39:49.840611Z","end":"2026-05-11T11:39:50.4746Z","steps":["trace[95323976] 'process raft request' (duration: 502.94461ms)","trace[95323976] 'compare' (duration: 129.971784ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:39:50.474703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:49.840586Z","time spent":"634.079276ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:39:50.474878Z","caller":"traceutil/trace.go:171","msg":"trace[341691455] transaction","detail":"{read_only:false; response_revision:25342; number_of_response:1; }","duration":"630.571909ms","start":"2026-05-11T11:39:49.844268Z","end":"2026-05-11T11:39:50.47484Z","steps":["trace[341691455] 'process raft request' (duration: 630.269794ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:50.474931Z","caller":"traceutil/trace.go:171","msg":"trace[1675711865] transaction","detail":"{read_only:false; response_revision:25343; number_of_response:1; }","duration":"630.245624ms","start":"2026-05-11T11:39:49.844671Z","end":"2026-05-11T11:39:50.474917Z","steps":["trace[1675711865] 'process raft request' (duration: 630.108751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:50.475037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:49.844666Z","time spent":"630.302684ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:39:50.475044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:49.84426Z","time spent":"630.698272ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:39:50.475081Z","caller":"traceutil/trace.go:171","msg":"trace[575437834] linearizableReadLoop","detail":"{readStateIndex:26168; appliedIndex:26163; }","duration":"627.451321ms","start":"2026-05-11T11:39:49.847611Z","end":"2026-05-11T11:39:50.475062Z","steps":["trace[575437834] 'read index received' (duration: 247.407793ms)","trace[575437834] 'applied index is now lower than readState.Index' (duration: 380.042078ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:39:50.475319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.704576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T11:39:50.475373Z","caller":"traceutil/trace.go:171","msg":"trace[1868515031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25344; }","duration":"627.792848ms","start":"2026-05-11T11:39:49.847563Z","end":"2026-05-11T11:39:50.475356Z","steps":["trace[1868515031] 'agreement among raft nodes before linearized reading' (duration: 627.621844ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:50.47526Z","caller":"traceutil/trace.go:171","msg":"trace[1252924811] transaction","detail":"{read_only:false; response_revision:25344; number_of_response:1; }","duration":"630.341356ms","start":"2026-05-11T11:39:49.844859Z","end":"2026-05-11T11:39:50.4752Z","steps":["trace[1252924811] 'process raft request' (duration: 630.02641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:50.475423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:49.847546Z","time spent":"627.870189ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T11:39:50.475616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:49.844826Z","time spent":"630.62837ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:39:50.530668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.3284ms","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-05-11T11:39:50.530737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.567804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-11T11:39:50.530771Z","caller":"traceutil/trace.go:171","msg":"trace[206995065] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25345; }","duration":"218.641775ms","start":"2026-05-11T11:39:50.31212Z","end":"2026-05-11T11:39:50.530762Z","steps":["trace[206995065] 'agreement among raft nodes before linearized reading' (duration: 218.488082ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:50.530758Z","caller":"traceutil/trace.go:171","msg":"trace[966884604] 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:25345; }","duration":"398.500493ms","start":"2026-05-11T11:39:50.132237Z","end":"2026-05-11T11:39:50.530737Z","steps":["trace[966884604] 'agreement among raft nodes before linearized reading' (duration: 398.32871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:50.530809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:50.132203Z","time spent":"398.595555ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-05-11T11:39:50.53079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.712464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-11T11:39:50.530955Z","caller":"traceutil/trace.go:171","msg":"trace[1925838077] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25345; }","duration":"660.896568ms","start":"2026-05-11T11:39:49.870019Z","end":"2026-05-11T11:39:50.530916Z","steps":["trace[1925838077] 'agreement among raft nodes before linearized reading' (duration: 660.49209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:50.53102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.913624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-11T11:39:50.531037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:49.86993Z","time spent":"661.090511ms","remote":"127.0.0.1:57914","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-05-11T11:39:50.531014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.137494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-11T11:39:50.530847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.529131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-11T11:39:50.531119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.135127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-11T11:39:50.531149Z","caller":"traceutil/trace.go:171","msg":"trace[2074007515] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25345; }","duration":"636.328317ms","start":"2026-05-11T11:39:49.89479Z","end":"2026-05-11T11:39:50.531118Z","steps":["trace[2074007515] 'agreement among raft nodes before linearized reading' (duration: 635.91108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:50.530849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.96367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:39:50.531172Z","caller":"traceutil/trace.go:171","msg":"trace[2070783519] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25345; }","duration":"586.231599ms","start":"2026-05-11T11:39:49.944924Z","end":"2026-05-11T11:39:50.531156Z","steps":["trace[2070783519] 'agreement among raft nodes before linearized reading' (duration: 586.112377ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:50.531202Z","caller":"traceutil/trace.go:171","msg":"trace[729990886] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25345; }","duration":"362.344517ms","start":"2026-05-11T11:39:50.168844Z","end":"2026-05-11T11:39:50.531189Z","steps":["trace[729990886] 'agreement among raft nodes before linearized reading' (duration: 361.899798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:50.531204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:49.894777Z","time spent":"636.41868ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T11:39:50.531213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:49.94489Z","time spent":"586.315221ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-11T11:39:50.531247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:50.168821Z","time spent":"362.412617ms","remote":"127.0.0.1:52820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":28,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"info","ts":"2026-05-11T11:39:50.53113Z","caller":"traceutil/trace.go:171","msg":"trace[1979906981] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25345; }","duration":"652.871537ms","start":"2026-05-11T11:39:49.878248Z","end":"2026-05-11T11:39:50.531119Z","steps":["trace[1979906981] 'agreement among raft nodes before linearized reading' (duration: 652.47151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:50.531323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:49.878224Z","time spent":"653.090092ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-11T11:39:50.531074Z","caller":"traceutil/trace.go:171","msg":"trace[1376339505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25345; }","duration":"655.976075ms","start":"2026-05-11T11:39:49.875079Z","end":"2026-05-11T11:39:50.531055Z","steps":["trace[1376339505] 'agreement among raft nodes before linearized reading' (duration: 655.826292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:50.531445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:49.875068Z","time spent":"656.365253ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-11T11:39:50.531074Z","caller":"traceutil/trace.go:171","msg":"trace[1720621330] transaction","detail":"{read_only:false; response_revision:25345; number_of_response:1; }","duration":"277.0968ms","start":"2026-05-11T11:39:50.253959Z","end":"2026-05-11T11:39:50.531055Z","steps":["trace[1720621330] 'process raft request' (duration: 276.304316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:50.533644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.68798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T11:39:50.533796Z","caller":"traceutil/trace.go:171","msg":"trace[1968758739] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:25345; }","duration":"443.810863ms","start":"2026-05-11T11:39:50.089892Z","end":"2026-05-11T11:39:50.533702Z","steps":["trace[1968758739] 'agreement among raft nodes before linearized reading' (duration: 440.908918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:50.533928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:50.08987Z","time spent":"444.012606ms","remote":"127.0.0.1:58000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":99,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-05-11T11:39:50.692296Z","caller":"traceutil/trace.go:171","msg":"trace[775746356] transaction","detail":"{read_only:false; response_revision:25348; number_of_response:1; }","duration":"155.36678ms","start":"2026-05-11T11:39:50.536899Z","end":"2026-05-11T11:39:50.692266Z","steps":["trace[775746356] 'process raft request' (duration: 155.147746ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:50.692417Z","caller":"traceutil/trace.go:171","msg":"trace[824386540] transaction","detail":"{read_only:false; response_revision:25352; number_of_response:1; }","duration":"109.812918ms","start":"2026-05-11T11:39:50.582577Z","end":"2026-05-11T11:39:50.69239Z","steps":["trace[824386540] 'process raft request' (duration: 109.695675ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:50.692575Z","caller":"traceutil/trace.go:171","msg":"trace[777558269] transaction","detail":"{read_only:false; response_revision:25347; number_of_response:1; }","duration":"157.379568ms","start":"2026-05-11T11:39:50.535182Z","end":"2026-05-11T11:39:50.692562Z","steps":["trace[777558269] 'process raft request' (duration: 82.791331ms)","trace[777558269] 'compare' (duration: 73.741531ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:39:50.692318Z","caller":"traceutil/trace.go:171","msg":"trace[1436665769] linearizableReadLoop","detail":"{readStateIndex:26175; appliedIndex:26170; }","duration":"144.19605ms","start":"2026-05-11T11:39:50.548104Z","end":"2026-05-11T11:39:50.6923Z","steps":["trace[1436665769] 'read index received' (duration: 69.895329ms)","trace[1436665769] 'applied index is now lower than readState.Index' (duration: 74.299251ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:39:50.692574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.44231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:39:50.69269Z","caller":"traceutil/trace.go:171","msg":"trace[484828433] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25352; }","duration":"156.619403ms","start":"2026-05-11T11:39:50.536059Z","end":"2026-05-11T11:39:50.692678Z","steps":["trace[484828433] 'agreement among raft nodes before linearized reading' (duration: 156.294596ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:50.692963Z","caller":"traceutil/trace.go:171","msg":"trace[713994899] transaction","detail":"{read_only:false; response_revision:25351; number_of_response:1; }","duration":"153.710709ms","start":"2026-05-11T11:39:50.539242Z","end":"2026-05-11T11:39:50.692952Z","steps":["trace[713994899] 'process raft request' (duration: 152.988825ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:50.692978Z","caller":"traceutil/trace.go:171","msg":"trace[1201889544] transaction","detail":"{read_only:false; response_revision:25350; number_of_response:1; }","duration":"155.093246ms","start":"2026-05-11T11:39:50.537867Z","end":"2026-05-11T11:39:50.69296Z","steps":["trace[1201889544] 'process raft request' (duration: 154.32521ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:50.693122Z","caller":"traceutil/trace.go:171","msg":"trace[1937192366] transaction","detail":"{read_only:false; response_revision:25349; number_of_response:1; }","duration":"155.676706ms","start":"2026-05-11T11:39:50.536931Z","end":"2026-05-11T11:39:50.692607Z","steps":["trace[1937192366] 'process raft request' (duration: 155.166236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:50.693156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.42589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-11T11:39:50.693185Z","caller":"traceutil/trace.go:171","msg":"trace[1073415054] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25352; }","duration":"156.471101ms","start":"2026-05-11T11:39:50.536705Z","end":"2026-05-11T11:39:50.693176Z","steps":["trace[1073415054] 'agreement among raft nodes before linearized reading' (duration: 156.336538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:52.220053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.751187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T11:39:52.220149Z","caller":"traceutil/trace.go:171","msg":"trace[1487975580] transaction","detail":"{read_only:false; response_revision:25354; number_of_response:1; }","duration":"194.244367ms","start":"2026-05-11T11:39:52.02589Z","end":"2026-05-11T11:39:52.220134Z","steps":["trace[1487975580] 'process raft request' (duration: 38.333077ms)","trace[1487975580] 'compare' (duration: 155.648346ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:39:52.23335Z","caller":"traceutil/trace.go:171","msg":"trace[1232734492] transaction","detail":"{read_only:false; response_revision:25355; number_of_response:1; }","duration":"132.352839ms","start":"2026-05-11T11:39:52.100968Z","end":"2026-05-11T11:39:52.233321Z","steps":["trace[1232734492] 'process raft request' (duration: 132.205737ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:52.778311Z","caller":"traceutil/trace.go:171","msg":"trace[98811658] transaction","detail":"{read_only:false; response_revision:25356; number_of_response:1; }","duration":"296.776398ms","start":"2026-05-11T11:39:52.481509Z","end":"2026-05-11T11:39:52.778286Z","steps":["trace[98811658] 'process raft request' (duration: 296.621296ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:52.81386Z","caller":"traceutil/trace.go:171","msg":"trace[1609260292] transaction","detail":"{read_only:false; response_revision:25357; number_of_response:1; }","duration":"332.227612ms","start":"2026-05-11T11:39:52.481601Z","end":"2026-05-11T11:39:52.813828Z","steps":["trace[1609260292] 'process raft request' (duration: 331.976718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:52.81413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:52.48156Z","time spent":"332.457587ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:39:52.814271Z","caller":"traceutil/trace.go:171","msg":"trace[937938673] transaction","detail":"{read_only:false; response_revision:25359; number_of_response:1; }","duration":"331.62326ms","start":"2026-05-11T11:39:52.482642Z","end":"2026-05-11T11:39:52.814265Z","steps":["trace[937938673] 'process raft request' (duration: 331.379086ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:52.814262Z","caller":"traceutil/trace.go:171","msg":"trace[291491667] linearizableReadLoop","detail":"{readStateIndex:26183; appliedIndex:26179; }","duration":"313.525582ms","start":"2026-05-11T11:39:52.500715Z","end":"2026-05-11T11:39:52.814241Z","steps":["trace[291491667] 'read index received' (duration: 277.460556ms)","trace[291491667] 'applied index is now lower than readState.Index' (duration: 36.063986ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:39:52.814364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:52.482611Z","time spent":"331.693061ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:39:52.814395Z","caller":"traceutil/trace.go:171","msg":"trace[1856499042] transaction","detail":"{read_only:false; response_revision:25360; number_of_response:1; }","duration":"276.826405ms","start":"2026-05-11T11:39:52.537553Z","end":"2026-05-11T11:39:52.814379Z","steps":["trace[1856499042] 'process raft request' (duration: 276.622731ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:52.814138Z","caller":"traceutil/trace.go:171","msg":"trace[1730972696] transaction","detail":"{read_only:false; response_revision:25358; number_of_response:1; }","duration":"331.885606ms","start":"2026-05-11T11:39:52.482232Z","end":"2026-05-11T11:39:52.814118Z","steps":["trace[1730972696] 'process raft request' (duration: 331.538559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:52.814465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:52.482219Z","time spent":"332.226232ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:39:52.814498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.89323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T11:39:52.81458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.91267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T11:39:52.814602Z","caller":"traceutil/trace.go:171","msg":"trace[1576758027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25360; }","duration":"115.941601ms","start":"2026-05-11T11:39:52.698654Z","end":"2026-05-11T11:39:52.814595Z","steps":["trace[1576758027] 'agreement among raft nodes before linearized reading' (duration: 115.860679ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:52.814611Z","caller":"traceutil/trace.go:171","msg":"trace[2022333007] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:25360; }","duration":"274.043503ms","start":"2026-05-11T11:39:52.540528Z","end":"2026-05-11T11:39:52.814572Z","steps":["trace[2022333007] 'agreement among raft nodes before linearized reading' (duration: 273.86328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:52.814642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.010552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-11T11:39:52.814712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.784258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-11T11:39:52.814663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.94579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T11:39:52.814736Z","caller":"traceutil/trace.go:171","msg":"trace[463772137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25360; }","duration":"115.845359ms","start":"2026-05-11T11:39:52.698884Z","end":"2026-05-11T11:39:52.81473Z","steps":["trace[463772137] 'agreement among raft nodes before linearized reading' (duration: 115.791418ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:52.814747Z","caller":"traceutil/trace.go:171","msg":"trace[2060720335] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25360; }","duration":"314.025261ms","start":"2026-05-11T11:39:52.500711Z","end":"2026-05-11T11:39:52.814736Z","steps":["trace[2060720335] 'agreement among raft nodes before linearized reading' (duration: 313.933319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:52.814816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.917971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-11T11:39:52.814807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:52.500674Z","time spent":"314.112813ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-11T11:39:52.814838Z","caller":"traceutil/trace.go:171","msg":"trace[36316643] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25360; }","duration":"115.950511ms","start":"2026-05-11T11:39:52.698881Z","end":"2026-05-11T11:39:52.814831Z","steps":["trace[36316643] 'agreement among raft nodes before linearized reading' (duration: 115.89842ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:52.814705Z","caller":"traceutil/trace.go:171","msg":"trace[1346142538] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25360; }","duration":"116.104084ms","start":"2026-05-11T11:39:52.698585Z","end":"2026-05-11T11:39:52.814689Z","steps":["trace[1346142538] 'agreement among raft nodes before linearized reading' (duration: 115.956452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:52.815015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.363878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-11T11:39:52.815041Z","caller":"traceutil/trace.go:171","msg":"trace[977428681] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25360; }","duration":"116.39859ms","start":"2026-05-11T11:39:52.698637Z","end":"2026-05-11T11:39:52.815035Z","steps":["trace[977428681] 'agreement among raft nodes before linearized reading' (duration: 116.339879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:52.81554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.277101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T11:39:52.815588Z","caller":"traceutil/trace.go:171","msg":"trace[338614429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25360; }","duration":"263.348253ms","start":"2026-05-11T11:39:52.552226Z","end":"2026-05-11T11:39:52.815575Z","steps":["trace[338614429] 'agreement among raft nodes before linearized reading' (duration: 262.66863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:53.007971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.974226ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:39:53.008078Z","caller":"traceutil/trace.go:171","msg":"trace[693647329] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25360; }","duration":"129.093528ms","start":"2026-05-11T11:39:52.878969Z","end":"2026-05-11T11:39:53.008062Z","steps":["trace[693647329] 'range keys from in-memory index tree' (duration: 128.957116ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:53.008345Z","caller":"traceutil/trace.go:171","msg":"trace[1647951595] transaction","detail":"{read_only:false; response_revision:25361; number_of_response:1; }","duration":"188.405599ms","start":"2026-05-11T11:39:52.819913Z","end":"2026-05-11T11:39:53.008318Z","steps":["trace[1647951595] 'process raft request' (duration: 153.676209ms)","trace[1647951595] 'compare' (duration: 34.22344ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:39:53.008703Z","caller":"traceutil/trace.go:171","msg":"trace[208902927] transaction","detail":"{read_only:false; response_revision:25362; number_of_response:1; }","duration":"188.743315ms","start":"2026-05-11T11:39:52.819947Z","end":"2026-05-11T11:39:53.00869Z","steps":["trace[208902927] 'process raft request' (duration: 188.282986ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:53.008892Z","caller":"traceutil/trace.go:171","msg":"trace[1285746176] transaction","detail":"{read_only:false; response_revision:25363; number_of_response:1; }","duration":"188.607162ms","start":"2026-05-11T11:39:52.820274Z","end":"2026-05-11T11:39:53.008881Z","steps":["trace[1285746176] 'process raft request' (duration: 188.200385ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:53.00912Z","caller":"traceutil/trace.go:171","msg":"trace[1177079931] transaction","detail":"{read_only:false; response_revision:25364; number_of_response:1; }","duration":"188.350398ms","start":"2026-05-11T11:39:52.820742Z","end":"2026-05-11T11:39:53.009093Z","steps":["trace[1177079931] 'process raft request' (duration: 188.098273ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:53.009162Z","caller":"traceutil/trace.go:171","msg":"trace[1281824402] linearizableReadLoop","detail":"{readStateIndex:26190; appliedIndex:26184; }","duration":"108.760067ms","start":"2026-05-11T11:39:52.900379Z","end":"2026-05-11T11:39:53.009139Z","steps":["trace[1281824402] 'read index received' (duration: 73.287152ms)","trace[1281824402] 'applied index is now lower than readState.Index' (duration: 35.472015ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:39:53.009241Z","caller":"traceutil/trace.go:171","msg":"trace[1203585349] transaction","detail":"{read_only:false; response_revision:25365; number_of_response:1; }","duration":"188.256097ms","start":"2026-05-11T11:39:52.820945Z","end":"2026-05-11T11:39:53.009201Z","steps":["trace[1203585349] 'process raft request' (duration: 188.041872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:53.009323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.92033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:39:53.009377Z","caller":"traceutil/trace.go:171","msg":"trace[405069705] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25366; }","duration":"109.041952ms","start":"2026-05-11T11:39:52.900322Z","end":"2026-05-11T11:39:53.009364Z","steps":["trace[405069705] 'agreement among raft nodes before linearized reading' (duration: 108.91246ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:53.009488Z","caller":"traceutil/trace.go:171","msg":"trace[691071113] transaction","detail":"{read_only:false; response_revision:25366; number_of_response:1; }","duration":"187.098074ms","start":"2026-05-11T11:39:52.822172Z","end":"2026-05-11T11:39:53.00927Z","steps":["trace[691071113] 'process raft request' (duration: 186.91009ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:53.754794Z","caller":"traceutil/trace.go:171","msg":"trace[1138618273] transaction","detail":"{read_only:false; response_revision:25367; number_of_response:1; }","duration":"113.275383ms","start":"2026-05-11T11:39:53.641499Z","end":"2026-05-11T11:39:53.754775Z","steps":["trace[1138618273] 'process raft request' (duration: 113.166901ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:54.041693Z","caller":"traceutil/trace.go:171","msg":"trace[1898991671] transaction","detail":"{read_only:false; response_revision:25368; number_of_response:1; }","duration":"373.894262ms","start":"2026-05-11T11:39:53.667767Z","end":"2026-05-11T11:39:54.041661Z","steps":["trace[1898991671] 'process raft request' (duration: 373.664958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:54.041856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:53.667739Z","time spent":"374.037565ms","remote":"127.0.0.1:57654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:39:54.041928Z","caller":"traceutil/trace.go:171","msg":"trace[2099363433] transaction","detail":"{read_only:false; response_revision:25369; number_of_response:1; }","duration":"358.716869ms","start":"2026-05-11T11:39:53.683185Z","end":"2026-05-11T11:39:54.041902Z","steps":["trace[2099363433] 'process raft request' (duration: 358.484764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:54.042045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:39:53.683167Z","time spent":"358.81797ms","remote":"127.0.0.1:40952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418362,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:39:54.186489Z","caller":"traceutil/trace.go:171","msg":"trace[2051639412] linearizableReadLoop","detail":"{readStateIndex:26195; appliedIndex:26194; }","duration":"140.42528ms","start":"2026-05-11T11:39:54.046031Z","end":"2026-05-11T11:39:54.186456Z","steps":["trace[2051639412] 'read index received' (duration: 65.379964ms)","trace[2051639412] 'applied index is now lower than readState.Index' (duration: 75.043546ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:39:54.18669Z","caller":"traceutil/trace.go:171","msg":"trace[1790524305] transaction","detail":"{read_only:false; response_revision:25370; number_of_response:1; }","duration":"256.183078ms","start":"2026-05-11T11:39:53.930475Z","end":"2026-05-11T11:39:54.186658Z","steps":["trace[1790524305] 'process raft request' (duration: 181.01469ms)","trace[1790524305] 'compare' (duration: 74.839732ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:39:54.186722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.671235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-11T11:39:54.186763Z","caller":"traceutil/trace.go:171","msg":"trace[1188866914] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25370; }","duration":"140.741775ms","start":"2026-05-11T11:39:54.046004Z","end":"2026-05-11T11:39:54.186746Z","steps":["trace[1188866914] 'agreement among raft nodes before linearized reading' (duration: 140.552292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:54.492434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.171005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T11:39:54.492552Z","caller":"traceutil/trace.go:171","msg":"trace[485326665] linearizableReadLoop","detail":"{readStateIndex:26196; appliedIndex:26195; }","duration":"250.869948ms","start":"2026-05-11T11:39:54.241665Z","end":"2026-05-11T11:39:54.492535Z","steps":["trace[485326665] 'read index received' (duration: 125.426379ms)","trace[485326665] 'applied index is now lower than readState.Index' (duration: 125.442239ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:39:54.492623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.981061ms","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-05-11T11:39:54.492623Z","caller":"traceutil/trace.go:171","msg":"trace[677468470] transaction","detail":"{read_only:false; response_revision:25371; number_of_response:1; }","duration":"252.412597ms","start":"2026-05-11T11:39:54.240179Z","end":"2026-05-11T11:39:54.492592Z","steps":["trace[677468470] 'process raft request' (duration: 127.001328ms)","trace[677468470] 'compare' (duration: 125.035643ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:39:54.492661Z","caller":"traceutil/trace.go:171","msg":"trace[1837028085] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:25371; }","duration":"251.056882ms","start":"2026-05-11T11:39:54.241591Z","end":"2026-05-11T11:39:54.492648Z","steps":["trace[1837028085] 'agreement among raft nodes before linearized reading' (duration: 250.991611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:55.071751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.912132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T11:39:55.071878Z","caller":"traceutil/trace.go:171","msg":"trace[1493076766] transaction","detail":"{read_only:false; response_revision:25373; number_of_response:1; }","duration":"250.999982ms","start":"2026-05-11T11:39:54.820863Z","end":"2026-05-11T11:39:55.071863Z","steps":["trace[1493076766] 'process raft request' (duration: 119.916846ms)","trace[1493076766] 'compare' (duration: 130.453724ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:39:55.072287Z","caller":"traceutil/trace.go:171","msg":"trace[300332134] transaction","detail":"{read_only:false; response_revision:25374; number_of_response:1; }","duration":"251.091223ms","start":"2026-05-11T11:39:54.821189Z","end":"2026-05-11T11:39:55.07228Z","steps":["trace[300332134] 'process raft request' (duration: 250.631805ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:55.072359Z","caller":"traceutil/trace.go:171","msg":"trace[45240245] transaction","detail":"{read_only:false; response_revision:25375; number_of_response:1; }","duration":"250.308137ms","start":"2026-05-11T11:39:54.822024Z","end":"2026-05-11T11:39:55.072332Z","steps":["trace[45240245] 'process raft request' (duration: 250.045223ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:55.072527Z","caller":"traceutil/trace.go:171","msg":"trace[1821422916] transaction","detail":"{read_only:false; response_revision:25376; number_of_response:1; }","duration":"250.141994ms","start":"2026-05-11T11:39:54.82236Z","end":"2026-05-11T11:39:55.072502Z","steps":["trace[1821422916] 'process raft request' (duration: 249.807808ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:55.259524Z","caller":"traceutil/trace.go:171","msg":"trace[731684544] transaction","detail":"{read_only:false; response_revision:25378; number_of_response:1; }","duration":"183.060689ms","start":"2026-05-11T11:39:55.076437Z","end":"2026-05-11T11:39:55.259498Z","steps":["trace[731684544] 'process raft request' (duration: 157.153104ms)","trace[731684544] 'compare' (duration: 25.377935ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:39:55.259875Z","caller":"traceutil/trace.go:171","msg":"trace[762501757] transaction","detail":"{read_only:false; response_revision:25379; number_of_response:1; }","duration":"182.846224ms","start":"2026-05-11T11:39:55.076965Z","end":"2026-05-11T11:39:55.259811Z","steps":["trace[762501757] 'process raft request' (duration: 182.489588ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:55.260039Z","caller":"traceutil/trace.go:171","msg":"trace[732153743] transaction","detail":"{read_only:false; response_revision:25380; number_of_response:1; }","duration":"182.589649ms","start":"2026-05-11T11:39:55.077425Z","end":"2026-05-11T11:39:55.260014Z","steps":["trace[732153743] 'process raft request' (duration: 182.266043ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:55.260669Z","caller":"traceutil/trace.go:171","msg":"trace[127298925] transaction","detail":"{read_only:false; response_revision:25381; number_of_response:1; }","duration":"183.079508ms","start":"2026-05-11T11:39:55.077522Z","end":"2026-05-11T11:39:55.260601Z","steps":["trace[127298925] 'process raft request' (duration: 182.426316ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:55.261104Z","caller":"traceutil/trace.go:171","msg":"trace[734296848] transaction","detail":"{read_only:false; response_revision:25382; number_of_response:1; }","duration":"183.471836ms","start":"2026-05-11T11:39:55.077603Z","end":"2026-05-11T11:39:55.261075Z","steps":["trace[734296848] 'process raft request' (duration: 182.913275ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:56.45249Z","caller":"traceutil/trace.go:171","msg":"trace[1459220055] linearizableReadLoop","detail":"{readStateIndex:26209; appliedIndex:26208; }","duration":"175.070129ms","start":"2026-05-11T11:39:56.2774Z","end":"2026-05-11T11:39:56.45247Z","steps":["trace[1459220055] 'read index received' (duration: 174.901776ms)","trace[1459220055] 'applied index is now lower than readState.Index' (duration: 167.453µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:39:56.452602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.195632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:39:56.452624Z","caller":"traceutil/trace.go:171","msg":"trace[530794541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25384; }","duration":"175.260063ms","start":"2026-05-11T11:39:56.277358Z","end":"2026-05-11T11:39:56.452618Z","steps":["trace[530794541] 'agreement among raft nodes before linearized reading' (duration: 175.207952ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:56.452546Z","caller":"traceutil/trace.go:171","msg":"trace[1179426212] transaction","detail":"{read_only:false; response_revision:25384; number_of_response:1; }","duration":"207.182501ms","start":"2026-05-11T11:39:56.245325Z","end":"2026-05-11T11:39:56.452507Z","steps":["trace[1179426212] 'process raft request' (duration: 207.025058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:56.663417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.051949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T11:39:56.663541Z","caller":"traceutil/trace.go:171","msg":"trace[929586641] transaction","detail":"{read_only:false; response_revision:25385; number_of_response:1; }","duration":"162.887231ms","start":"2026-05-11T11:39:56.500628Z","end":"2026-05-11T11:39:56.663516Z","steps":["trace[929586641] 'process raft request' (duration: 34.650069ms)","trace[929586641] 'compare' (duration: 127.919016ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:40:40.517202Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22821} {"level":"info","ts":"2026-05-11T11:40:40.649849Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22821,"took":"129.091277ms","hash":1865819764,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":36294656,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-11T11:40:40.649911Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1865819764,"revision":22821,"compact-revision":20103} {"level":"info","ts":"2026-05-11T11:41:42.30537Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006aa3.wal"} {"level":"info","ts":"2026-05-11T11:45:40.524109Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25922} {"level":"info","ts":"2026-05-11T11:45:40.668076Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25922,"took":"139.397571ms","hash":3479035483,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":34992128,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-11T11:45:40.66815Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3479035483,"revision":25922,"compact-revision":22821} {"level":"info","ts":"2026-05-11T11:46:43.617291Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T11:46:43.623823Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-11T11:46:43.623903Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-11T11:50:40.531163Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28626} {"level":"info","ts":"2026-05-11T11:50:40.672341Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28626,"took":"136.515706ms","hash":614007623,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":29995008,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-11T11:50:40.672425Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":614007623,"revision":28626,"compact-revision":25922} {"level":"info","ts":"2026-05-11T11:55:40.537423Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30842} {"level":"info","ts":"2026-05-11T11:55:40.652936Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30842,"took":"110.976643ms","hash":3537772622,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":30994432,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-11T11:55:40.653004Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3537772622,"revision":30842,"compact-revision":28626} {"level":"warn","ts":"2026-05-11T11:59:13.464733Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219755530629570,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T11:59:13.965338Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219755530629570,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T11:59:14.465966Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219755530629570,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T11:59:14.966418Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219755530629570,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T11:59:15.114293Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.447110726s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T11:59:15.114769Z","caller":"traceutil/trace.go:171","msg":"trace[1988156732] transaction","detail":"{read_only:false; response_revision:34718; number_of_response:1; }","duration":"2.447642016s","start":"2026-05-11T11:59:12.667103Z","end":"2026-05-11T11:59:15.114745Z","steps":["trace[1988156732] 'process raft request' (duration: 2.447491583s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:15.114906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:12.667088Z","time spent":"2.447749749s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:59:15.265134Z","caller":"traceutil/trace.go:171","msg":"trace[1653950701] transaction","detail":"{read_only:false; response_revision:34720; number_of_response:1; }","duration":"2.141525559s","start":"2026-05-11T11:59:13.12359Z","end":"2026-05-11T11:59:15.265115Z","steps":["trace[1653950701] 'process raft request' (duration: 2.141468748s)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:15.265243Z","caller":"traceutil/trace.go:171","msg":"trace[1622271092] transaction","detail":"{read_only:false; response_revision:34721; number_of_response:1; }","duration":"1.964137789s","start":"2026-05-11T11:59:13.301097Z","end":"2026-05-11T11:59:15.265235Z","steps":["trace[1622271092] 'process raft request' (duration: 1.963986746s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:15.265269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:13.123573Z","time spent":"2.14161857s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:59:15.265293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:13.301076Z","time spent":"1.964191869s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:59:15.265136Z","caller":"traceutil/trace.go:171","msg":"trace[61258870] transaction","detail":"{read_only:false; response_revision:34719; number_of_response:1; }","duration":"2.447170978s","start":"2026-05-11T11:59:12.817948Z","end":"2026-05-11T11:59:15.265119Z","steps":["trace[61258870] 'process raft request' (duration: 2.446985614s)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:15.265412Z","caller":"traceutil/trace.go:171","msg":"trace[149372587] transaction","detail":"{read_only:false; response_revision:34722; number_of_response:1; }","duration":"1.938780973s","start":"2026-05-11T11:59:13.326623Z","end":"2026-05-11T11:59:15.265404Z","steps":["trace[149372587] 'process raft request' (duration: 1.938576539s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:15.265453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:13.326586Z","time spent":"1.938845503s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:59:15.265455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:12.817925Z","time spent":"2.447454433s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:59:15.265653Z","caller":"traceutil/trace.go:171","msg":"trace[1605315610] transaction","detail":"{read_only:false; response_revision:34723; number_of_response:1; }","duration":"1.937569579s","start":"2026-05-11T11:59:13.328072Z","end":"2026-05-11T11:59:15.265641Z","steps":["trace[1605315610] 'process raft request' (duration: 1.937303314s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:15.265688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:13.328057Z","time spent":"1.93761522s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:59:15.265764Z","caller":"traceutil/trace.go:171","msg":"trace[31157574] transaction","detail":"{read_only:false; response_revision:34724; number_of_response:1; }","duration":"1.888701252s","start":"2026-05-11T11:59:13.377055Z","end":"2026-05-11T11:59:15.265756Z","steps":["trace[31157574] 'process raft request' (duration: 1.888481088s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:15.265789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:13.376999Z","time spent":"1.888775554s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:59:15.265839Z","caller":"traceutil/trace.go:171","msg":"trace[375161152] transaction","detail":"{read_only:false; response_revision:34725; number_of_response:1; }","duration":"1.275121202s","start":"2026-05-11T11:59:13.990713Z","end":"2026-05-11T11:59:15.265834Z","steps":["trace[375161152] 'process raft request' (duration: 1.274909698s)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:15.265807Z","caller":"traceutil/trace.go:171","msg":"trace[2029722119] linearizableReadLoop","detail":"{readStateIndex:35809; appliedIndex:35801; }","duration":"2.301550313s","start":"2026-05-11T11:59:12.964227Z","end":"2026-05-11T11:59:15.265777Z","steps":["trace[2029722119] 'read index received' (duration: 2.150522717s)","trace[2029722119] 'applied index is now lower than readState.Index' (duration: 151.026046ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:59:15.265866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:13.990688Z","time spent":"1.275165082s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:59:15.265918Z","caller":"traceutil/trace.go:171","msg":"trace[1451323039] transaction","detail":"{read_only:false; response_revision:34727; number_of_response:1; }","duration":"245.179424ms","start":"2026-05-11T11:59:15.020699Z","end":"2026-05-11T11:59:15.265878Z","steps":["trace[1451323039] 'process raft request' (duration: 244.99735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:15.265982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.221367228s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-11T11:59:15.26597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.301726757s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T11:59:15.266037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.624565971s","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-05-11T11:59:15.266066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.985918037s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-11T11:59:15.26607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.859097516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-11T11:59:15.266065Z","caller":"traceutil/trace.go:171","msg":"trace[1576281417] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:34727; }","duration":"2.301860549s","start":"2026-05-11T11:59:12.964185Z","end":"2026-05-11T11:59:15.266045Z","steps":["trace[1576281417] 'agreement among raft nodes before linearized reading' (duration: 2.301686996s)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:15.266082Z","caller":"traceutil/trace.go:171","msg":"trace[723595457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:34727; }","duration":"1.985981478s","start":"2026-05-11T11:59:13.280096Z","end":"2026-05-11T11:59:15.266078Z","steps":["trace[723595457] 'agreement among raft nodes before linearized reading' (duration: 1.985941057s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:15.266072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.613840592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-05-11T11:59:15.266089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.901346699s","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":"warn","ts":"2026-05-11T11:59:15.266097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:13.280073Z","time spent":"1.98602143s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-11T11:59:15.266095Z","caller":"traceutil/trace.go:171","msg":"trace[1487670916] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:34727; }","duration":"1.859120987s","start":"2026-05-11T11:59:13.406967Z","end":"2026-05-11T11:59:15.266088Z","steps":["trace[1487670916] 'agreement among raft nodes before linearized reading' (duration: 1.859062954s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:15.266106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:12.964162Z","time spent":"2.301933532s","remote":"127.0.0.1:52820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":28,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"info","ts":"2026-05-11T11:59:15.266121Z","caller":"traceutil/trace.go:171","msg":"trace[727182146] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:34727; }","duration":"1.613917874s","start":"2026-05-11T11:59:13.652191Z","end":"2026-05-11T11:59:15.266109Z","steps":["trace[727182146] 'agreement among raft nodes before linearized reading' (duration: 1.61377777s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:15.266126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:13.406892Z","time spent":"1.859227969s","remote":"127.0.0.1:57914","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-05-11T11:59:15.266138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.920294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T11:59:15.266149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:13.652178Z","time spent":"1.613965175s","remote":"127.0.0.1:57654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-05-11T11:59:15.266166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.986764674s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-11T11:59:15.266184Z","caller":"traceutil/trace.go:171","msg":"trace[420852237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:34727; }","duration":"1.986817585s","start":"2026-05-11T11:59:13.279362Z","end":"2026-05-11T11:59:15.26618Z","steps":["trace[420852237] 'agreement among raft nodes before linearized reading' (duration: 1.986777314s)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:15.266176Z","caller":"traceutil/trace.go:171","msg":"trace[1636111326] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:34727; }","duration":"134.000366ms","start":"2026-05-11T11:59:15.132165Z","end":"2026-05-11T11:59:15.266166Z","steps":["trace[1636111326] 'agreement among raft nodes before linearized reading' (duration: 133.948035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:15.266182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.854303106s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-05-11T11:59:15.266199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:13.279339Z","time spent":"1.986857446s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-11T11:59:15.266211Z","caller":"traceutil/trace.go:171","msg":"trace[637782414] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:34727; }","duration":"1.854332817s","start":"2026-05-11T11:59:13.411871Z","end":"2026-05-11T11:59:15.266204Z","steps":["trace[637782414] 'agreement among raft nodes before linearized reading' (duration: 1.854265495s)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:15.265843Z","caller":"traceutil/trace.go:171","msg":"trace[1102870249] transaction","detail":"{read_only:false; response_revision:34726; number_of_response:1; }","duration":"783.505711ms","start":"2026-05-11T11:59:14.482326Z","end":"2026-05-11T11:59:15.265831Z","steps":["trace[1102870249] 'process raft request' (duration: 783.335618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:15.266236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:13.411803Z","time spent":"1.854427458s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-11T11:59:15.266234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.22090617s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:59:15.266261Z","caller":"traceutil/trace.go:171","msg":"trace[30724545] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:34727; }","duration":"2.220953571s","start":"2026-05-11T11:59:13.0453Z","end":"2026-05-11T11:59:15.266254Z","steps":["trace[30724545] 'agreement among raft nodes before linearized reading' (duration: 2.220881809s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:15.265985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.842120077s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-11T11:59:15.266288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:13.045291Z","time spent":"2.220990001s","remote":"127.0.0.1:52782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-11T11:59:15.266297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:14.482301Z","time spent":"783.938169ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:59:15.266317Z","caller":"traceutil/trace.go:171","msg":"trace[1850163271] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:34727; }","duration":"1.842520416s","start":"2026-05-11T11:59:13.423784Z","end":"2026-05-11T11:59:15.266304Z","steps":["trace[1850163271] 'agreement among raft nodes before linearized reading' (duration: 1.842140698s)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:15.266121Z","caller":"traceutil/trace.go:171","msg":"trace[1904563059] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:34727; }","duration":"1.90137694s","start":"2026-05-11T11:59:13.364735Z","end":"2026-05-11T11:59:15.266112Z","steps":["trace[1904563059] 'agreement among raft nodes before linearized reading' (duration: 1.901331659s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:15.26636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:13.423772Z","time spent":"1.842576566s","remote":"127.0.0.1:58936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-11T11:59:15.266375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:13.364711Z","time spent":"1.901656076s","remote":"127.0.0.1:58972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-05-11T11:59:15.266049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.904991688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T11:59:15.266412Z","caller":"traceutil/trace.go:171","msg":"trace[1551883638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:34727; }","duration":"1.905410256s","start":"2026-05-11T11:59:13.360994Z","end":"2026-05-11T11:59:15.266405Z","steps":["trace[1551883638] 'agreement among raft nodes before linearized reading' (duration: 1.904982038s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:15.266439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:13.360969Z","time spent":"1.905463118s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-11T11:59:15.266001Z","caller":"traceutil/trace.go:171","msg":"trace[1471636083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:34727; }","duration":"2.221423859s","start":"2026-05-11T11:59:13.044572Z","end":"2026-05-11T11:59:15.265996Z","steps":["trace[1471636083] 'agreement among raft nodes before linearized reading' (duration: 2.221349217s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:15.266477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:13.04455Z","time spent":"2.22191908s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T11:59:15.266028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.985501938s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T11:59:15.266007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.17117117s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:59:15.266528Z","caller":"traceutil/trace.go:171","msg":"trace[1499177678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:34727; }","duration":"1.98605533s","start":"2026-05-11T11:59:13.280458Z","end":"2026-05-11T11:59:15.266514Z","steps":["trace[1499177678] 'agreement among raft nodes before linearized reading' (duration: 1.98555465s)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:15.266532Z","caller":"traceutil/trace.go:171","msg":"trace[1029787449] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:34727; }","duration":"1.171745932s","start":"2026-05-11T11:59:14.094778Z","end":"2026-05-11T11:59:15.266524Z","steps":["trace[1029787449] 'agreement among raft nodes before linearized reading' (duration: 1.171199591s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:15.266541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:13.280413Z","time spent":"1.986125242s","remote":"127.0.0.1:57612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T11:59:15.266557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:14.094751Z","time spent":"1.171798222s","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-11T11:59:15.266088Z","caller":"traceutil/trace.go:171","msg":"trace[1714388202] 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:34727; }","duration":"1.624612123s","start":"2026-05-11T11:59:13.64146Z","end":"2026-05-11T11:59:15.266072Z","steps":["trace[1714388202] 'agreement among raft nodes before linearized reading' (duration: 1.624551411s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:15.266637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:13.641438Z","time spent":"1.625183124s","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-05-11T11:59:15.267806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.171574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-11T11:59:15.268055Z","caller":"traceutil/trace.go:171","msg":"trace[1826555864] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:34727; }","duration":"776.432388ms","start":"2026-05-11T11:59:14.491584Z","end":"2026-05-11T11:59:15.268017Z","steps":["trace[1826555864] 'agreement among raft nodes before linearized reading' (duration: 774.359559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:15.268113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:14.49157Z","time spent":"776.526399ms","remote":"127.0.0.1:57808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-11T11:59:23.273131Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219755530629716,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T11:59:23.774196Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219755530629716,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T11:59:24.27443Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219755530629716,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T11:59:24.775223Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219755530629716,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T11:59:24.864967Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.092738723s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T11:59:24.865368Z","caller":"traceutil/trace.go:171","msg":"trace[1701033664] transaction","detail":"{read_only:false; response_revision:34778; number_of_response:1; }","duration":"2.093238602s","start":"2026-05-11T11:59:22.772099Z","end":"2026-05-11T11:59:24.865338Z","steps":["trace[1701033664] 'process raft request' (duration: 2.093075249s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:24.865518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:22.772072Z","time spent":"2.093369515s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:59:24.921922Z","caller":"traceutil/trace.go:171","msg":"trace[938869394] transaction","detail":"{read_only:false; response_revision:34779; number_of_response:1; }","duration":"1.767887603s","start":"2026-05-11T11:59:23.154001Z","end":"2026-05-11T11:59:24.921888Z","steps":["trace[938869394] 'process raft request' (duration: 1.767658029s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:24.922094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:23.153982Z","time spent":"1.768020886s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:59:24.922155Z","caller":"traceutil/trace.go:171","msg":"trace[1695072993] transaction","detail":"{read_only:false; response_revision:34780; number_of_response:1; }","duration":"1.625828306s","start":"2026-05-11T11:59:23.296298Z","end":"2026-05-11T11:59:24.922126Z","steps":["trace[1695072993] 'process raft request' (duration: 1.62554236s)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:24.922171Z","caller":"traceutil/trace.go:171","msg":"trace[422479470] transaction","detail":"{read_only:false; response_revision:34781; number_of_response:1; }","duration":"1.625393947s","start":"2026-05-11T11:59:23.296752Z","end":"2026-05-11T11:59:24.922146Z","steps":["trace[422479470] 'process raft request' (duration: 1.625264675s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:24.922296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:23.296267Z","time spent":"1.625955078s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:59:24.922309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:23.296743Z","time spent":"1.62549167s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:59:24.922369Z","caller":"traceutil/trace.go:171","msg":"trace[302679150] transaction","detail":"{read_only:false; response_revision:34782; number_of_response:1; }","duration":"1.625073643s","start":"2026-05-11T11:59:23.297283Z","end":"2026-05-11T11:59:24.922357Z","steps":["trace[302679150] 'process raft request' (duration: 1.624819037s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:24.922447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:23.297274Z","time spent":"1.625131154s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:59:24.922576Z","caller":"traceutil/trace.go:171","msg":"trace[185520570] transaction","detail":"{read_only:false; response_revision:34783; number_of_response:1; }","duration":"1.625229256s","start":"2026-05-11T11:59:23.297336Z","end":"2026-05-11T11:59:24.922565Z","steps":["trace[185520570] 'process raft request' (duration: 1.624934589s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:24.922631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:23.297305Z","time spent":"1.625294577s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:59:24.922872Z","caller":"traceutil/trace.go:171","msg":"trace[779537599] transaction","detail":"{read_only:false; response_revision:34784; number_of_response:1; }","duration":"1.623992001s","start":"2026-05-11T11:59:23.298866Z","end":"2026-05-11T11:59:24.922858Z","steps":["trace[779537599] 'process raft request' (duration: 1.623658095s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:24.922958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:23.298849Z","time spent":"1.624070623s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:59:24.923117Z","caller":"traceutil/trace.go:171","msg":"trace[1911001855] linearizableReadLoop","detail":"{readStateIndex:35870; appliedIndex:35862; }","duration":"2.150802123s","start":"2026-05-11T11:59:22.772304Z","end":"2026-05-11T11:59:24.923106Z","steps":["trace[1911001855] 'read index received' (duration: 2.092859365s)","trace[1911001855] 'applied index is now lower than readState.Index' (duration: 57.941778ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:59:24.923253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.150955456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:59:24.923307Z","caller":"traceutil/trace.go:171","msg":"trace[1579189239] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:34785; }","duration":"2.151053018s","start":"2026-05-11T11:59:22.77224Z","end":"2026-05-11T11:59:24.923293Z","steps":["trace[1579189239] 'agreement among raft nodes before linearized reading' (duration: 2.150972707s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:24.923347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:22.772217Z","time spent":"2.151120999s","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true "} {"level":"warn","ts":"2026-05-11T11:59:24.923371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.828964701s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T11:59:24.923412Z","caller":"traceutil/trace.go:171","msg":"trace[1167685014] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:34785; }","duration":"1.829063742s","start":"2026-05-11T11:59:23.094336Z","end":"2026-05-11T11:59:24.923399Z","steps":["trace[1167685014] 'agreement among raft nodes before linearized reading' (duration: 1.828994771s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:24.923442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:23.094308Z","time spent":"1.829126274s","remote":"127.0.0.1:57898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-05-11T11:59:24.923513Z","caller":"traceutil/trace.go:171","msg":"trace[1106287902] transaction","detail":"{read_only:false; response_revision:34785; number_of_response:1; }","duration":"1.621697689s","start":"2026-05-11T11:59:23.301801Z","end":"2026-05-11T11:59:24.923499Z","steps":["trace[1106287902] 'process raft request' (duration: 1.621003446s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:24.92363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:23.301784Z","time spent":"1.62177927s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:59:24.923808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.214754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T11:59:24.923857Z","caller":"traceutil/trace.go:171","msg":"trace[1187070755] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:34785; }","duration":"286.269555ms","start":"2026-05-11T11:59:24.637576Z","end":"2026-05-11T11:59:24.923846Z","steps":["trace[1187070755] 'agreement among raft nodes before linearized reading' (duration: 286.179463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:24.92405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.569073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T11:59:24.92409Z","caller":"traceutil/trace.go:171","msg":"trace[923195575] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:34785; }","duration":"463.665215ms","start":"2026-05-11T11:59:24.460414Z","end":"2026-05-11T11:59:24.924079Z","steps":["trace[923195575] 'agreement among raft nodes before linearized reading' (duration: 463.577553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:24.924128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:24.460389Z","time spent":"463.730777ms","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":40,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-05-11T11:59:24.924282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110744254s","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:6"} {"level":"info","ts":"2026-05-11T11:59:24.924321Z","caller":"traceutil/trace.go:171","msg":"trace[1076241265] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:34785; }","duration":"1.110781335s","start":"2026-05-11T11:59:23.813528Z","end":"2026-05-11T11:59:24.924309Z","steps":["trace[1076241265] 'agreement among raft nodes before linearized reading' (duration: 1.110727554s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:24.924357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:23.813496Z","time spent":"1.110852927s","remote":"127.0.0.1:58854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-11T11:59:24.924674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.272338849s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-05-11T11:59:24.924691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.623668105s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:59:24.924726Z","caller":"traceutil/trace.go:171","msg":"trace[1972444873] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:34785; }","duration":"1.272452851s","start":"2026-05-11T11:59:23.652262Z","end":"2026-05-11T11:59:24.924715Z","steps":["trace[1972444873] 'agreement among raft nodes before linearized reading' (duration: 1.272222286s)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:24.924734Z","caller":"traceutil/trace.go:171","msg":"trace[709548936] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:34785; }","duration":"1.623762587s","start":"2026-05-11T11:59:23.300959Z","end":"2026-05-11T11:59:24.924722Z","steps":["trace[709548936] 'agreement among raft nodes before linearized reading' (duration: 1.623685455s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:24.924762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:23.652225Z","time spent":"1.272530702s","remote":"127.0.0.1:57654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-05-11T11:59:24.92477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:23.300935Z","time spent":"1.623826438s","remote":"127.0.0.1:50588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"warn","ts":"2026-05-11T11:59:24.924955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.643006049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:59:24.924983Z","caller":"traceutil/trace.go:171","msg":"trace[526329487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:34785; }","duration":"1.64308374s","start":"2026-05-11T11:59:23.28189Z","end":"2026-05-11T11:59:24.924974Z","steps":["trace[526329487] 'agreement among raft nodes before linearized reading' (duration: 1.64303527s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:24.925008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:23.281862Z","time spent":"1.643140771s","remote":"127.0.0.1:57612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T11:59:24.925032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.57226642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-11T11:59:24.925063Z","caller":"traceutil/trace.go:171","msg":"trace[1186434774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:34785; }","duration":"1.572317371s","start":"2026-05-11T11:59:23.352736Z","end":"2026-05-11T11:59:24.925053Z","steps":["trace[1186434774] 'agreement among raft nodes before linearized reading' (duration: 1.572202089s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:24.925092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:23.352724Z","time spent":"1.572362392s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T11:59:24.925149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.809276709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:59:24.925177Z","caller":"traceutil/trace.go:171","msg":"trace[1219519314] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:34785; }","duration":"1.809339252s","start":"2026-05-11T11:59:23.115829Z","end":"2026-05-11T11:59:24.925168Z","steps":["trace[1219519314] 'agreement among raft nodes before linearized reading' (duration: 1.80929233s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:24.925204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:23.115807Z","time spent":"1.809389593s","remote":"127.0.0.1:52626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-11T11:59:24.92526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.577187743s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T11:59:24.9253Z","caller":"traceutil/trace.go:171","msg":"trace[1892969353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:34785; }","duration":"1.577287095s","start":"2026-05-11T11:59:23.348004Z","end":"2026-05-11T11:59:24.925292Z","steps":["trace[1892969353] 'agreement among raft nodes before linearized reading' (duration: 1.577193764s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:24.925337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:23.34799Z","time spent":"1.577338566s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T11:59:24.925453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.583073974s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-11T11:59:24.925487Z","caller":"traceutil/trace.go:171","msg":"trace[1463668024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:34785; }","duration":"1.583135606s","start":"2026-05-11T11:59:23.342344Z","end":"2026-05-11T11:59:24.925479Z","steps":["trace[1463668024] 'agreement among raft nodes before linearized reading' (duration: 1.583051184s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:24.92552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:23.342328Z","time spent":"1.583186316s","remote":"127.0.0.1:57914","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-05-11T11:59:24.925596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.584119494s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-05-11T11:59:24.925512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.585812135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-11T11:59:24.925643Z","caller":"traceutil/trace.go:171","msg":"trace[922198299] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:34785; }","duration":"1.584197875s","start":"2026-05-11T11:59:23.341429Z","end":"2026-05-11T11:59:24.925627Z","steps":["trace[922198299] 'agreement among raft nodes before linearized reading' (duration: 1.584108124s)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:24.925664Z","caller":"traceutil/trace.go:171","msg":"trace[1647501871] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:34785; }","duration":"1.586007978s","start":"2026-05-11T11:59:23.339647Z","end":"2026-05-11T11:59:24.925655Z","steps":["trace[1647501871] 'agreement among raft nodes before linearized reading' (duration: 1.585767814s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:24.925676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:23.341404Z","time spent":"1.584264576s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-11T11:59:24.925702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:23.339632Z","time spent":"1.58606262s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T11:59:24.925824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.585988278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T11:59:24.925869Z","caller":"traceutil/trace.go:171","msg":"trace[1766835419] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:34785; }","duration":"1.58605585s","start":"2026-05-11T11:59:23.339804Z","end":"2026-05-11T11:59:24.92586Z","steps":["trace[1766835419] 'agreement among raft nodes before linearized reading' (duration: 1.585963078s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:24.925902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:59:23.339767Z","time spent":"1.58612882s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-11T11:59:25.749821Z","caller":"traceutil/trace.go:171","msg":"trace[1146221771] linearizableReadLoop","detail":"{readStateIndex:35880; appliedIndex:35879; }","duration":"117.647499ms","start":"2026-05-11T11:59:25.632149Z","end":"2026-05-11T11:59:25.749796Z","steps":["trace[1146221771] 'read index received' (duration: 117.422984ms)","trace[1146221771] 'applied index is now lower than readState.Index' (duration: 223.305µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:59:25.74988Z","caller":"traceutil/trace.go:171","msg":"trace[1341454946] transaction","detail":"{read_only:false; response_revision:34794; number_of_response:1; }","duration":"117.921153ms","start":"2026-05-11T11:59:25.631926Z","end":"2026-05-11T11:59:25.749847Z","steps":["trace[1341454946] 'process raft request' (duration: 117.609728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:59:25.750046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.881894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-11T11:59:25.750089Z","caller":"traceutil/trace.go:171","msg":"trace[1012130509] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:34794; }","duration":"117.996926ms","start":"2026-05-11T11:59:25.632081Z","end":"2026-05-11T11:59:25.750078Z","steps":["trace[1012130509] 'agreement among raft nodes before linearized reading' (duration: 117.809732ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:27.059374Z","caller":"traceutil/trace.go:171","msg":"trace[327134968] transaction","detail":"{read_only:false; response_revision:34796; number_of_response:1; }","duration":"130.018771ms","start":"2026-05-11T11:59:26.929326Z","end":"2026-05-11T11:59:27.059345Z","steps":["trace[327134968] 'process raft request' (duration: 103.985293ms)","trace[327134968] 'compare' (duration: 25.850955ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:59:27.059595Z","caller":"traceutil/trace.go:171","msg":"trace[1911350315] transaction","detail":"{read_only:false; response_revision:34797; number_of_response:1; }","duration":"129.585003ms","start":"2026-05-11T11:59:26.929982Z","end":"2026-05-11T11:59:27.059567Z","steps":["trace[1911350315] 'process raft request' (duration: 129.316948ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:27.059723Z","caller":"traceutil/trace.go:171","msg":"trace[1914623177] transaction","detail":"{read_only:false; response_revision:34798; number_of_response:1; }","duration":"129.268578ms","start":"2026-05-11T11:59:26.930421Z","end":"2026-05-11T11:59:27.05969Z","steps":["trace[1914623177] 'process raft request' (duration: 129.070684ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:27.060074Z","caller":"traceutil/trace.go:171","msg":"trace[41477746] transaction","detail":"{read_only:false; response_revision:34799; number_of_response:1; }","duration":"129.552783ms","start":"2026-05-11T11:59:26.930506Z","end":"2026-05-11T11:59:27.060059Z","steps":["trace[41477746] 'process raft request' (duration: 129.155225ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:27.060266Z","caller":"traceutil/trace.go:171","msg":"trace[1208219342] transaction","detail":"{read_only:false; response_revision:34800; number_of_response:1; }","duration":"128.34152ms","start":"2026-05-11T11:59:26.931897Z","end":"2026-05-11T11:59:27.060238Z","steps":["trace[1208219342] 'process raft request' (duration: 128.120016ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:27.060385Z","caller":"traceutil/trace.go:171","msg":"trace[138620737] transaction","detail":"{read_only:false; response_revision:34801; number_of_response:1; }","duration":"126.103288ms","start":"2026-05-11T11:59:26.934265Z","end":"2026-05-11T11:59:27.060368Z","steps":["trace[138620737] 'process raft request' (duration: 125.882694ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:27.179184Z","caller":"traceutil/trace.go:171","msg":"trace[496730047] transaction","detail":"{read_only:false; response_revision:34807; number_of_response:1; }","duration":"113.153644ms","start":"2026-05-11T11:59:27.066004Z","end":"2026-05-11T11:59:27.179157Z","steps":["trace[496730047] 'process raft request' (duration: 113.092073ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:27.179365Z","caller":"traceutil/trace.go:171","msg":"trace[749945623] transaction","detail":"{read_only:false; response_revision:34804; number_of_response:1; }","duration":"114.469019ms","start":"2026-05-11T11:59:27.064862Z","end":"2026-05-11T11:59:27.179331Z","steps":["trace[749945623] 'process raft request' (duration: 114.138583ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:27.179467Z","caller":"traceutil/trace.go:171","msg":"trace[1874646664] transaction","detail":"{read_only:false; response_revision:34803; number_of_response:1; }","duration":"114.644692ms","start":"2026-05-11T11:59:27.064793Z","end":"2026-05-11T11:59:27.179438Z","steps":["trace[1874646664] 'process raft request' (duration: 109.024727ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:27.179544Z","caller":"traceutil/trace.go:171","msg":"trace[1619423452] transaction","detail":"{read_only:false; response_revision:34806; number_of_response:1; }","duration":"114.109442ms","start":"2026-05-11T11:59:27.06539Z","end":"2026-05-11T11:59:27.179499Z","steps":["trace[1619423452] 'process raft request' (duration: 113.674574ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:59:27.179545Z","caller":"traceutil/trace.go:171","msg":"trace[1406576190] transaction","detail":"{read_only:false; response_revision:34805; number_of_response:1; }","duration":"114.163874ms","start":"2026-05-11T11:59:27.065291Z","end":"2026-05-11T11:59:27.179455Z","steps":["trace[1406576190] 'process raft request' (duration: 113.744476ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:00:40.5429Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33110} {"level":"info","ts":"2026-05-11T12:00:40.707457Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33110,"took":"160.930521ms","hash":1091509226,"current-db-size-bytes":59523072,"current-db-size":"60 MB","current-db-size-in-use-bytes":32030720,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T12:00:40.707554Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1091509226,"revision":33110,"compact-revision":30842} {"level":"info","ts":"2026-05-11T12:05:40.569911Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35473} {"level":"info","ts":"2026-05-11T12:05:40.721398Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35473,"took":"145.585828ms","hash":2484523909,"current-db-size-bytes":59981824,"current-db-size":"60 MB","current-db-size-in-use-bytes":31580160,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T12:05:40.721491Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2484523909,"revision":35473,"compact-revision":33110} {"level":"info","ts":"2026-05-11T12:07:59.834802Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T12:07:59.839598Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-11T12:07:59.839736Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}