{"level":"warn","ts":"2026-07-15T03:18:31.266242Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T03:18:31.266339Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157: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.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157: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-07-15T03:18:31.266412Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T03:18:31.266424Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-07-15T03:18:31.266443Z","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-07-15T03:18:31.267936Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-07-15T03:18:31.268025Z","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.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157: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-07-15T03:18:31.27078Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.534147ms"} {"level":"info","ts":"2026-07-15T03:18:31.276469Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-07-15T03:18:31.276565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-07-15T03:18:31.276594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-07-15T03:18:31.27661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-15T03:18:31.276625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-07-15T03:18:31.276676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-07-15T03:18:31.288815Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-15T03:18:31.290662Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-15T03:18:31.291824Z","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-07-15T03:18:31.294704Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-15T03:18:31.294863Z","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-07-15T03:18:31.294977Z","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-07-15T03:18:31.294933Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-15T03:18:31.294994Z","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-07-15T03:18:31.295926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-07-15T03:18:31.296049Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-07-15T03:18:31.300705Z","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-07-15T03:18:31.300791Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-07-15T03:18:31.300861Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-07-15T03:18:31.301164Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-15T03:18:31.301208Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-15T03:18:32.277549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-07-15T03:18:32.277688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-07-15T03:18:32.277737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-07-15T03:18:32.277761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-07-15T03:18:32.277775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-07-15T03:18:32.277793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-07-15T03:18:32.277815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-07-15T03:18:32.279054Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T03:18:32.280128Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-15T03:18:32.280167Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T03:18:32.280265Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T03:18:32.280634Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T03:18:32.280669Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-15T03:18:32.280794Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T03:18:32.280834Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-15T03:18:32.280936Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T03:18:32.283347Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-07-15T03:18:32.284434Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-15T03:18:47.272725Z","caller":"traceutil/trace.go:171","msg":"trace[2067851754] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"220.891037ms","start":"2026-07-15T03:18:47.0518Z","end":"2026-07-15T03:18:47.272691Z","steps":["trace[2067851754] 'read index received' (duration: 220.624522ms)","trace[2067851754] 'applied index is now lower than readState.Index' (duration: 264.845µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:18:47.272835Z","caller":"traceutil/trace.go:171","msg":"trace[2061377693] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"396.009316ms","start":"2026-07-15T03:18:46.876789Z","end":"2026-07-15T03:18:47.272798Z","steps":["trace[2061377693] 'process raft request' (duration: 395.713751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:47.273069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.226293ms","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-07-15T03:18:47.273236Z","caller":"traceutil/trace.go:171","msg":"trace[559252994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:411; }","duration":"221.500378ms","start":"2026-07-15T03:18:47.051706Z","end":"2026-07-15T03:18:47.273206Z","steps":["trace[559252994] 'agreement among raft nodes before linearized reading' (duration: 221.161732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:47.273985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:18:46.876739Z","time spent":"396.313552ms","remote":"127.0.0.1:45908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:19:17.787413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.132556ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:19:17.787531Z","caller":"traceutil/trace.go:171","msg":"trace[785720917] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"325.278383ms","start":"2026-07-15T03:19:17.462235Z","end":"2026-07-15T03:19:17.787513Z","steps":["trace[785720917] 'read index received' (duration: 124.710198ms)","trace[785720917] 'applied index is now lower than readState.Index' (duration: 200.567025ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:19:17.787704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.466797ms","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-07-15T03:19:17.787769Z","caller":"traceutil/trace.go:171","msg":"trace[1813597812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"325.555599ms","start":"2026-07-15T03:19:17.462176Z","end":"2026-07-15T03:19:17.787732Z","steps":["trace[1813597812] 'agreement among raft nodes before linearized reading' (duration: 325.408886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:17.787796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.076771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-crtw9\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-15T03:19:17.787817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:17.462143Z","time spent":"325.66273ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-15T03:19:17.787561Z","caller":"traceutil/trace.go:171","msg":"trace[379715800] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"440.84067ms","start":"2026-07-15T03:19:17.346678Z","end":"2026-07-15T03:19:17.787519Z","steps":["trace[379715800] 'process raft request' (duration: 240.387477ms)","trace[379715800] 'compare' (duration: 199.996733ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:19:17.787861Z","caller":"traceutil/trace.go:171","msg":"trace[1408388245] range","detail":"{range_begin:/registry/pods/kube-system/cilium-crtw9; range_end:; response_count:1; response_revision:631; }","duration":"300.195314ms","start":"2026-07-15T03:19:17.487649Z","end":"2026-07-15T03:19:17.787844Z","steps":["trace[1408388245] 'agreement among raft nodes before linearized reading' (duration: 299.98567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:17.787888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:17.487626Z","time spent":"300.256675ms","remote":"127.0.0.1:45908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-crtw9\" "} {"level":"warn","ts":"2026-07-15T03:19:17.787957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:17.346651Z","time spent":"441.226037ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T03:19:33.237856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.737821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:19:33.237946Z","caller":"traceutil/trace.go:171","msg":"trace[640056094] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:804; }","duration":"122.902904ms","start":"2026-07-15T03:19:33.115024Z","end":"2026-07-15T03:19:33.237927Z","steps":["trace[640056094] 'count revisions from in-memory index tree' (duration: 122.628159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:33.238252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.130867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-15T03:19:33.238389Z","caller":"traceutil/trace.go:171","msg":"trace[41126349] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:804; }","duration":"154.351541ms","start":"2026-07-15T03:19:33.084005Z","end":"2026-07-15T03:19:33.238357Z","steps":["trace[41126349] 'range keys from in-memory index tree' (duration: 153.865122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:20:45.722888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.218942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:20:45.723068Z","caller":"traceutil/trace.go:171","msg":"trace[1116096567] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1921; }","duration":"292.107859ms","start":"2026-07-15T03:20:45.430932Z","end":"2026-07-15T03:20:45.72304Z","steps":["trace[1116096567] 'read index received' (duration: 180.54931ms)","trace[1116096567] 'applied index is now lower than readState.Index' (duration: 111.556459ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:20:45.72309Z","caller":"traceutil/trace.go:171","msg":"trace[1211739797] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"330.326995ms","start":"2026-07-15T03:20:45.392718Z","end":"2026-07-15T03:20:45.723045Z","steps":["trace[1211739797] 'process raft request' (duration: 218.796377ms)","trace[1211739797] 'compare' (duration: 111.07428ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:20:45.723313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:20:45.392684Z","time spent":"330.506228ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:20:45.723909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.950814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-15T03:20:45.72402Z","caller":"traceutil/trace.go:171","msg":"trace[162215118] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:1853; }","duration":"293.100566ms","start":"2026-07-15T03:20:45.430897Z","end":"2026-07-15T03:20:45.723997Z","steps":["trace[162215118] 'agreement among raft nodes before linearized reading' (duration: 292.289162ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:20:46.161557Z","caller":"traceutil/trace.go:171","msg":"trace[50304126] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"133.690303ms","start":"2026-07-15T03:20:46.027837Z","end":"2026-07-15T03:20:46.161527Z","steps":["trace[50304126] 'process raft request' (duration: 133.56226ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:21:01.485887Z","caller":"traceutil/trace.go:171","msg":"trace[606157910] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"150.577909ms","start":"2026-07-15T03:21:01.335278Z","end":"2026-07-15T03:21:01.485856Z","steps":["trace[606157910] 'process raft request' (duration: 150.385965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:21:37.12059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.267166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-15T03:21:37.120732Z","caller":"traceutil/trace.go:171","msg":"trace[1888646606] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2185; }","duration":"252.457289ms","start":"2026-07-15T03:21:36.868247Z","end":"2026-07-15T03:21:37.120705Z","steps":["trace[1888646606] 'range keys from in-memory index tree' (duration: 252.069363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:21:37.120772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.20187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-15T03:21:37.120868Z","caller":"traceutil/trace.go:171","msg":"trace[1145131071] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2185; }","duration":"254.378323ms","start":"2026-07-15T03:21:36.866467Z","end":"2026-07-15T03:21:37.120845Z","steps":["trace[1145131071] 'range keys from in-memory index tree' (duration: 253.972546ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:21:37.360656Z","caller":"traceutil/trace.go:171","msg":"trace[236535957] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"234.064715ms","start":"2026-07-15T03:21:37.126563Z","end":"2026-07-15T03:21:37.360628Z","steps":["trace[236535957] 'process raft request' (duration: 233.852711ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:21:37.360906Z","caller":"traceutil/trace.go:171","msg":"trace[890104095] linearizableReadLoop","detail":"{readStateIndex:2268; appliedIndex:2266; }","duration":"134.857626ms","start":"2026-07-15T03:21:37.22598Z","end":"2026-07-15T03:21:37.360838Z","steps":["trace[890104095] 'read index received' (duration: 134.357688ms)","trace[890104095] 'applied index is now lower than readState.Index' (duration: 495.948µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:21:37.361238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.151712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:21:37.361418Z","caller":"traceutil/trace.go:171","msg":"trace[56020972] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2188; }","duration":"135.482378ms","start":"2026-07-15T03:21:37.225907Z","end":"2026-07-15T03:21:37.36139Z","steps":["trace[56020972] 'agreement among raft nodes before linearized reading' (duration: 135.103101ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:21:37.361642Z","caller":"traceutil/trace.go:171","msg":"trace[311347364] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"234.393781ms","start":"2026-07-15T03:21:37.126587Z","end":"2026-07-15T03:21:37.360981Z","steps":["trace[311347364] 'process raft request' (duration: 233.996294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:22:24.97118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.484764ms","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-07-15T03:22:24.971295Z","caller":"traceutil/trace.go:171","msg":"trace[1978177295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2491; }","duration":"128.653128ms","start":"2026-07-15T03:22:24.842619Z","end":"2026-07-15T03:22:24.971272Z","steps":["trace[1978177295] 'range keys from in-memory index tree' (duration: 128.288881ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:22:54.864705Z","caller":"traceutil/trace.go:171","msg":"trace[1691284013] transaction","detail":"{read_only:false; response_revision:2658; number_of_response:1; }","duration":"109.540124ms","start":"2026-07-15T03:22:54.755125Z","end":"2026-07-15T03:22:54.864665Z","steps":["trace[1691284013] 'process raft request' (duration: 109.31516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:23:00.395105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.933985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:23:00.395254Z","caller":"traceutil/trace.go:171","msg":"trace[237558418] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:2687; }","duration":"184.157249ms","start":"2026-07-15T03:23:00.211064Z","end":"2026-07-15T03:23:00.395221Z","steps":["trace[237558418] 'count revisions from in-memory index tree' (duration: 183.828333ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:25:56.230192Z","caller":"traceutil/trace.go:171","msg":"trace[1055430535] transaction","detail":"{read_only:false; response_revision:3798; number_of_response:1; }","duration":"157.258008ms","start":"2026-07-15T03:25:56.0729Z","end":"2026-07-15T03:25:56.230158Z","steps":["trace[1055430535] 'process raft request' (duration: 156.925142ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:27:14.213986Z","caller":"traceutil/trace.go:171","msg":"trace[560275507] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"139.094707ms","start":"2026-07-15T03:27:14.074868Z","end":"2026-07-15T03:27:14.213962Z","steps":["trace[560275507] 'process raft request' (duration: 130.73616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:27:19.50993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.545626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-07-15T03:27:19.510062Z","caller":"traceutil/trace.go:171","msg":"trace[419309764] linearizableReadLoop","detail":"{readStateIndex:5131; appliedIndex:5130; }","duration":"185.038319ms","start":"2026-07-15T03:27:19.325Z","end":"2026-07-15T03:27:19.510038Z","steps":["trace[419309764] 'read index received' (duration: 45.213048ms)","trace[419309764] 'applied index is now lower than readState.Index' (duration: 139.824181ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:27:19.510134Z","caller":"traceutil/trace.go:171","msg":"trace[1694592479] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4946; }","duration":"200.900847ms","start":"2026-07-15T03:27:19.309176Z","end":"2026-07-15T03:27:19.510076Z","steps":["trace[1694592479] 'process raft request' (duration: 61.123136ms)","trace[1694592479] 'compare' (duration: 139.486576ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:27:19.510282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.517251ms","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-07-15T03:27:19.510315Z","caller":"traceutil/trace.go:171","msg":"trace[1645378541] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4946; }","duration":"175.586443ms","start":"2026-07-15T03:27:19.334718Z","end":"2026-07-15T03:27:19.510304Z","steps":["trace[1645378541] 'agreement among raft nodes before linearized reading' (duration: 175.4334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:27:19.510361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.335094ms","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-07-15T03:27:19.51048Z","caller":"traceutil/trace.go:171","msg":"trace[1326440210] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4946; }","duration":"185.508498ms","start":"2026-07-15T03:27:19.324947Z","end":"2026-07-15T03:27:19.510455Z","steps":["trace[1326440210] 'agreement among raft nodes before linearized reading' (duration: 185.185012ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:27:19.625673Z","caller":"traceutil/trace.go:171","msg":"trace[1476160194] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"111.220076ms","start":"2026-07-15T03:27:19.514422Z","end":"2026-07-15T03:27:19.625642Z","steps":["trace[1476160194] 'process raft request' (duration: 111.022422ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:27:19.67803Z","caller":"traceutil/trace.go:171","msg":"trace[1450249120] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"163.358864ms","start":"2026-07-15T03:27:19.51459Z","end":"2026-07-15T03:27:19.677949Z","steps":["trace[1450249120] 'process raft request' (duration: 163.071938ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:27:19.678192Z","caller":"traceutil/trace.go:171","msg":"trace[2115068673] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"146.946695ms","start":"2026-07-15T03:27:19.531203Z","end":"2026-07-15T03:27:19.678149Z","steps":["trace[2115068673] 'process raft request' (duration: 146.600629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:27:33.548535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.350368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:27:33.548785Z","caller":"traceutil/trace.go:171","msg":"trace[478511184] transaction","detail":"{read_only:false; response_revision:5217; number_of_response:1; }","duration":"158.289067ms","start":"2026-07-15T03:27:33.390451Z","end":"2026-07-15T03:27:33.54874Z","steps":["trace[478511184] 'process raft request' (duration: 22.622773ms)","trace[478511184] 'compare' (duration: 135.071673ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:27:34.013264Z","caller":"traceutil/trace.go:171","msg":"trace[959147956] transaction","detail":"{read_only:false; response_revision:5220; number_of_response:1; }","duration":"227.261572ms","start":"2026-07-15T03:27:33.785978Z","end":"2026-07-15T03:27:34.01324Z","steps":["trace[959147956] 'process raft request' (duration: 227.17423ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:27:34.013311Z","caller":"traceutil/trace.go:171","msg":"trace[1816512789] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"227.866022ms","start":"2026-07-15T03:27:33.785416Z","end":"2026-07-15T03:27:34.013282Z","steps":["trace[1816512789] 'process raft request' (duration: 225.363055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:27:41.30161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.475108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:27:41.301841Z","caller":"traceutil/trace.go:171","msg":"trace[1477751825] transaction","detail":"{read_only:false; response_revision:5377; number_of_response:1; }","duration":"196.656003ms","start":"2026-07-15T03:27:41.105156Z","end":"2026-07-15T03:27:41.301812Z","steps":["trace[1477751825] 'process raft request' (duration: 52.883759ms)","trace[1477751825] 'compare' (duration: 143.219014ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:27:41.301936Z","caller":"traceutil/trace.go:171","msg":"trace[196406633] linearizableReadLoop","detail":"{readStateIndex:5573; appliedIndex:5572; }","duration":"183.653675ms","start":"2026-07-15T03:27:41.11825Z","end":"2026-07-15T03:27:41.301904Z","steps":["trace[196406633] 'read index received' (duration: 39.711517ms)","trace[196406633] 'applied index is now lower than readState.Index' (duration: 143.939358ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:27:41.302138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.854629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T03:27:41.302248Z","caller":"traceutil/trace.go:171","msg":"trace[873492794] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:5377; }","duration":"183.979902ms","start":"2026-07-15T03:27:41.118243Z","end":"2026-07-15T03:27:41.302223Z","steps":["trace[873492794] 'agreement among raft nodes before linearized reading' (duration: 183.752797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:27:41.303208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.445869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T03:27:41.303287Z","caller":"traceutil/trace.go:171","msg":"trace[509826450] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:5378; }","duration":"174.54128ms","start":"2026-07-15T03:27:41.128723Z","end":"2026-07-15T03:27:41.303264Z","steps":["trace[509826450] 'agreement among raft nodes before linearized reading' (duration: 174.299356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:27:41.608308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.324139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:1726"} {"level":"info","ts":"2026-07-15T03:27:41.608588Z","caller":"traceutil/trace.go:171","msg":"trace[2096845538] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5379; }","duration":"292.133904ms","start":"2026-07-15T03:27:41.316434Z","end":"2026-07-15T03:27:41.608568Z","steps":["trace[2096845538] 'process raft request' (duration: 174.439448ms)","trace[2096845538] 'compare' (duration: 117.237108ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:27:41.845635Z","caller":"traceutil/trace.go:171","msg":"trace[2042443189] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"229.615802ms","start":"2026-07-15T03:27:41.615983Z","end":"2026-07-15T03:27:41.845599Z","steps":["trace[2042443189] 'process raft request' (duration: 226.136416ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:27:41.845917Z","caller":"traceutil/trace.go:171","msg":"trace[747687167] transaction","detail":"{read_only:false; response_revision:5383; number_of_response:1; }","duration":"223.19871ms","start":"2026-07-15T03:27:41.622654Z","end":"2026-07-15T03:27:41.845853Z","steps":["trace[747687167] 'process raft request' (duration: 222.870473ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:28:32.317804Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2854} {"level":"info","ts":"2026-07-15T03:28:32.383861Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2854,"took":"64.656054ms","hash":3096419952,"current-db-size-bytes":57516032,"current-db-size":"58 MB","current-db-size-in-use-bytes":32841728,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-15T03:28:32.38393Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3096419952,"revision":2854,"compact-revision":-1} {"level":"info","ts":"2026-07-15T03:33:32.325067Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5880} {"level":"info","ts":"2026-07-15T03:33:32.401673Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5880,"took":"75.191985ms","hash":2477044491,"current-db-size-bytes":57516032,"current-db-size":"58 MB","current-db-size-in-use-bytes":25378816,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-07-15T03:33:32.401743Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2477044491,"revision":5880,"compact-revision":2854} {"level":"info","ts":"2026-07-15T03:33:43.112272Z","caller":"traceutil/trace.go:171","msg":"trace[820163430] linearizableReadLoop","detail":"{readStateIndex:8475; appliedIndex:8474; }","duration":"333.537336ms","start":"2026-07-15T03:33:42.778712Z","end":"2026-07-15T03:33:43.112249Z","steps":["trace[820163430] 'read index received' (duration: 333.317752ms)","trace[820163430] 'applied index is now lower than readState.Index' (duration: 215.534µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:33:43.112374Z","caller":"traceutil/trace.go:171","msg":"trace[320284744] transaction","detail":"{read_only:false; response_revision:8191; number_of_response:1; }","duration":"335.082957ms","start":"2026-07-15T03:33:42.777243Z","end":"2026-07-15T03:33:43.112326Z","steps":["trace[320284744] 'process raft request' (duration: 334.847482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:33:43.112427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.706859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:33:43.112468Z","caller":"traceutil/trace.go:171","msg":"trace[1950710848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8191; }","duration":"333.822121ms","start":"2026-07-15T03:33:42.778636Z","end":"2026-07-15T03:33:43.112458Z","steps":["trace[1950710848] 'agreement among raft nodes before linearized reading' (duration: 333.73417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:33:43.11251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:33:42.778606Z","time spent":"333.895783ms","remote":"127.0.0.1:45708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T03:33:43.11257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:33:42.777213Z","time spent":"335.253971ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T03:33:50.356297Z","caller":"traceutil/trace.go:171","msg":"trace[1756573975] transaction","detail":"{read_only:false; response_revision:8229; number_of_response:1; }","duration":"135.784092ms","start":"2026-07-15T03:33:50.220477Z","end":"2026-07-15T03:33:50.356261Z","steps":["trace[1756573975] 'process raft request' (duration: 135.561078ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:34:22.326357Z","caller":"traceutil/trace.go:171","msg":"trace[682226982] linearizableReadLoop","detail":"{readStateIndex:8741; appliedIndex:8740; }","duration":"310.665832ms","start":"2026-07-15T03:34:22.015668Z","end":"2026-07-15T03:34:22.326334Z","steps":["trace[682226982] 'read index received' (duration: 310.476068ms)","trace[682226982] 'applied index is now lower than readState.Index' (duration: 188.944µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:34:22.326556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.883765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-15T03:34:22.326586Z","caller":"traceutil/trace.go:171","msg":"trace[510493748] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8447; }","duration":"310.965707ms","start":"2026-07-15T03:34:22.015612Z","end":"2026-07-15T03:34:22.326578Z","steps":["trace[510493748] 'agreement among raft nodes before linearized reading' (duration: 310.818584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:34:22.326609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:34:22.015594Z","time spent":"311.008248ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-15T03:34:22.326707Z","caller":"traceutil/trace.go:171","msg":"trace[1980944069] transaction","detail":"{read_only:false; response_revision:8447; number_of_response:1; }","duration":"348.721558ms","start":"2026-07-15T03:34:21.977964Z","end":"2026-07-15T03:34:22.326685Z","steps":["trace[1980944069] 'process raft request' (duration: 348.235229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:34:22.326812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:34:21.977949Z","time spent":"348.80339ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T03:34:34.444831Z","caller":"traceutil/trace.go:171","msg":"trace[547598478] transaction","detail":"{read_only:false; response_revision:8554; number_of_response:1; }","duration":"121.422206ms","start":"2026-07-15T03:34:34.323374Z","end":"2026-07-15T03:34:34.444797Z","steps":["trace[547598478] 'process raft request' (duration: 121.217572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:34:34.726408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.037438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:34:34.726611Z","caller":"traceutil/trace.go:171","msg":"trace[1105175335] transaction","detail":"{read_only:false; response_revision:8556; number_of_response:1; }","duration":"197.53688ms","start":"2026-07-15T03:34:34.529056Z","end":"2026-07-15T03:34:34.726593Z","steps":["trace[1105175335] 'process raft request' (duration: 197.481059ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:34:34.726782Z","caller":"traceutil/trace.go:171","msg":"trace[286758816] linearizableReadLoop","detail":"{readStateIndex:8854; appliedIndex:8852; }","duration":"339.165458ms","start":"2026-07-15T03:34:34.387596Z","end":"2026-07-15T03:34:34.726761Z","steps":["trace[286758816] 'read index received' (duration: 57.056135ms)","trace[286758816] 'applied index is now lower than readState.Index' (duration: 282.107023ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:34:34.726792Z","caller":"traceutil/trace.go:171","msg":"trace[1329558994] transaction","detail":"{read_only:false; response_revision:8555; number_of_response:1; }","duration":"350.832621ms","start":"2026-07-15T03:34:34.375925Z","end":"2026-07-15T03:34:34.726758Z","steps":["trace[1329558994] 'process raft request' (duration: 126.356074ms)","trace[1329558994] 'compare' (duration: 223.911255ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:34:34.726948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:34:34.375896Z","time spent":"350.969633ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:34:34.727012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.408943ms","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-07-15T03:34:34.727056Z","caller":"traceutil/trace.go:171","msg":"trace[315050806] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8556; }","duration":"339.501095ms","start":"2026-07-15T03:34:34.387541Z","end":"2026-07-15T03:34:34.727042Z","steps":["trace[315050806] 'agreement among raft nodes before linearized reading' (duration: 339.327461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:34:34.7271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:34:34.387511Z","time spent":"339.577466ms","remote":"127.0.0.1:45970","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-07-15T03:34:34.739171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.144443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-15T03:34:34.739204Z","caller":"traceutil/trace.go:171","msg":"trace[1335883347] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8557; }","duration":"293.248485ms","start":"2026-07-15T03:34:34.445948Z","end":"2026-07-15T03:34:34.739196Z","steps":["trace[1335883347] 'agreement among raft nodes before linearized reading' (duration: 293.150903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:34:34.739232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.844161ms","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-07-15T03:34:34.739266Z","caller":"traceutil/trace.go:171","msg":"trace[458655019] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8557; }","duration":"155.926463ms","start":"2026-07-15T03:34:34.58333Z","end":"2026-07-15T03:34:34.739257Z","steps":["trace[458655019] 'agreement among raft nodes before linearized reading' (duration: 155.80034ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:34:34.739357Z","caller":"traceutil/trace.go:171","msg":"trace[550033255] transaction","detail":"{read_only:false; response_revision:8557; number_of_response:1; }","duration":"135.043077ms","start":"2026-07-15T03:34:34.604291Z","end":"2026-07-15T03:34:34.739334Z","steps":["trace[550033255] 'process raft request' (duration: 134.734901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:34:34.73938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.792336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-15T03:34:34.7395Z","caller":"traceutil/trace.go:171","msg":"trace[704561750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8557; }","duration":"198.943928ms","start":"2026-07-15T03:34:34.540539Z","end":"2026-07-15T03:34:34.739483Z","steps":["trace[704561750] 'agreement among raft nodes before linearized reading' (duration: 198.778655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:34:34.739453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.066984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-15T03:34:34.739774Z","caller":"traceutil/trace.go:171","msg":"trace[153678189] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8557; }","duration":"155.33878ms","start":"2026-07-15T03:34:34.584379Z","end":"2026-07-15T03:34:34.739718Z","steps":["trace[153678189] 'agreement among raft nodes before linearized reading' (duration: 154.999573ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:35:19.046617Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002489.wal"} {"level":"info","ts":"2026-07-15T03:36:27.259111Z","caller":"traceutil/trace.go:171","msg":"trace[1404099232] transaction","detail":"{read_only:false; response_revision:9593; number_of_response:1; }","duration":"118.509138ms","start":"2026-07-15T03:36:27.140572Z","end":"2026-07-15T03:36:27.259081Z","steps":["trace[1404099232] 'process raft request' (duration: 105.805505ms)","trace[1404099232] 'compare' (duration: 12.181552ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:36:27.268917Z","caller":"traceutil/trace.go:171","msg":"trace[1243150635] transaction","detail":"{read_only:false; response_revision:9594; number_of_response:1; }","duration":"126.786723ms","start":"2026-07-15T03:36:27.142105Z","end":"2026-07-15T03:36:27.268891Z","steps":["trace[1243150635] 'process raft request' (duration: 126.63258ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:36:34.218147Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f170c75a1d97cc","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T03:36:34.270142Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-15T03:36:34.270391Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-15T03:38:32.332621Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8005} {"level":"info","ts":"2026-07-15T03:38:32.395599Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8005,"took":"60.521503ms","hash":867543430,"current-db-size-bytes":57516032,"current-db-size":"58 MB","current-db-size-in-use-bytes":29638656,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-15T03:38:32.395693Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":867543430,"revision":8005,"compact-revision":5880} {"level":"info","ts":"2026-07-15T03:39:18.55188Z","caller":"traceutil/trace.go:171","msg":"trace[209193857] transaction","detail":"{read_only:false; response_revision:11102; number_of_response:1; }","duration":"101.07087ms","start":"2026-07-15T03:39:18.450768Z","end":"2026-07-15T03:39:18.551839Z","steps":["trace[209193857] 'process raft request' (duration: 58.095656ms)","trace[209193857] 'compare' (duration: 42.776951ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:39:22.637943Z","caller":"traceutil/trace.go:171","msg":"trace[420374688] linearizableReadLoop","detail":"{readStateIndex:11495; appliedIndex:11494; }","duration":"102.766483ms","start":"2026-07-15T03:39:22.535146Z","end":"2026-07-15T03:39:22.637913Z","steps":["trace[420374688] 'read index received' (duration: 102.503818ms)","trace[420374688] 'applied index is now lower than readState.Index' (duration: 261.625µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:39:22.638024Z","caller":"traceutil/trace.go:171","msg":"trace[1529991960] transaction","detail":"{read_only:false; response_revision:11121; number_of_response:1; }","duration":"148.048652ms","start":"2026-07-15T03:39:22.48993Z","end":"2026-07-15T03:39:22.637979Z","steps":["trace[1529991960] 'process raft request' (duration: 147.799697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:39:22.638144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.973056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:39:22.638238Z","caller":"traceutil/trace.go:171","msg":"trace[1189660059] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:11121; }","duration":"103.103599ms","start":"2026-07-15T03:39:22.535111Z","end":"2026-07-15T03:39:22.638214Z","steps":["trace[1189660059] 'agreement among raft nodes before linearized reading' (duration: 102.954846ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:39:22.77915Z","caller":"traceutil/trace.go:171","msg":"trace[1965087950] transaction","detail":"{read_only:false; response_revision:11122; number_of_response:1; }","duration":"136.04398ms","start":"2026-07-15T03:39:22.643077Z","end":"2026-07-15T03:39:22.779121Z","steps":["trace[1965087950] 'process raft request' (duration: 62.159613ms)","trace[1965087950] 'compare' (duration: 73.702903ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:39:22.77946Z","caller":"traceutil/trace.go:171","msg":"trace[1230745651] transaction","detail":"{read_only:false; response_revision:11123; number_of_response:1; }","duration":"136.307765ms","start":"2026-07-15T03:39:22.643107Z","end":"2026-07-15T03:39:22.779415Z","steps":["trace[1230745651] 'process raft request' (duration: 135.971139ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:39:22.779612Z","caller":"traceutil/trace.go:171","msg":"trace[1744079760] linearizableReadLoop","detail":"{readStateIndex:11498; appliedIndex:11495; }","duration":"120.517753ms","start":"2026-07-15T03:39:22.65907Z","end":"2026-07-15T03:39:22.779588Z","steps":["trace[1744079760] 'read index received' (duration: 46.259657ms)","trace[1744079760] 'applied index is now lower than readState.Index' (duration: 74.256326ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:39:22.779784Z","caller":"traceutil/trace.go:171","msg":"trace[1043349652] transaction","detail":"{read_only:false; response_revision:11124; number_of_response:1; }","duration":"136.128392ms","start":"2026-07-15T03:39:22.643558Z","end":"2026-07-15T03:39:22.779686Z","steps":["trace[1043349652] 'process raft request' (duration: 135.753804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:39:22.780034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.84765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T03:39:22.780148Z","caller":"traceutil/trace.go:171","msg":"trace[1275038373] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:11124; }","duration":"121.133225ms","start":"2026-07-15T03:39:22.658996Z","end":"2026-07-15T03:39:22.780129Z","steps":["trace[1275038373] 'agreement among raft nodes before linearized reading' (duration: 120.818179ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:39:23.514787Z","caller":"traceutil/trace.go:171","msg":"trace[2118830791] transaction","detail":"{read_only:false; response_revision:11127; number_of_response:1; }","duration":"148.483259ms","start":"2026-07-15T03:39:23.36628Z","end":"2026-07-15T03:39:23.514764Z","steps":["trace[2118830791] 'process raft request' (duration: 147.912709ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:43:32.342844Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10652} {"level":"info","ts":"2026-07-15T03:43:32.430915Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10652,"took":"84.157742ms","hash":2762902513,"current-db-size-bytes":57516032,"current-db-size":"58 MB","current-db-size-in-use-bytes":27348992,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-15T03:43:32.430995Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2762902513,"revision":10652,"compact-revision":8005} {"level":"info","ts":"2026-07-15T03:45:35.321751Z","caller":"traceutil/trace.go:171","msg":"trace[1665545604] transaction","detail":"{read_only:false; response_revision:13637; number_of_response:1; }","duration":"127.921365ms","start":"2026-07-15T03:45:35.193811Z","end":"2026-07-15T03:45:35.321733Z","steps":["trace[1665545604] 'process raft request' (duration: 127.231491ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:45:42.366919Z","caller":"traceutil/trace.go:171","msg":"trace[477174751] transaction","detail":"{read_only:false; response_revision:13672; number_of_response:1; }","duration":"106.585706ms","start":"2026-07-15T03:45:42.260302Z","end":"2026-07-15T03:45:42.366888Z","steps":["trace[477174751] 'process raft request' (duration: 106.389552ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:45:49.876639Z","caller":"traceutil/trace.go:171","msg":"trace[1916191854] linearizableReadLoop","detail":"{readStateIndex:14191; appliedIndex:14190; }","duration":"171.031931ms","start":"2026-07-15T03:45:49.705574Z","end":"2026-07-15T03:45:49.876606Z","steps":["trace[1916191854] 'read index received' (duration: 170.660154ms)","trace[1916191854] 'applied index is now lower than readState.Index' (duration: 370.107µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:45:49.876814Z","caller":"traceutil/trace.go:171","msg":"trace[1630339472] transaction","detail":"{read_only:false; response_revision:13727; number_of_response:1; }","duration":"184.755095ms","start":"2026-07-15T03:45:49.692031Z","end":"2026-07-15T03:45:49.876787Z","steps":["trace[1630339472] 'process raft request' (duration: 184.307846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:45:49.877065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.06079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:45:49.877118Z","caller":"traceutil/trace.go:171","msg":"trace[1802451144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13727; }","duration":"100.159611ms","start":"2026-07-15T03:45:49.77694Z","end":"2026-07-15T03:45:49.8771Z","steps":["trace[1802451144] 'agreement among raft nodes before linearized reading' (duration: 100.057129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:45:49.877135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.903468ms","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-07-15T03:45:49.877261Z","caller":"traceutil/trace.go:171","msg":"trace[937675185] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13727; }","duration":"149.0925ms","start":"2026-07-15T03:45:49.728144Z","end":"2026-07-15T03:45:49.877236Z","steps":["trace[937675185] 'agreement among raft nodes before linearized reading' (duration: 148.708973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:45:49.877103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.48899ms","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:7"} {"level":"info","ts":"2026-07-15T03:45:49.87733Z","caller":"traceutil/trace.go:171","msg":"trace[316213391] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:13727; }","duration":"171.759366ms","start":"2026-07-15T03:45:49.705554Z","end":"2026-07-15T03:45:49.877313Z","steps":["trace[316213391] 'agreement among raft nodes before linearized reading' (duration: 171.42884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:45:50.887296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.861823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-07-15T03:45:50.887388Z","caller":"traceutil/trace.go:171","msg":"trace[1019822909] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:13729; }","duration":"202.012576ms","start":"2026-07-15T03:45:50.685357Z","end":"2026-07-15T03:45:50.887369Z","steps":["trace[1019822909] 'range keys from in-memory index tree' (duration: 201.68571ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:45:50.900389Z","caller":"traceutil/trace.go:171","msg":"trace[508110855] linearizableReadLoop","detail":"{readStateIndex:14194; appliedIndex:14193; }","duration":"123.813166ms","start":"2026-07-15T03:45:50.776525Z","end":"2026-07-15T03:45:50.900338Z","steps":["trace[508110855] 'read index received' (duration: 61.242115ms)","trace[508110855] 'applied index is now lower than readState.Index' (duration: 62.568971ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:45:50.900509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.973599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:45:50.90059Z","caller":"traceutil/trace.go:171","msg":"trace[188730403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13729; }","duration":"124.098312ms","start":"2026-07-15T03:45:50.776472Z","end":"2026-07-15T03:45:50.900571Z","steps":["trace[188730403] 'agreement among raft nodes before linearized reading' (duration: 123.967429ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:46:00.297287Z","caller":"traceutil/trace.go:171","msg":"trace[664162118] linearizableReadLoop","detail":"{readStateIndex:14361; appliedIndex:14360; }","duration":"135.271805ms","start":"2026-07-15T03:46:00.161982Z","end":"2026-07-15T03:46:00.297254Z","steps":["trace[664162118] 'read index received' (duration: 134.97998ms)","trace[664162118] 'applied index is now lower than readState.Index' (duration: 290.645µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:46:00.29733Z","caller":"traceutil/trace.go:171","msg":"trace[155026197] transaction","detail":"{read_only:false; response_revision:13894; number_of_response:1; }","duration":"208.230396ms","start":"2026-07-15T03:46:00.089057Z","end":"2026-07-15T03:46:00.297287Z","steps":["trace[155026197] 'process raft request' (duration: 207.994242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:46:00.297647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.646482ms","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-07-15T03:46:00.297744Z","caller":"traceutil/trace.go:171","msg":"trace[401095551] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13894; }","duration":"135.810815ms","start":"2026-07-15T03:46:00.161912Z","end":"2026-07-15T03:46:00.297723Z","steps":["trace[401095551] 'agreement among raft nodes before linearized reading' (duration: 135.479849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:46:00.297678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.232242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-m7888\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-07-15T03:46:00.297817Z","caller":"traceutil/trace.go:171","msg":"trace[1838198302] range","detail":"{range_begin:/registry/pods/openstack/keepalived-m7888; range_end:; response_count:1; response_revision:13894; }","duration":"101.430386ms","start":"2026-07-15T03:46:00.196371Z","end":"2026-07-15T03:46:00.297801Z","steps":["trace[1838198302] 'agreement among raft nodes before linearized reading' (duration: 101.097069ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:47:39.837046Z","caller":"traceutil/trace.go:171","msg":"trace[2009203251] transaction","detail":"{read_only:false; response_revision:14980; number_of_response:1; }","duration":"114.576919ms","start":"2026-07-15T03:47:39.722399Z","end":"2026-07-15T03:47:39.836976Z","steps":["trace[2009203251] 'process raft request' (duration: 114.233102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:47:41.10043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.276702ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-15T03:47:41.100543Z","caller":"traceutil/trace.go:171","msg":"trace[1521130043] linearizableReadLoop","detail":"{readStateIndex:15487; appliedIndex:15486; }","duration":"175.679872ms","start":"2026-07-15T03:47:40.924839Z","end":"2026-07-15T03:47:41.100519Z","steps":["trace[1521130043] 'read index received' (duration: 35.294158ms)","trace[1521130043] 'applied index is now lower than readState.Index' (duration: 140.384384ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:47:41.100855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.998298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:47:41.100908Z","caller":"traceutil/trace.go:171","msg":"trace[1517279721] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14984; }","duration":"176.061209ms","start":"2026-07-15T03:47:40.924831Z","end":"2026-07-15T03:47:41.100893Z","steps":["trace[1517279721] 'agreement among raft nodes before linearized reading' (duration: 175.913276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:47:41.100944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.035259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-15T03:47:41.101003Z","caller":"traceutil/trace.go:171","msg":"trace[1260176372] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14984; }","duration":"176.12056ms","start":"2026-07-15T03:47:40.924869Z","end":"2026-07-15T03:47:41.100989Z","steps":["trace[1260176372] 'agreement among raft nodes before linearized reading' (duration: 175.943536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:47:41.101142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.863756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-15T03:47:41.101354Z","caller":"traceutil/trace.go:171","msg":"trace[1641797893] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14984; }","duration":"174.992318ms","start":"2026-07-15T03:47:40.926226Z","end":"2026-07-15T03:47:41.101218Z","steps":["trace[1641797893] 'agreement among raft nodes before linearized reading' (duration: 174.723573ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:48:32.348885Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12726} {"level":"info","ts":"2026-07-15T03:48:32.436935Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12726,"took":"85.222225ms","hash":1185192060,"current-db-size-bytes":57516032,"current-db-size":"58 MB","current-db-size-in-use-bytes":30363648,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-15T03:48:32.437022Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1185192060,"revision":12726,"compact-revision":10652} {"level":"info","ts":"2026-07-15T03:51:59.08315Z","caller":"traceutil/trace.go:171","msg":"trace[749491304] transaction","detail":"{read_only:false; response_revision:16900; number_of_response:1; }","duration":"107.191151ms","start":"2026-07-15T03:51:58.975928Z","end":"2026-07-15T03:51:59.08312Z","steps":["trace[749491304] 'process raft request' (duration: 70.841726ms)","trace[749491304] 'compare' (duration: 36.14796ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:51:59.083564Z","caller":"traceutil/trace.go:171","msg":"trace[1397211148] transaction","detail":"{read_only:false; response_revision:16901; number_of_response:1; }","duration":"105.864264ms","start":"2026-07-15T03:51:58.977624Z","end":"2026-07-15T03:51:59.083488Z","steps":["trace[1397211148] 'process raft request' (duration: 105.452027ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:53:21.455261Z","caller":"traceutil/trace.go:171","msg":"trace[1196045087] transaction","detail":"{read_only:false; response_revision:17553; number_of_response:1; }","duration":"178.617157ms","start":"2026-07-15T03:53:21.276616Z","end":"2026-07-15T03:53:21.455233Z","steps":["trace[1196045087] 'process raft request' (duration: 178.447794ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:53:21.472189Z","caller":"traceutil/trace.go:171","msg":"trace[682900570] transaction","detail":"{read_only:false; response_revision:17554; number_of_response:1; }","duration":"166.411466ms","start":"2026-07-15T03:53:21.305754Z","end":"2026-07-15T03:53:21.472166Z","steps":["trace[682900570] 'process raft request' (duration: 165.944347ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:53:21.852119Z","caller":"traceutil/trace.go:171","msg":"trace[1591497664] linearizableReadLoop","detail":"{readStateIndex:18143; appliedIndex:18142; }","duration":"358.089139ms","start":"2026-07-15T03:53:21.493995Z","end":"2026-07-15T03:53:21.852084Z","steps":["trace[1591497664] 'read index received' (duration: 261.490608ms)","trace[1591497664] 'applied index is now lower than readState.Index' (duration: 96.596871ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:53:21.852281Z","caller":"traceutil/trace.go:171","msg":"trace[1025690708] transaction","detail":"{read_only:false; response_revision:17555; number_of_response:1; }","duration":"365.865737ms","start":"2026-07-15T03:53:21.486397Z","end":"2026-07-15T03:53:21.852263Z","steps":["trace[1025690708] 'process raft request' (duration: 269.148283ms)","trace[1025690708] 'compare' (duration: 96.212794ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:53:21.852425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:53:21.486382Z","time spent":"365.942429ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:53:21.852463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.159072ms","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-07-15T03:53:21.852565Z","caller":"traceutil/trace.go:171","msg":"trace[526401769] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17555; }","duration":"357.282905ms","start":"2026-07-15T03:53:21.495257Z","end":"2026-07-15T03:53:21.85254Z","steps":["trace[526401769] 'agreement among raft nodes before linearized reading' (duration: 357.039761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:53:21.852618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:53:21.495252Z","time spent":"357.357717ms","remote":"127.0.0.1:45970","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-07-15T03:53:21.853074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.45741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-fn8cz\" ","response":"range_response_count:1 size:20755"} {"level":"info","ts":"2026-07-15T03:53:21.85316Z","caller":"traceutil/trace.go:171","msg":"trace[1955651322] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-fn8cz; range_end:; response_count:1; response_revision:17555; }","duration":"250.568852ms","start":"2026-07-15T03:53:21.602569Z","end":"2026-07-15T03:53:21.853138Z","steps":["trace[1955651322] 'agreement among raft nodes before linearized reading' (duration: 250.355408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:53:21.853266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.236391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-5pjpn\" ","response":"range_response_count:1 size:22125"} {"level":"warn","ts":"2026-07-15T03:53:21.853328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.367025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-07-15T03:53:21.853382Z","caller":"traceutil/trace.go:171","msg":"trace[1640808502] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-5pjpn; range_end:; response_count:1; response_revision:17555; }","duration":"359.407615ms","start":"2026-07-15T03:53:21.493947Z","end":"2026-07-15T03:53:21.853354Z","steps":["trace[1640808502] 'agreement among raft nodes before linearized reading' (duration: 359.024647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:53:21.853435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:53:21.493921Z","time spent":"359.504656ms","remote":"127.0.0.1:45908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22147,"request content":"key:\"/registry/pods/openstack/nova-compute-default-5pjpn\" "} {"level":"info","ts":"2026-07-15T03:53:21.853437Z","caller":"traceutil/trace.go:171","msg":"trace[785655088] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:17555; }","duration":"310.536509ms","start":"2026-07-15T03:53:21.542871Z","end":"2026-07-15T03:53:21.853408Z","steps":["trace[785655088] 'agreement among raft nodes before linearized reading' (duration: 310.169172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:53:21.85349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:53:21.542857Z","time spent":"310.62552ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-15T03:53:21.853828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.449043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T03:53:21.853914Z","caller":"traceutil/trace.go:171","msg":"trace[295830002] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:17555; }","duration":"121.591525ms","start":"2026-07-15T03:53:21.732298Z","end":"2026-07-15T03:53:21.85389Z","steps":["trace[295830002] 'agreement among raft nodes before linearized reading' (duration: 120.544546ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:53:21.997068Z","caller":"traceutil/trace.go:171","msg":"trace[557670770] linearizableReadLoop","detail":"{readStateIndex:18148; appliedIndex:18143; }","duration":"132.867079ms","start":"2026-07-15T03:53:21.864134Z","end":"2026-07-15T03:53:21.997001Z","steps":["trace[557670770] 'read index received' (duration: 13.383364ms)","trace[557670770] 'applied index is now lower than readState.Index' (duration: 119.482285ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:53:21.997349Z","caller":"traceutil/trace.go:171","msg":"trace[1080223013] transaction","detail":"{read_only:false; response_revision:17558; number_of_response:1; }","duration":"137.67301ms","start":"2026-07-15T03:53:21.859651Z","end":"2026-07-15T03:53:21.997324Z","steps":["trace[1080223013] 'process raft request' (duration: 136.885635ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:53:21.998836Z","caller":"traceutil/trace.go:171","msg":"trace[1983254251] transaction","detail":"{read_only:false; response_revision:17557; number_of_response:1; }","duration":"139.18537ms","start":"2026-07-15T03:53:21.859632Z","end":"2026-07-15T03:53:21.998817Z","steps":["trace[1983254251] 'process raft request' (duration: 121.170338ms)","trace[1983254251] 'compare' (duration: 15.081076ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:53:21.999441Z","caller":"traceutil/trace.go:171","msg":"trace[1545193449] transaction","detail":"{read_only:false; response_revision:17560; number_of_response:1; }","duration":"138.061857ms","start":"2026-07-15T03:53:21.861328Z","end":"2026-07-15T03:53:21.99939Z","steps":["trace[1545193449] 'process raft request' (duration: 135.403817ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:53:21.999767Z","caller":"traceutil/trace.go:171","msg":"trace[982679147] transaction","detail":"{read_only:false; response_revision:17559; number_of_response:1; }","duration":"139.221ms","start":"2026-07-15T03:53:21.860501Z","end":"2026-07-15T03:53:21.999722Z","steps":["trace[982679147] 'process raft request' (duration: 136.141402ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:53:21.99993Z","caller":"traceutil/trace.go:171","msg":"trace[376896183] transaction","detail":"{read_only:false; response_revision:17561; number_of_response:1; }","duration":"129.049346ms","start":"2026-07-15T03:53:21.870787Z","end":"2026-07-15T03:53:21.999836Z","steps":["trace[376896183] 'process raft request' (duration: 126.08127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:53:22.002554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.395813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:93 size:909021"} {"level":"info","ts":"2026-07-15T03:53:22.002603Z","caller":"traceutil/trace.go:171","msg":"trace[880041329] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:93; response_revision:17561; }","duration":"138.491546ms","start":"2026-07-15T03:53:21.864101Z","end":"2026-07-15T03:53:22.002592Z","steps":["trace[880041329] 'agreement among raft nodes before linearized reading' (duration: 133.117134ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:53:30.720622Z","caller":"traceutil/trace.go:171","msg":"trace[871131455] transaction","detail":"{read_only:false; response_revision:17824; number_of_response:1; }","duration":"109.740151ms","start":"2026-07-15T03:53:30.610857Z","end":"2026-07-15T03:53:30.720597Z","steps":["trace[871131455] 'process raft request' (duration: 22.898884ms)","trace[871131455] 'compare' (duration: 86.685514ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:53:32.355171Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15318} {"level":"info","ts":"2026-07-15T03:53:32.451781Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15318,"took":"93.868509ms","hash":4080029997,"current-db-size-bytes":57516032,"current-db-size":"58 MB","current-db-size-in-use-bytes":32530432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T03:53:32.451868Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4080029997,"revision":15318,"compact-revision":12726} {"level":"info","ts":"2026-07-15T03:56:22.245762Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f170c75a1d97cc","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T03:56:22.251357Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-15T03:56:22.251458Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-15T03:58:32.360582Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17839} {"level":"info","ts":"2026-07-15T03:58:32.476193Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17839,"took":"112.485125ms","hash":616982386,"current-db-size-bytes":57516032,"current-db-size":"58 MB","current-db-size-in-use-bytes":31268864,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T03:58:32.476269Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":616982386,"revision":17839,"compact-revision":15318} {"level":"warn","ts":"2026-07-15T04:00:35.617909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.060063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T04:00:35.618172Z","caller":"traceutil/trace.go:171","msg":"trace[704272452] linearizableReadLoop","detail":"{readStateIndex:21897; appliedIndex:21895; }","duration":"173.785624ms","start":"2026-07-15T04:00:35.44437Z","end":"2026-07-15T04:00:35.618155Z","steps":["trace[704272452] 'read index received' (duration: 73.185286ms)","trace[704272452] 'applied index is now lower than readState.Index' (duration: 100.599067ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:00:35.6183Z","caller":"traceutil/trace.go:171","msg":"trace[2107735169] transaction","detail":"{read_only:false; response_revision:21196; number_of_response:1; }","duration":"252.2882ms","start":"2026-07-15T04:00:35.366001Z","end":"2026-07-15T04:00:35.618289Z","steps":["trace[2107735169] 'process raft request' (duration: 252.010585ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:00:35.618348Z","caller":"traceutil/trace.go:171","msg":"trace[958593304] transaction","detail":"{read_only:false; response_revision:21195; number_of_response:1; }","duration":"295.198309ms","start":"2026-07-15T04:00:35.323117Z","end":"2026-07-15T04:00:35.618316Z","steps":["trace[958593304] 'process raft request' (duration: 115.639975ms)","trace[958593304] 'compare' (duration: 178.627365ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:00:35.618909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.516767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:00:35.618973Z","caller":"traceutil/trace.go:171","msg":"trace[281758464] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:21196; }","duration":"174.590648ms","start":"2026-07-15T04:00:35.444363Z","end":"2026-07-15T04:00:35.618953Z","steps":["trace[281758464] 'agreement among raft nodes before linearized reading' (duration: 174.479576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:00:35.904331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.266415ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-15T04:00:36.16537Z","caller":"traceutil/trace.go:171","msg":"trace[2029486485] transaction","detail":"{read_only:false; response_revision:21197; number_of_response:1; }","duration":"345.637139ms","start":"2026-07-15T04:00:35.819702Z","end":"2026-07-15T04:00:36.165339Z","steps":["trace[2029486485] 'process raft request' (duration: 305.589186ms)","trace[2029486485] 'compare' (duration: 39.89487ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:00:36.165546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:00:35.819681Z","time spent":"345.774212ms","remote":"127.0.0.1:45970","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-07-15T04:00:36.175443Z","caller":"traceutil/trace.go:171","msg":"trace[1818049763] linearizableReadLoop","detail":"{readStateIndex:21900; appliedIndex:21898; }","duration":"130.353425ms","start":"2026-07-15T04:00:36.045071Z","end":"2026-07-15T04:00:36.175425Z","steps":["trace[1818049763] 'read index received' (duration: 80.298241ms)","trace[1818049763] 'applied index is now lower than readState.Index' (duration: 50.054004ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:00:36.175535Z","caller":"traceutil/trace.go:171","msg":"trace[626398390] transaction","detail":"{read_only:false; response_revision:21198; number_of_response:1; }","duration":"270.078339ms","start":"2026-07-15T04:00:35.905449Z","end":"2026-07-15T04:00:36.175527Z","steps":["trace[626398390] 'process raft request' (duration: 269.853124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:00:36.175819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.671292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T04:00:36.175924Z","caller":"traceutil/trace.go:171","msg":"trace[1636440795] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:21198; }","duration":"130.887235ms","start":"2026-07-15T04:00:36.04502Z","end":"2026-07-15T04:00:36.175907Z","steps":["trace[1636440795] 'agreement among raft nodes before linearized reading' (duration: 130.649091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:00:36.510296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.111806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T04:00:36.510444Z","caller":"traceutil/trace.go:171","msg":"trace[298008414] linearizableReadLoop","detail":"{readStateIndex:21902; appliedIndex:21901; }","duration":"319.623383ms","start":"2026-07-15T04:00:36.190798Z","end":"2026-07-15T04:00:36.510421Z","steps":["trace[298008414] 'read index received' (duration: 195.236862ms)","trace[298008414] 'applied index is now lower than readState.Index' (duration: 124.384231ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:00:36.510568Z","caller":"traceutil/trace.go:171","msg":"trace[1224346536] transaction","detail":"{read_only:false; response_revision:21200; number_of_response:1; }","duration":"330.977269ms","start":"2026-07-15T04:00:36.179572Z","end":"2026-07-15T04:00:36.510549Z","steps":["trace[1224346536] 'process raft request' (duration: 206.479586ms)","trace[1224346536] 'compare' (duration: 123.849931ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:00:36.510761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:00:36.179549Z","time spent":"331.166333ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T04:00:36.510884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.080871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T04:00:36.51097Z","caller":"traceutil/trace.go:171","msg":"trace[796660286] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:21200; }","duration":"320.215234ms","start":"2026-07-15T04:00:36.190737Z","end":"2026-07-15T04:00:36.510953Z","steps":["trace[796660286] 'agreement among raft nodes before linearized reading' (duration: 319.97496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:00:36.511013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:00:36.190717Z","time spent":"320.287065ms","remote":"127.0.0.1:45982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-07-15T04:00:36.510895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.5672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-15T04:00:36.511123Z","caller":"traceutil/trace.go:171","msg":"trace[1300841697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21200; }","duration":"129.853536ms","start":"2026-07-15T04:00:36.381238Z","end":"2026-07-15T04:00:36.511092Z","steps":["trace[1300841697] 'agreement among raft nodes before linearized reading' (duration: 129.399607ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:00:36.722881Z","caller":"traceutil/trace.go:171","msg":"trace[1732156641] transaction","detail":"{read_only:false; response_revision:21201; number_of_response:1; }","duration":"207.432724ms","start":"2026-07-15T04:00:36.515427Z","end":"2026-07-15T04:00:36.72286Z","steps":["trace[1732156641] 'process raft request' (duration: 166.661586ms)","trace[1732156641] 'compare' (duration: 40.651546ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:00:36.726716Z","caller":"traceutil/trace.go:171","msg":"trace[1809848617] linearizableReadLoop","detail":"{readStateIndex:21905; appliedIndex:21902; }","duration":"185.901054ms","start":"2026-07-15T04:00:36.540803Z","end":"2026-07-15T04:00:36.726705Z","steps":["trace[1809848617] 'read index received' (duration: 141.382195ms)","trace[1809848617] 'applied index is now lower than readState.Index' (duration: 44.517819ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:00:36.726804Z","caller":"traceutil/trace.go:171","msg":"trace[38828652] transaction","detail":"{read_only:false; response_revision:21203; number_of_response:1; }","duration":"210.409201ms","start":"2026-07-15T04:00:36.516373Z","end":"2026-07-15T04:00:36.726782Z","steps":["trace[38828652] 'process raft request' (duration: 210.301569ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:00:36.726811Z","caller":"traceutil/trace.go:171","msg":"trace[992705697] transaction","detail":"{read_only:false; response_revision:21202; number_of_response:1; }","duration":"210.440101ms","start":"2026-07-15T04:00:36.516341Z","end":"2026-07-15T04:00:36.726781Z","steps":["trace[992705697] 'process raft request' (duration: 210.264928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:00:36.726855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.041586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-15T04:00:36.726884Z","caller":"traceutil/trace.go:171","msg":"trace[1138393185] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21203; }","duration":"186.118818ms","start":"2026-07-15T04:00:36.540759Z","end":"2026-07-15T04:00:36.726878Z","steps":["trace[1138393185] 'agreement among raft nodes before linearized reading' (duration: 186.003906ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:03:32.366364Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20236} {"level":"info","ts":"2026-07-15T04:03:32.471813Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20236,"took":"102.034076ms","hash":817447325,"current-db-size-bytes":57516032,"current-db-size":"58 MB","current-db-size-in-use-bytes":32460800,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T04:03:32.471876Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":817447325,"revision":20236,"compact-revision":17839} {"level":"info","ts":"2026-07-15T04:07:08.499556Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006624.wal"} {"level":"warn","ts":"2026-07-15T04:07:23.198831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.790517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:07:23.198935Z","caller":"traceutil/trace.go:171","msg":"trace[907870994] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:25553; }","duration":"138.94553ms","start":"2026-07-15T04:07:23.059971Z","end":"2026-07-15T04:07:23.198916Z","steps":["trace[907870994] 'count revisions from in-memory index tree' (duration: 138.719385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:23.199317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.795922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418529"} {"level":"info","ts":"2026-07-15T04:07:23.199428Z","caller":"traceutil/trace.go:171","msg":"trace[735253534] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25553; }","duration":"219.946767ms","start":"2026-07-15T04:07:22.979444Z","end":"2026-07-15T04:07:23.199391Z","steps":["trace[735253534] 'range keys from in-memory index tree' (duration: 219.278493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:23.502239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.553008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:07:23.502327Z","caller":"traceutil/trace.go:171","msg":"trace[773190166] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:25554; }","duration":"112.68675ms","start":"2026-07-15T04:07:23.389619Z","end":"2026-07-15T04:07:23.502306Z","steps":["trace[773190166] 'count revisions from in-memory index tree' (duration: 112.391915ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:23.869205Z","caller":"traceutil/trace.go:171","msg":"trace[1910601715] transaction","detail":"{read_only:false; response_revision:25555; number_of_response:1; }","duration":"284.138591ms","start":"2026-07-15T04:07:23.585039Z","end":"2026-07-15T04:07:23.869178Z","steps":["trace[1910601715] 'process raft request' (duration: 283.828495ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:23.911027Z","caller":"traceutil/trace.go:171","msg":"trace[2127054129] linearizableReadLoop","detail":"{readStateIndex:26378; appliedIndex:26376; }","duration":"302.140384ms","start":"2026-07-15T04:07:23.608867Z","end":"2026-07-15T04:07:23.911007Z","steps":["trace[2127054129] 'read index received' (duration: 259.922918ms)","trace[2127054129] 'applied index is now lower than readState.Index' (duration: 42.216426ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:07:23.911323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.430279ms","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-07-15T04:07:23.911411Z","caller":"traceutil/trace.go:171","msg":"trace[2086868020] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:25557; }","duration":"302.575082ms","start":"2026-07-15T04:07:23.608818Z","end":"2026-07-15T04:07:23.911393Z","steps":["trace[2086868020] 'agreement among raft nodes before linearized reading' (duration: 302.415389ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:23.911412Z","caller":"traceutil/trace.go:171","msg":"trace[1901773308] transaction","detail":"{read_only:false; response_revision:25556; number_of_response:1; }","duration":"319.22335ms","start":"2026-07-15T04:07:23.592166Z","end":"2026-07-15T04:07:23.91139Z","steps":["trace[1901773308] 'process raft request' (duration: 316.658291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:23.911446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:23.6088Z","time spent":"302.637903ms","remote":"127.0.0.1:42058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-15T04:07:23.911475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.127462ms","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-07-15T04:07:23.911535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:23.592138Z","time spent":"319.318552ms","remote":"127.0.0.1:45970","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-07-15T04:07:23.911559Z","caller":"traceutil/trace.go:171","msg":"trace[161170215] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25557; }","duration":"260.238434ms","start":"2026-07-15T04:07:23.6513Z","end":"2026-07-15T04:07:23.911538Z","steps":["trace[161170215] 'agreement among raft nodes before linearized reading' (duration: 259.979239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:23.911536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.904383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:07:23.911675Z","caller":"traceutil/trace.go:171","msg":"trace[845624188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25557; }","duration":"136.103677ms","start":"2026-07-15T04:07:23.775541Z","end":"2026-07-15T04:07:23.911645Z","steps":["trace[845624188] 'agreement among raft nodes before linearized reading' (duration: 135.769991ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:23.911415Z","caller":"traceutil/trace.go:171","msg":"trace[1118410746] transaction","detail":"{read_only:false; response_revision:25557; number_of_response:1; }","duration":"252.048619ms","start":"2026-07-15T04:07:23.659357Z","end":"2026-07-15T04:07:23.911405Z","steps":["trace[1118410746] 'process raft request' (duration: 251.60229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:28.54638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.495334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:07:28.546466Z","caller":"traceutil/trace.go:171","msg":"trace[504726963] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25600; }","duration":"222.652327ms","start":"2026-07-15T04:07:28.323793Z","end":"2026-07-15T04:07:28.546446Z","steps":["trace[504726963] 'count revisions from in-memory index tree' (duration: 222.388353ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:28.662955Z","caller":"traceutil/trace.go:171","msg":"trace[1147086945] transaction","detail":"{read_only:false; response_revision:25601; number_of_response:1; }","duration":"103.579856ms","start":"2026-07-15T04:07:28.559348Z","end":"2026-07-15T04:07:28.662928Z","steps":["trace[1147086945] 'process raft request' (duration: 103.354991ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:28.948853Z","caller":"traceutil/trace.go:171","msg":"trace[902849942] linearizableReadLoop","detail":"{readStateIndex:26425; appliedIndex:26424; }","duration":"285.937405ms","start":"2026-07-15T04:07:28.662875Z","end":"2026-07-15T04:07:28.948812Z","steps":["trace[902849942] 'read index received' (duration: 256.387701ms)","trace[902849942] 'applied index is now lower than readState.Index' (duration: 29.548584ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:07:28.948958Z","caller":"traceutil/trace.go:171","msg":"trace[1672419226] transaction","detail":"{read_only:false; response_revision:25602; number_of_response:1; }","duration":"356.817977ms","start":"2026-07-15T04:07:28.592107Z","end":"2026-07-15T04:07:28.948925Z","steps":["trace[1672419226] 'process raft request' (duration: 327.14316ms)","trace[1672419226] 'compare' (duration: 29.432562ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:07:28.949091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.642765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-15T04:07:28.949128Z","caller":"traceutil/trace.go:171","msg":"trace[1589927753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25602; }","duration":"311.730916ms","start":"2026-07-15T04:07:28.637385Z","end":"2026-07-15T04:07:28.949116Z","steps":["trace[1589927753] 'agreement among raft nodes before linearized reading' (duration: 311.565523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:28.949111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:28.592082Z","time spent":"356.946889ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T04:07:28.949177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:28.637369Z","time spent":"311.797078ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-15T04:07:28.951232Z","caller":"traceutil/trace.go:171","msg":"trace[1909453616] transaction","detail":"{read_only:false; response_revision:25603; number_of_response:1; }","duration":"142.704683ms","start":"2026-07-15T04:07:28.80851Z","end":"2026-07-15T04:07:28.951215Z","steps":["trace[1909453616] 'process raft request' (duration: 142.56834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:28.951423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.267046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:07:28.951477Z","caller":"traceutil/trace.go:171","msg":"trace[961255263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25604; }","duration":"173.367267ms","start":"2026-07-15T04:07:28.778098Z","end":"2026-07-15T04:07:28.951465Z","steps":["trace[961255263] 'agreement among raft nodes before linearized reading' (duration: 173.246355ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:28.951729Z","caller":"traceutil/trace.go:171","msg":"trace[2076820449] transaction","detail":"{read_only:false; response_revision:25604; number_of_response:1; }","duration":"130.690263ms","start":"2026-07-15T04:07:28.821032Z","end":"2026-07-15T04:07:28.951722Z","steps":["trace[2076820449] 'process raft request' (duration: 130.139403ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:29.297181Z","caller":"traceutil/trace.go:171","msg":"trace[1094565070] transaction","detail":"{read_only:false; response_revision:25606; number_of_response:1; }","duration":"341.319721ms","start":"2026-07-15T04:07:28.955837Z","end":"2026-07-15T04:07:29.297157Z","steps":["trace[1094565070] 'process raft request' (duration: 341.117118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:29.297326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:28.955819Z","time spent":"341.437673ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:07:29.29747Z","caller":"traceutil/trace.go:171","msg":"trace[1653865090] transaction","detail":"{read_only:false; response_revision:25607; number_of_response:1; }","duration":"340.823711ms","start":"2026-07-15T04:07:28.956618Z","end":"2026-07-15T04:07:29.297441Z","steps":["trace[1653865090] 'process raft request' (duration: 340.495515ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:29.2975Z","caller":"traceutil/trace.go:171","msg":"trace[474151173] transaction","detail":"{read_only:false; response_revision:25609; number_of_response:1; }","duration":"340.676639ms","start":"2026-07-15T04:07:28.956803Z","end":"2026-07-15T04:07:29.29748Z","steps":["trace[474151173] 'process raft request' (duration: 340.632648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:29.29762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:28.956797Z","time spent":"340.75866ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T04:07:29.297614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:28.95661Z","time spent":"340.928093ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:07:29.297662Z","caller":"traceutil/trace.go:171","msg":"trace[1342156661] transaction","detail":"{read_only:false; response_revision:25608; number_of_response:1; }","duration":"340.927533ms","start":"2026-07-15T04:07:28.956723Z","end":"2026-07-15T04:07:29.297651Z","steps":["trace[1342156661] 'process raft request' (duration: 340.614647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:29.297715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:28.956693Z","time spent":"340.996874ms","remote":"127.0.0.1:45970","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-07-15T04:07:29.574043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.910139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T04:07:29.574314Z","caller":"traceutil/trace.go:171","msg":"trace[1524188315] linearizableReadLoop","detail":"{readStateIndex:26435; appliedIndex:26432; }","duration":"117.503832ms","start":"2026-07-15T04:07:29.45679Z","end":"2026-07-15T04:07:29.574294Z","steps":["trace[1524188315] 'read index received' (duration: 50.051µs)","trace[1524188315] 'applied index is now lower than readState.Index' (duration: 117.449961ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:07:29.57441Z","caller":"traceutil/trace.go:171","msg":"trace[1341788191] transaction","detail":"{read_only:false; response_revision:25612; number_of_response:1; }","duration":"355.744296ms","start":"2026-07-15T04:07:29.218643Z","end":"2026-07-15T04:07:29.574387Z","steps":["trace[1341788191] 'process raft request' (duration: 355.589033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:29.574521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.668125ms","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-07-15T04:07:29.574346Z","caller":"traceutil/trace.go:171","msg":"trace[348869308] transaction","detail":"{read_only:false; response_revision:25611; number_of_response:1; }","duration":"440.990042ms","start":"2026-07-15T04:07:29.133322Z","end":"2026-07-15T04:07:29.574312Z","steps":["trace[348869308] 'process raft request' (duration: 440.830059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:29.574556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:29.21862Z","time spent":"355.857848ms","remote":"127.0.0.1:45970","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-07-15T04:07:29.574539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.471303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:07:29.574585Z","caller":"traceutil/trace.go:171","msg":"trace[694686858] 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:25612; }","duration":"117.822568ms","start":"2026-07-15T04:07:29.456744Z","end":"2026-07-15T04:07:29.574567Z","steps":["trace[694686858] 'agreement among raft nodes before linearized reading' (duration: 117.672565ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:29.574618Z","caller":"traceutil/trace.go:171","msg":"trace[1040005732] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25612; }","duration":"104.585244ms","start":"2026-07-15T04:07:29.470019Z","end":"2026-07-15T04:07:29.574604Z","steps":["trace[1040005732] 'agreement among raft nodes before linearized reading' (duration: 104.440662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:29.57465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:29.133306Z","time spent":"441.284528ms","remote":"127.0.0.1:45970","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-07-15T04:07:29.574712Z","caller":"traceutil/trace.go:171","msg":"trace[771568728] transaction","detail":"{read_only:false; response_revision:25610; number_of_response:1; }","duration":"491.412985ms","start":"2026-07-15T04:07:29.082828Z","end":"2026-07-15T04:07:29.574241Z","steps":["trace[771568728] 'process raft request' (duration: 350.916795ms)","trace[771568728] 'compare' (duration: 139.769306ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:07:29.575418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:29.082784Z","time spent":"492.586018ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T04:07:29.903054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.73654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:07:29.903168Z","caller":"traceutil/trace.go:171","msg":"trace[993862177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25612; }","duration":"124.896673ms","start":"2026-07-15T04:07:29.778247Z","end":"2026-07-15T04:07:29.903144Z","steps":["trace[993862177] 'range keys from in-memory index tree' (duration: 124.669398ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:30.144813Z","caller":"traceutil/trace.go:171","msg":"trace[2075381015] linearizableReadLoop","detail":"{readStateIndex:26436; appliedIndex:26435; }","duration":"166.931206ms","start":"2026-07-15T04:07:29.97786Z","end":"2026-07-15T04:07:30.144791Z","steps":["trace[2075381015] 'read index received' (duration: 166.713402ms)","trace[2075381015] 'applied index is now lower than readState.Index' (duration: 216.784µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:07:30.14498Z","caller":"traceutil/trace.go:171","msg":"trace[293689710] transaction","detail":"{read_only:false; response_revision:25613; number_of_response:1; }","duration":"198.874404ms","start":"2026-07-15T04:07:29.945974Z","end":"2026-07-15T04:07:30.144849Z","steps":["trace[293689710] 'process raft request' (duration: 198.66448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:30.145151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.207561ms","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-07-15T04:07:30.145255Z","caller":"traceutil/trace.go:171","msg":"trace[75540765] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25613; }","duration":"167.418134ms","start":"2026-07-15T04:07:29.977813Z","end":"2026-07-15T04:07:30.145231Z","steps":["trace[75540765] 'agreement among raft nodes before linearized reading' (duration: 167.071507ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:30.441652Z","caller":"traceutil/trace.go:171","msg":"trace[160763114] transaction","detail":"{read_only:false; response_revision:25615; number_of_response:1; }","duration":"183.033112ms","start":"2026-07-15T04:07:30.258335Z","end":"2026-07-15T04:07:30.441368Z","steps":["trace[160763114] 'process raft request' (duration: 182.853839ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:30.468835Z","caller":"traceutil/trace.go:171","msg":"trace[1348553218] linearizableReadLoop","detail":"{readStateIndex:26439; appliedIndex:26437; }","duration":"100.551517ms","start":"2026-07-15T04:07:30.368253Z","end":"2026-07-15T04:07:30.468805Z","steps":["trace[1348553218] 'read index received' (duration: 72.88976ms)","trace[1348553218] 'applied index is now lower than readState.Index' (duration: 27.660647ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:07:30.469028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.702162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:07:30.46901Z","caller":"traceutil/trace.go:171","msg":"trace[1853864959] transaction","detail":"{read_only:false; response_revision:25616; number_of_response:1; }","duration":"126.695468ms","start":"2026-07-15T04:07:30.342246Z","end":"2026-07-15T04:07:30.468942Z","steps":["trace[1853864959] 'process raft request' (duration: 126.416001ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:30.469078Z","caller":"traceutil/trace.go:171","msg":"trace[489575098] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:25616; }","duration":"100.866984ms","start":"2026-07-15T04:07:30.368195Z","end":"2026-07-15T04:07:30.469062Z","steps":["trace[489575098] 'agreement among raft nodes before linearized reading' (duration: 100.713211ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:30.830313Z","caller":"traceutil/trace.go:171","msg":"trace[1207078136] linearizableReadLoop","detail":"{readStateIndex:26440; appliedIndex:26439; }","duration":"106.849529ms","start":"2026-07-15T04:07:30.723446Z","end":"2026-07-15T04:07:30.830296Z","steps":["trace[1207078136] 'read index received' (duration: 106.755286ms)","trace[1207078136] 'applied index is now lower than readState.Index' (duration: 93.383µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:07:30.830523Z","caller":"traceutil/trace.go:171","msg":"trace[138722753] transaction","detail":"{read_only:false; response_revision:25617; number_of_response:1; }","duration":"161.761216ms","start":"2026-07-15T04:07:30.668734Z","end":"2026-07-15T04:07:30.830495Z","steps":["trace[138722753] 'process raft request' (duration: 161.441219ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:30.830534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.054123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:07:30.8306Z","caller":"traceutil/trace.go:171","msg":"trace[59115890] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:25617; }","duration":"107.196195ms","start":"2026-07-15T04:07:30.723386Z","end":"2026-07-15T04:07:30.830582Z","steps":["trace[59115890] 'agreement among raft nodes before linearized reading' (duration: 107.046963ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:31.141985Z","caller":"traceutil/trace.go:171","msg":"trace[1378020077] transaction","detail":"{read_only:false; response_revision:25618; number_of_response:1; }","duration":"187.194102ms","start":"2026-07-15T04:07:30.954771Z","end":"2026-07-15T04:07:31.141965Z","steps":["trace[1378020077] 'process raft request' (duration: 187.061529ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:31.290775Z","caller":"traceutil/trace.go:171","msg":"trace[2024293201] transaction","detail":"{read_only:false; response_revision:25619; number_of_response:1; }","duration":"334.712266ms","start":"2026-07-15T04:07:30.956034Z","end":"2026-07-15T04:07:31.290747Z","steps":["trace[2024293201] 'process raft request' (duration: 332.593045ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:31.290855Z","caller":"traceutil/trace.go:171","msg":"trace[859345810] transaction","detail":"{read_only:false; response_revision:25620; number_of_response:1; }","duration":"333.277468ms","start":"2026-07-15T04:07:30.957533Z","end":"2026-07-15T04:07:31.29081Z","steps":["trace[859345810] 'process raft request' (duration: 332.84093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:31.29097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:30.956013Z","time spent":"334.87952ms","remote":"127.0.0.1:45970","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-07-15T04:07:31.291044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:30.957507Z","time spent":"333.441492ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:07:31.302706Z","caller":"traceutil/trace.go:171","msg":"trace[135648301] linearizableReadLoop","detail":"{readStateIndex:26443; appliedIndex:26441; }","duration":"308.848601ms","start":"2026-07-15T04:07:30.993831Z","end":"2026-07-15T04:07:31.302679Z","steps":["trace[135648301] 'read index received' (duration: 148.118095ms)","trace[135648301] 'applied index is now lower than readState.Index' (duration: 160.729146ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:07:31.30288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.055206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-15T04:07:31.30292Z","caller":"traceutil/trace.go:171","msg":"trace[134862611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25620; }","duration":"309.143058ms","start":"2026-07-15T04:07:30.993768Z","end":"2026-07-15T04:07:31.302911Z","steps":["trace[134862611] 'agreement among raft nodes before linearized reading' (duration: 308.999605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:31.302946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:30.993742Z","time spent":"309.199058ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-15T04:07:31.461715Z","caller":"traceutil/trace.go:171","msg":"trace[796441868] transaction","detail":"{read_only:false; response_revision:25622; number_of_response:1; }","duration":"153.397267ms","start":"2026-07-15T04:07:31.308291Z","end":"2026-07-15T04:07:31.461688Z","steps":["trace[796441868] 'process raft request' (duration: 153.329045ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:31.461734Z","caller":"traceutil/trace.go:171","msg":"trace[1547019541] transaction","detail":"{read_only:false; response_revision:25621; number_of_response:1; }","duration":"153.418977ms","start":"2026-07-15T04:07:31.308283Z","end":"2026-07-15T04:07:31.461702Z","steps":["trace[1547019541] 'process raft request' (duration: 153.111041ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:31.823961Z","caller":"traceutil/trace.go:171","msg":"trace[486074759] transaction","detail":"{read_only:false; response_revision:25624; number_of_response:1; }","duration":"515.550505ms","start":"2026-07-15T04:07:31.308389Z","end":"2026-07-15T04:07:31.823939Z","steps":["trace[486074759] 'process raft request' (duration: 515.443243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:31.824144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:31.308382Z","time spent":"515.644777ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:07:31.824387Z","caller":"traceutil/trace.go:171","msg":"trace[1458618827] linearizableReadLoop","detail":"{readStateIndex:26449; appliedIndex:26444; }","duration":"412.112312ms","start":"2026-07-15T04:07:31.412241Z","end":"2026-07-15T04:07:31.824353Z","steps":["trace[1458618827] 'read index received' (duration: 47.525187ms)","trace[1458618827] 'applied index is now lower than readState.Index' (duration: 364.586115ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:07:31.824445Z","caller":"traceutil/trace.go:171","msg":"trace[953018729] transaction","detail":"{read_only:false; response_revision:25623; number_of_response:1; }","duration":"516.090076ms","start":"2026-07-15T04:07:31.308338Z","end":"2026-07-15T04:07:31.824428Z","steps":["trace[953018729] 'process raft request' (duration: 515.331611ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:31.824616Z","caller":"traceutil/trace.go:171","msg":"trace[1206527009] transaction","detail":"{read_only:false; response_revision:25625; number_of_response:1; }","duration":"515.78361ms","start":"2026-07-15T04:07:31.308819Z","end":"2026-07-15T04:07:31.824603Z","steps":["trace[1206527009] 'process raft request' (duration: 515.104317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:31.824687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:31.308333Z","time spent":"516.292409ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T04:07:31.824768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:31.308811Z","time spent":"515.836061ms","remote":"127.0.0.1:45970","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-07-15T04:07:31.824933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.687404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:07:31.824996Z","caller":"traceutil/trace.go:171","msg":"trace[423814177] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:25625; }","duration":"412.767555ms","start":"2026-07-15T04:07:31.412201Z","end":"2026-07-15T04:07:31.824968Z","steps":["trace[423814177] 'agreement among raft nodes before linearized reading' (duration: 412.675193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:31.825047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:31.412186Z","time spent":"412.847777ms","remote":"127.0.0.1:46014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-07-15T04:07:31.848847Z","caller":"traceutil/trace.go:171","msg":"trace[998345675] transaction","detail":"{read_only:false; response_revision:25626; number_of_response:1; }","duration":"268.111335ms","start":"2026-07-15T04:07:31.580713Z","end":"2026-07-15T04:07:31.848824Z","steps":["trace[998345675] 'process raft request' (duration: 266.499105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:31.849237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.976144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T04:07:31.849284Z","caller":"traceutil/trace.go:171","msg":"trace[558029598] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:25626; }","duration":"124.063467ms","start":"2026-07-15T04:07:31.725209Z","end":"2026-07-15T04:07:31.849273Z","steps":["trace[558029598] 'agreement among raft nodes before linearized reading' (duration: 123.983855ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:31.850774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.32546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:07:31.850842Z","caller":"traceutil/trace.go:171","msg":"trace[1038117584] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:25626; }","duration":"137.688687ms","start":"2026-07-15T04:07:31.713139Z","end":"2026-07-15T04:07:31.850828Z","steps":["trace[1038117584] 'agreement among raft nodes before linearized reading' (duration: 136.277829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:45.026073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.054293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T04:07:45.026093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.011448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-37a3e42c-d2df-4087-8962-4037c3cc0183\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-15T04:07:45.026138Z","caller":"traceutil/trace.go:171","msg":"trace[1501163898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25776; }","duration":"248.157735ms","start":"2026-07-15T04:07:44.777966Z","end":"2026-07-15T04:07:45.026124Z","steps":["trace[1501163898] 'range keys from in-memory index tree' (duration: 248.001152ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:45.026173Z","caller":"traceutil/trace.go:171","msg":"trace[1046497525] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-37a3e42c-d2df-4087-8962-4037c3cc0183; range_end:; response_count:1; response_revision:25776; }","duration":"219.128801ms","start":"2026-07-15T04:07:44.807021Z","end":"2026-07-15T04:07:45.02615Z","steps":["trace[1046497525] 'range keys from in-memory index tree' (duration: 218.845615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:45.026209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.989582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T04:07:45.026299Z","caller":"traceutil/trace.go:171","msg":"trace[145363054] linearizableReadLoop","detail":"{readStateIndex:26603; appliedIndex:26602; }","duration":"104.408971ms","start":"2026-07-15T04:07:44.921878Z","end":"2026-07-15T04:07:45.026287Z","steps":["trace[145363054] 'read index received' (duration: 30.21µs)","trace[145363054] 'applied index is now lower than readState.Index' (duration: 104.377911ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:07:45.026342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.467263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:07:45.026355Z","caller":"traceutil/trace.go:171","msg":"trace[1185597542] transaction","detail":"{read_only:false; response_revision:25777; number_of_response:1; }","duration":"153.04411ms","start":"2026-07-15T04:07:44.8733Z","end":"2026-07-15T04:07:45.026344Z","steps":["trace[1185597542] 'process raft request' (duration: 14.858834ms)","trace[1185597542] 'compare' (duration: 137.910801ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:07:45.02636Z","caller":"traceutil/trace.go:171","msg":"trace[1151071282] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:25777; }","duration":"104.508573ms","start":"2026-07-15T04:07:44.921842Z","end":"2026-07-15T04:07:45.026351Z","steps":["trace[1151071282] 'agreement among raft nodes before linearized reading' (duration: 104.475013ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:45.186163Z","caller":"traceutil/trace.go:171","msg":"trace[827249911] transaction","detail":"{read_only:false; response_revision:25778; number_of_response:1; }","duration":"131.145862ms","start":"2026-07-15T04:07:45.054991Z","end":"2026-07-15T04:07:45.186137Z","steps":["trace[827249911] 'process raft request' (duration: 130.982999ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:45.347428Z","caller":"traceutil/trace.go:171","msg":"trace[823848790] transaction","detail":"{read_only:false; response_revision:25779; number_of_response:1; }","duration":"161.843168ms","start":"2026-07-15T04:07:45.185547Z","end":"2026-07-15T04:07:45.347391Z","steps":["trace[823848790] 'process raft request' (duration: 161.617734ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:45.697324Z","caller":"traceutil/trace.go:171","msg":"trace[177842704] transaction","detail":"{read_only:false; response_revision:25782; number_of_response:1; }","duration":"134.646818ms","start":"2026-07-15T04:07:45.562649Z","end":"2026-07-15T04:07:45.697296Z","steps":["trace[177842704] 'process raft request' (duration: 134.481695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:45.908581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.919299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T04:07:45.908685Z","caller":"traceutil/trace.go:171","msg":"trace[1567871833] linearizableReadLoop","detail":"{readStateIndex:26609; appliedIndex:26607; }","duration":"334.032132ms","start":"2026-07-15T04:07:45.574635Z","end":"2026-07-15T04:07:45.908668Z","steps":["trace[1567871833] 'read index received' (duration: 122.484787ms)","trace[1567871833] 'applied index is now lower than readState.Index' (duration: 211.546395ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:07:45.908753Z","caller":"traceutil/trace.go:171","msg":"trace[333431960] transaction","detail":"{read_only:false; response_revision:25783; number_of_response:1; }","duration":"345.842467ms","start":"2026-07-15T04:07:45.5629Z","end":"2026-07-15T04:07:45.908743Z","steps":["trace[333431960] 'process raft request' (duration: 216.679194ms)","trace[333431960] 'compare' (duration: 128.816557ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:07:45.908811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:45.562886Z","time spent":"345.896758ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T04:07:45.908968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.326227ms","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-07-15T04:07:45.909038Z","caller":"traceutil/trace.go:171","msg":"trace[720539378] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:25783; }","duration":"334.45038ms","start":"2026-07-15T04:07:45.574572Z","end":"2026-07-15T04:07:45.909022Z","steps":["trace[720539378] 'agreement among raft nodes before linearized reading' (duration: 334.203285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:45.909081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:45.574552Z","time spent":"334.518941ms","remote":"127.0.0.1:46048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":195,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-15T04:07:46.180227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.8503ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T04:07:46.180555Z","caller":"traceutil/trace.go:171","msg":"trace[1648521714] linearizableReadLoop","detail":"{readStateIndex:26610; appliedIndex:26609; }","duration":"271.827316ms","start":"2026-07-15T04:07:45.908709Z","end":"2026-07-15T04:07:46.180537Z","steps":["trace[1648521714] 'read index received' (duration: 111.545328ms)","trace[1648521714] 'applied index is now lower than readState.Index' (duration: 160.280018ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:07:46.180818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.494173ms","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":"warn","ts":"2026-07-15T04:07:46.180857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.37674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-15T04:07:46.180918Z","caller":"traceutil/trace.go:171","msg":"trace[1486267490] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:25784; }","duration":"582.471073ms","start":"2026-07-15T04:07:45.598433Z","end":"2026-07-15T04:07:46.180904Z","steps":["trace[1486267490] 'agreement among raft nodes before linearized reading' (duration: 582.287509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:46.180901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.085158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-07-15T04:07:46.180899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.784129ms","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-07-15T04:07:46.180961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.317532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-07-15T04:07:46.180982Z","caller":"traceutil/trace.go:171","msg":"trace[1860818987] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25784; }","duration":"297.249151ms","start":"2026-07-15T04:07:45.883712Z","end":"2026-07-15T04:07:46.180961Z","steps":["trace[1860818987] 'agreement among raft nodes before linearized reading' (duration: 297.030277ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:46.18093Z","caller":"traceutil/trace.go:171","msg":"trace[791959969] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:25784; }","duration":"354.618955ms","start":"2026-07-15T04:07:45.826269Z","end":"2026-07-15T04:07:46.180888Z","steps":["trace[791959969] 'agreement among raft nodes before linearized reading' (duration: 354.434982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:46.180962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:45.598421Z","time spent":"582.532533ms","remote":"127.0.0.1:45766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/199.204.45.157\" "} {"level":"warn","ts":"2026-07-15T04:07:46.180904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.246766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-37a3e42c-d2df-4087-8962-4037c3cc0183\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-07-15T04:07:46.180998Z","caller":"traceutil/trace.go:171","msg":"trace[335387343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25784; }","duration":"298.932212ms","start":"2026-07-15T04:07:45.882048Z","end":"2026-07-15T04:07:46.18098Z","steps":["trace[335387343] 'agreement among raft nodes before linearized reading' (duration: 298.651857ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:46.1813Z","caller":"traceutil/trace.go:171","msg":"trace[963828442] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-37a3e42c-d2df-4087-8962-4037c3cc0183; range_end:; response_count:1; response_revision:25784; }","duration":"150.637413ms","start":"2026-07-15T04:07:46.030625Z","end":"2026-07-15T04:07:46.181262Z","steps":["trace[963828442] 'agreement among raft nodes before linearized reading' (duration: 150.108563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:46.181307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:45.82625Z","time spent":"355.038903ms","remote":"127.0.0.1:46064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":98,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-07-15T04:07:46.180806Z","caller":"traceutil/trace.go:171","msg":"trace[1422159183] transaction","detail":"{read_only:false; response_revision:25784; number_of_response:1; }","duration":"290.766827ms","start":"2026-07-15T04:07:45.890001Z","end":"2026-07-15T04:07:46.180768Z","steps":["trace[1422159183] 'process raft request' (duration: 130.267205ms)","trace[1422159183] 'compare' (duration: 159.631326ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:07:46.181002Z","caller":"traceutil/trace.go:171","msg":"trace[903868653] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25784; }","duration":"297.398883ms","start":"2026-07-15T04:07:45.883591Z","end":"2026-07-15T04:07:46.18099Z","steps":["trace[903868653] 'agreement among raft nodes before linearized reading' (duration: 297.280141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:46.554157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.708431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T04:07:46.554265Z","caller":"traceutil/trace.go:171","msg":"trace[1733804603] linearizableReadLoop","detail":"{readStateIndex:26616; appliedIndex:26615; }","duration":"225.828247ms","start":"2026-07-15T04:07:46.328421Z","end":"2026-07-15T04:07:46.554249Z","steps":["trace[1733804603] 'read index received' (duration: 81.854231ms)","trace[1733804603] 'applied index is now lower than readState.Index' (duration: 143.972966ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:07:46.554358Z","caller":"traceutil/trace.go:171","msg":"trace[827839883] transaction","detail":"{read_only:false; response_revision:25789; number_of_response:1; }","duration":"251.289034ms","start":"2026-07-15T04:07:46.303015Z","end":"2026-07-15T04:07:46.554304Z","steps":["trace[827839883] 'process raft request' (duration: 107.340138ms)","trace[827839883] 'compare' (duration: 143.510797ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:07:46.554492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.079313ms","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-07-15T04:07:46.554528Z","caller":"traceutil/trace.go:171","msg":"trace[1483965684] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25789; }","duration":"226.143964ms","start":"2026-07-15T04:07:46.328374Z","end":"2026-07-15T04:07:46.554518Z","steps":["trace[1483965684] 'agreement among raft nodes before linearized reading' (duration: 225.960521ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:46.847173Z","caller":"traceutil/trace.go:171","msg":"trace[1620879422] linearizableReadLoop","detail":"{readStateIndex:26618; appliedIndex:26617; }","duration":"219.847404ms","start":"2026-07-15T04:07:46.627302Z","end":"2026-07-15T04:07:46.847149Z","steps":["trace[1620879422] 'read index received' (duration: 219.467936ms)","trace[1620879422] 'applied index is now lower than readState.Index' (duration: 378.398µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:07:46.847301Z","caller":"traceutil/trace.go:171","msg":"trace[382599116] transaction","detail":"{read_only:false; response_revision:25791; number_of_response:1; }","duration":"287.979824ms","start":"2026-07-15T04:07:46.559289Z","end":"2026-07-15T04:07:46.847269Z","steps":["trace[382599116] 'process raft request' (duration: 287.516215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:46.847372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.047417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:07:46.847527Z","caller":"traceutil/trace.go:171","msg":"trace[1164930818] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:25791; }","duration":"220.267061ms","start":"2026-07-15T04:07:46.627243Z","end":"2026-07-15T04:07:46.84751Z","steps":["trace[1164930818] 'agreement among raft nodes before linearized reading' (duration: 220.032317ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:47.151432Z","caller":"traceutil/trace.go:171","msg":"trace[1607751317] transaction","detail":"{read_only:false; response_revision:25792; number_of_response:1; }","duration":"120.174692ms","start":"2026-07-15T04:07:47.031232Z","end":"2026-07-15T04:07:47.151406Z","steps":["trace[1607751317] 'process raft request' (duration: 119.820086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:47.687975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.747846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T04:07:47.688225Z","caller":"traceutil/trace.go:171","msg":"trace[825577665] linearizableReadLoop","detail":"{readStateIndex:26622; appliedIndex:26620; }","duration":"172.982879ms","start":"2026-07-15T04:07:47.515226Z","end":"2026-07-15T04:07:47.688209Z","steps":["trace[825577665] 'read index received' (duration: 43.957848ms)","trace[825577665] 'applied index is now lower than readState.Index' (duration: 129.024061ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:07:47.688304Z","caller":"traceutil/trace.go:171","msg":"trace[1024501258] transaction","detail":"{read_only:false; response_revision:25795; number_of_response:1; }","duration":"323.723095ms","start":"2026-07-15T04:07:47.364553Z","end":"2026-07-15T04:07:47.688276Z","steps":["trace[1024501258] 'process raft request' (duration: 323.547552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:47.68838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.158722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-07-15T04:07:47.688409Z","caller":"traceutil/trace.go:171","msg":"trace[77513429] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25795; }","duration":"173.247015ms","start":"2026-07-15T04:07:47.515152Z","end":"2026-07-15T04:07:47.688399Z","steps":["trace[77513429] 'agreement among raft nodes before linearized reading' (duration: 173.106382ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:47.688373Z","caller":"traceutil/trace.go:171","msg":"trace[1828145192] transaction","detail":"{read_only:false; response_revision:25794; number_of_response:1; }","duration":"325.706274ms","start":"2026-07-15T04:07:47.362635Z","end":"2026-07-15T04:07:47.688341Z","steps":["trace[1828145192] 'process raft request' (duration: 196.495769ms)","trace[1828145192] 'compare' (duration: 128.157405ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:07:47.688445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:47.364543Z","time spent":"323.824117ms","remote":"127.0.0.1:45970","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-07-15T04:07:47.688531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:47.362609Z","time spent":"325.840616ms","remote":"127.0.0.1:45970","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-07-15T04:07:48.013457Z","caller":"traceutil/trace.go:171","msg":"trace[734216902] transaction","detail":"{read_only:false; response_revision:25796; number_of_response:1; }","duration":"308.375403ms","start":"2026-07-15T04:07:47.705056Z","end":"2026-07-15T04:07:48.013431Z","steps":["trace[734216902] 'process raft request' (duration: 308.095068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:48.013597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:07:47.705034Z","time spent":"308.490205ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:07:48.013668Z","caller":"traceutil/trace.go:171","msg":"trace[1093980776] linearizableReadLoop","detail":"{readStateIndex:26623; appliedIndex:26622; }","duration":"235.330509ms","start":"2026-07-15T04:07:47.77802Z","end":"2026-07-15T04:07:48.013351Z","steps":["trace[1093980776] 'read index received' (duration: 234.961372ms)","trace[1093980776] 'applied index is now lower than readState.Index' (duration: 367.967µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:07:48.013819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.755198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:07:48.013926Z","caller":"traceutil/trace.go:171","msg":"trace[1696794836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25796; }","duration":"235.930072ms","start":"2026-07-15T04:07:47.777975Z","end":"2026-07-15T04:07:48.013905Z","steps":["trace[1696794836] 'agreement among raft nodes before linearized reading' (duration: 235.701746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:48.014051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.433936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-15T04:07:48.014114Z","caller":"traceutil/trace.go:171","msg":"trace[2017164105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25796; }","duration":"101.522317ms","start":"2026-07-15T04:07:47.912574Z","end":"2026-07-15T04:07:48.014096Z","steps":["trace[2017164105] 'agreement among raft nodes before linearized reading' (duration: 101.340814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:48.318589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.510199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:07:48.318668Z","caller":"traceutil/trace.go:171","msg":"trace[369764864] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:25797; }","duration":"206.631021ms","start":"2026-07-15T04:07:48.112015Z","end":"2026-07-15T04:07:48.318646Z","steps":["trace[369764864] 'count revisions from in-memory index tree' (duration: 206.198073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:48.318712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.120725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T04:07:48.318826Z","caller":"traceutil/trace.go:171","msg":"trace[1618739543] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:25797; }","duration":"137.253708ms","start":"2026-07-15T04:07:48.181547Z","end":"2026-07-15T04:07:48.318801Z","steps":["trace[1618739543] 'count revisions from in-memory index tree' (duration: 137.008874ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:48.318841Z","caller":"traceutil/trace.go:171","msg":"trace[1416315758] transaction","detail":"{read_only:false; response_revision:25798; number_of_response:1; }","duration":"131.867885ms","start":"2026-07-15T04:07:48.18695Z","end":"2026-07-15T04:07:48.318818Z","steps":["trace[1416315758] 'process raft request' (duration: 127.609775ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:48.318751Z","caller":"traceutil/trace.go:171","msg":"trace[1987108301] linearizableReadLoop","detail":"{readStateIndex:26625; appliedIndex:26624; }","duration":"129.094083ms","start":"2026-07-15T04:07:48.18964Z","end":"2026-07-15T04:07:48.318734Z","steps":["trace[1987108301] 'read index received' (duration: 125.187669ms)","trace[1987108301] 'applied index is now lower than readState.Index' (duration: 3.905364ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:07:48.318727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.164629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T04:07:48.319017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.373788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-37a3e42c-d2df-4087-8962-4037c3cc0183\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-07-15T04:07:48.319056Z","caller":"traceutil/trace.go:171","msg":"trace[544171040] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-37a3e42c-d2df-4087-8962-4037c3cc0183; range_end:; response_count:1; response_revision:25798; }","duration":"129.413788ms","start":"2026-07-15T04:07:48.189631Z","end":"2026-07-15T04:07:48.319045Z","steps":["trace[544171040] 'agreement among raft nodes before linearized reading' (duration: 129.145823ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:48.31909Z","caller":"traceutil/trace.go:171","msg":"trace[2063868040] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25797; }","duration":"221.534636ms","start":"2026-07-15T04:07:48.097506Z","end":"2026-07-15T04:07:48.319041Z","steps":["trace[2063868040] 'range keys from in-memory index tree' (duration: 221.067877ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:48.445906Z","caller":"traceutil/trace.go:171","msg":"trace[1830880856] linearizableReadLoop","detail":"{readStateIndex:26628; appliedIndex:26625; }","duration":"119.678193ms","start":"2026-07-15T04:07:48.326203Z","end":"2026-07-15T04:07:48.445881Z","steps":["trace[1830880856] 'read index received' (duration: 3.908714ms)","trace[1830880856] 'applied index is now lower than readState.Index' (duration: 115.768349ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:07:48.446009Z","caller":"traceutil/trace.go:171","msg":"trace[697794607] transaction","detail":"{read_only:false; response_revision:25800; number_of_response:1; }","duration":"122.373184ms","start":"2026-07-15T04:07:48.32357Z","end":"2026-07-15T04:07:48.445943Z","steps":["trace[697794607] 'process raft request' (duration: 122.104169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:48.446135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.908137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-15T04:07:48.446164Z","caller":"traceutil/trace.go:171","msg":"trace[1692661228] transaction","detail":"{read_only:false; response_revision:25801; number_of_response:1; }","duration":"121.649651ms","start":"2026-07-15T04:07:48.324474Z","end":"2026-07-15T04:07:48.446124Z","steps":["trace[1692661228] 'process raft request' (duration: 121.359025ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:48.446399Z","caller":"traceutil/trace.go:171","msg":"trace[1444978952] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25801; }","duration":"120.178313ms","start":"2026-07-15T04:07:48.326175Z","end":"2026-07-15T04:07:48.446354Z","steps":["trace[1444978952] 'agreement among raft nodes before linearized reading' (duration: 119.815566ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:48.703576Z","caller":"traceutil/trace.go:171","msg":"trace[73115511] linearizableReadLoop","detail":"{readStateIndex:26629; appliedIndex:26628; }","duration":"134.059018ms","start":"2026-07-15T04:07:48.569489Z","end":"2026-07-15T04:07:48.703548Z","steps":["trace[73115511] 'read index received' (duration: 116.438521ms)","trace[73115511] 'applied index is now lower than readState.Index' (duration: 17.619287ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:07:48.703638Z","caller":"traceutil/trace.go:171","msg":"trace[1647216622] transaction","detail":"{read_only:false; response_revision:25802; number_of_response:1; }","duration":"143.653821ms","start":"2026-07-15T04:07:48.559947Z","end":"2026-07-15T04:07:48.703601Z","steps":["trace[1647216622] 'process raft request' (duration: 125.989783ms)","trace[1647216622] 'compare' (duration: 17.360482ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:07:48.703872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.375183ms","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-07-15T04:07:48.70392Z","caller":"traceutil/trace.go:171","msg":"trace[305507798] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25802; }","duration":"134.480975ms","start":"2026-07-15T04:07:48.569425Z","end":"2026-07-15T04:07:48.703906Z","steps":["trace[305507798] 'agreement among raft nodes before linearized reading' (duration: 134.282302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:48.704016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.95998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3237"} {"level":"info","ts":"2026-07-15T04:07:48.704324Z","caller":"traceutil/trace.go:171","msg":"trace[436779382] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25802; }","duration":"108.083253ms","start":"2026-07-15T04:07:48.595998Z","end":"2026-07-15T04:07:48.704081Z","steps":["trace[436779382] 'agreement among raft nodes before linearized reading' (duration: 107.852988ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:07:50.480366Z","caller":"traceutil/trace.go:171","msg":"trace[76144195] linearizableReadLoop","detail":"{readStateIndex:26640; appliedIndex:26639; }","duration":"151.208535ms","start":"2026-07-15T04:07:50.329094Z","end":"2026-07-15T04:07:50.480303Z","steps":["trace[76144195] 'read index received' (duration: 151.025921ms)","trace[76144195] 'applied index is now lower than readState.Index' (duration: 181.554µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:07:50.480485Z","caller":"traceutil/trace.go:171","msg":"trace[1081119120] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"155.806653ms","start":"2026-07-15T04:07:50.324667Z","end":"2026-07-15T04:07:50.480474Z","steps":["trace[1081119120] 'process raft request' (duration: 155.501857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:50.480873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.757375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-37a3e42c-d2df-4087-8962-4037c3cc0183\" ","response":"range_response_count:1 size:3947"} {"level":"info","ts":"2026-07-15T04:07:50.480921Z","caller":"traceutil/trace.go:171","msg":"trace[259069417] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-37a3e42c-d2df-4087-8962-4037c3cc0183; range_end:; response_count:1; response_revision:25813; }","duration":"151.848947ms","start":"2026-07-15T04:07:50.32906Z","end":"2026-07-15T04:07:50.480909Z","steps":["trace[259069417] 'agreement among raft nodes before linearized reading' (duration: 151.650803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:07:50.481074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.077815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-15T04:07:50.48119Z","caller":"traceutil/trace.go:171","msg":"trace[1775777121] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25813; }","duration":"147.242798ms","start":"2026-07-15T04:07:50.333899Z","end":"2026-07-15T04:07:50.481142Z","steps":["trace[1775777121] 'agreement among raft nodes before linearized reading' (duration: 146.924092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:22.470583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.500439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:08:22.470677Z","caller":"traceutil/trace.go:171","msg":"trace[464319579] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:26090; }","duration":"130.646683ms","start":"2026-07-15T04:08:22.340007Z","end":"2026-07-15T04:08:22.470653Z","steps":["trace[464319579] 'count revisions from in-memory index tree' (duration: 130.358546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:22.470678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.445043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T04:08:22.470732Z","caller":"traceutil/trace.go:171","msg":"trace[199074069] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:26090; }","duration":"190.537995ms","start":"2026-07-15T04:08:22.28018Z","end":"2026-07-15T04:08:22.470718Z","steps":["trace[199074069] 'count revisions from in-memory index tree' (duration: 190.370262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:22.470885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.960598ms","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-07-15T04:08:22.470957Z","caller":"traceutil/trace.go:171","msg":"trace[75802759] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26090; }","duration":"229.08376ms","start":"2026-07-15T04:08:22.241855Z","end":"2026-07-15T04:08:22.470939Z","steps":["trace[75802759] 'count revisions from in-memory index tree' (duration: 228.900647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:22.471194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.587024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T04:08:22.471565Z","caller":"traceutil/trace.go:171","msg":"trace[641011411] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:26090; }","duration":"159.944751ms","start":"2026-07-15T04:08:22.311567Z","end":"2026-07-15T04:08:22.471511Z","steps":["trace[641011411] 'count revisions from in-memory index tree' (duration: 159.474732ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:22.744253Z","caller":"traceutil/trace.go:171","msg":"trace[837561741] linearizableReadLoop","detail":"{readStateIndex:26926; appliedIndex:26925; }","duration":"107.874098ms","start":"2026-07-15T04:08:22.636362Z","end":"2026-07-15T04:08:22.744236Z","steps":["trace[837561741] 'read index received' (duration: 107.766516ms)","trace[837561741] 'applied index is now lower than readState.Index' (duration: 106.822µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:08:22.744315Z","caller":"traceutil/trace.go:171","msg":"trace[124553547] transaction","detail":"{read_only:false; response_revision:26091; number_of_response:1; }","duration":"142.757963ms","start":"2026-07-15T04:08:22.601518Z","end":"2026-07-15T04:08:22.744276Z","steps":["trace[124553547] 'process raft request' (duration: 142.606201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:22.744434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.832927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-15T04:08:22.744465Z","caller":"traceutil/trace.go:171","msg":"trace[2096583938] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26091; }","duration":"107.913158ms","start":"2026-07-15T04:08:22.636543Z","end":"2026-07-15T04:08:22.744456Z","steps":["trace[2096583938] 'agreement among raft nodes before linearized reading' (duration: 107.795726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:22.744562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.85738ms","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-07-15T04:08:22.744639Z","caller":"traceutil/trace.go:171","msg":"trace[1874964816] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26091; }","duration":"104.951982ms","start":"2026-07-15T04:08:22.639669Z","end":"2026-07-15T04:08:22.744621Z","steps":["trace[1874964816] 'agreement among raft nodes before linearized reading' (duration: 104.715777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:22.744575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.180983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-07-15T04:08:22.744841Z","caller":"traceutil/trace.go:171","msg":"trace[1575104732] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:26091; }","duration":"108.489859ms","start":"2026-07-15T04:08:22.636308Z","end":"2026-07-15T04:08:22.744798Z","steps":["trace[1575104732] 'agreement among raft nodes before linearized reading' (duration: 108.02249ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:24.379021Z","caller":"traceutil/trace.go:171","msg":"trace[83243096] transaction","detail":"{read_only:false; response_revision:26103; number_of_response:1; }","duration":"188.814203ms","start":"2026-07-15T04:08:24.190188Z","end":"2026-07-15T04:08:24.379002Z","steps":["trace[83243096] 'process raft request' (duration: 188.69147ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:24.500593Z","caller":"traceutil/trace.go:171","msg":"trace[1004787433] linearizableReadLoop","detail":"{readStateIndex:26939; appliedIndex:26938; }","duration":"300.622225ms","start":"2026-07-15T04:08:24.19995Z","end":"2026-07-15T04:08:24.500572Z","steps":["trace[1004787433] 'read index received' (duration: 179.136287ms)","trace[1004787433] 'applied index is now lower than readState.Index' (duration: 121.483577ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:08:24.500692Z","caller":"traceutil/trace.go:171","msg":"trace[2108969184] transaction","detail":"{read_only:false; response_revision:26104; number_of_response:1; }","duration":"309.748119ms","start":"2026-07-15T04:08:24.190935Z","end":"2026-07-15T04:08:24.500683Z","steps":["trace[2108969184] 'process raft request' (duration: 298.007995ms)","trace[2108969184] 'compare' (duration: 11.52578ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:08:24.500767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:08:24.190906Z","time spent":"309.805549ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T04:08:24.500931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.980791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-15T04:08:24.500953Z","caller":"traceutil/trace.go:171","msg":"trace[289100295] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26104; }","duration":"301.023332ms","start":"2026-07-15T04:08:24.199922Z","end":"2026-07-15T04:08:24.500945Z","steps":["trace[289100295] 'agreement among raft nodes before linearized reading' (duration: 300.930771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:24.500975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:08:24.199912Z","time spent":"301.056793ms","remote":"127.0.0.1:45908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-15T04:08:24.50097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.928385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:08:24.501045Z","caller":"traceutil/trace.go:171","msg":"trace[1399517352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26104; }","duration":"133.044368ms","start":"2026-07-15T04:08:24.367981Z","end":"2026-07-15T04:08:24.501025Z","steps":["trace[1399517352] 'agreement among raft nodes before linearized reading' (duration: 132.914696ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:24.906307Z","caller":"traceutil/trace.go:171","msg":"trace[1888320502] transaction","detail":"{read_only:false; response_revision:26105; number_of_response:1; }","duration":"254.857583ms","start":"2026-07-15T04:08:24.651433Z","end":"2026-07-15T04:08:24.90629Z","steps":["trace[1888320502] 'process raft request' (duration: 254.70629ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:24.918383Z","caller":"traceutil/trace.go:171","msg":"trace[133480270] linearizableReadLoop","detail":"{readStateIndex:26941; appliedIndex:26939; }","duration":"145.14348ms","start":"2026-07-15T04:08:24.773221Z","end":"2026-07-15T04:08:24.918364Z","steps":["trace[133480270] 'read index received' (duration: 132.917627ms)","trace[133480270] 'applied index is now lower than readState.Index' (duration: 12.224333ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:08:24.918458Z","caller":"traceutil/trace.go:171","msg":"trace[963269358] transaction","detail":"{read_only:false; response_revision:26106; number_of_response:1; }","duration":"168.77002ms","start":"2026-07-15T04:08:24.749669Z","end":"2026-07-15T04:08:24.918439Z","steps":["trace[963269358] 'process raft request' (duration: 168.520065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:24.918605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.366604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T04:08:24.918595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.746941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:08:24.918633Z","caller":"traceutil/trace.go:171","msg":"trace[163271686] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:26107; }","duration":"145.444195ms","start":"2026-07-15T04:08:24.773178Z","end":"2026-07-15T04:08:24.918622Z","steps":["trace[163271686] 'agreement among raft nodes before linearized reading' (duration: 145.381674ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:24.918666Z","caller":"traceutil/trace.go:171","msg":"trace[1407551422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26107; }","duration":"133.853643ms","start":"2026-07-15T04:08:24.784795Z","end":"2026-07-15T04:08:24.918648Z","steps":["trace[1407551422] 'agreement among raft nodes before linearized reading' (duration: 133.7154ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:25.067556Z","caller":"traceutil/trace.go:171","msg":"trace[1035158095] transaction","detail":"{read_only:false; response_revision:26108; number_of_response:1; }","duration":"145.380694ms","start":"2026-07-15T04:08:24.922146Z","end":"2026-07-15T04:08:25.067527Z","steps":["trace[1035158095] 'process raft request' (duration: 145.202529ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:25.068337Z","caller":"traceutil/trace.go:171","msg":"trace[2019586174] transaction","detail":"{read_only:false; response_revision:26110; number_of_response:1; }","duration":"139.765957ms","start":"2026-07-15T04:08:24.928557Z","end":"2026-07-15T04:08:25.068323Z","steps":["trace[2019586174] 'process raft request' (duration: 139.604214ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:25.068384Z","caller":"traceutil/trace.go:171","msg":"trace[1899942290] transaction","detail":"{read_only:false; response_revision:26111; number_of_response:1; }","duration":"139.756296ms","start":"2026-07-15T04:08:24.928609Z","end":"2026-07-15T04:08:25.068366Z","steps":["trace[1899942290] 'process raft request' (duration: 139.583343ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:25.068426Z","caller":"traceutil/trace.go:171","msg":"trace[1686950860] transaction","detail":"{read_only:false; response_revision:26109; number_of_response:1; }","duration":"139.95739ms","start":"2026-07-15T04:08:24.928443Z","end":"2026-07-15T04:08:25.068401Z","steps":["trace[1686950860] 'process raft request' (duration: 139.612764ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:28.649959Z","caller":"traceutil/trace.go:171","msg":"trace[1341369401] transaction","detail":"{read_only:false; response_revision:26136; number_of_response:1; }","duration":"132.398466ms","start":"2026-07-15T04:08:28.517526Z","end":"2026-07-15T04:08:28.649925Z","steps":["trace[1341369401] 'process raft request' (duration: 64.744955ms)","trace[1341369401] 'compare' (duration: 67.366255ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:08:29.167597Z","caller":"traceutil/trace.go:171","msg":"trace[1579132723] transaction","detail":"{read_only:false; response_revision:26137; number_of_response:1; }","duration":"231.090478ms","start":"2026-07-15T04:08:28.936455Z","end":"2026-07-15T04:08:29.167546Z","steps":["trace[1579132723] 'process raft request' (duration: 230.787542ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:29.195781Z","caller":"traceutil/trace.go:171","msg":"trace[24774823] linearizableReadLoop","detail":"{readStateIndex:26974; appliedIndex:26972; }","duration":"133.579699ms","start":"2026-07-15T04:08:29.062133Z","end":"2026-07-15T04:08:29.195713Z","steps":["trace[24774823] 'read index received' (duration: 105.038635ms)","trace[24774823] 'applied index is now lower than readState.Index' (duration: 28.539724ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:08:29.195912Z","caller":"traceutil/trace.go:171","msg":"trace[1816449498] transaction","detail":"{read_only:false; response_revision:26138; number_of_response:1; }","duration":"257.40236ms","start":"2026-07-15T04:08:28.938498Z","end":"2026-07-15T04:08:29.1959Z","steps":["trace[1816449498] 'process raft request' (duration: 257.057004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:29.196222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.819302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-07-15T04:08:29.196329Z","caller":"traceutil/trace.go:171","msg":"trace[382113655] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:26138; }","duration":"112.940014ms","start":"2026-07-15T04:08:29.083358Z","end":"2026-07-15T04:08:29.196298Z","steps":["trace[382113655] 'agreement among raft nodes before linearized reading' (duration: 112.664219ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:29.196373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.944727ms","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-07-15T04:08:29.19643Z","caller":"traceutil/trace.go:171","msg":"trace[311346274] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26138; }","duration":"110.024069ms","start":"2026-07-15T04:08:29.086385Z","end":"2026-07-15T04:08:29.196409Z","steps":["trace[311346274] 'agreement among raft nodes before linearized reading' (duration: 109.863666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:29.196579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.207532ms","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-07-15T04:08:29.196597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.462685ms","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-07-15T04:08:29.196625Z","caller":"traceutil/trace.go:171","msg":"trace[874566715] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:26138; }","duration":"134.540377ms","start":"2026-07-15T04:08:29.062078Z","end":"2026-07-15T04:08:29.196618Z","steps":["trace[874566715] 'agreement among raft nodes before linearized reading' (duration: 134.471896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:29.196723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.742902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-15T04:08:29.196766Z","caller":"traceutil/trace.go:171","msg":"trace[1184871242] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26138; }","duration":"111.810223ms","start":"2026-07-15T04:08:29.084947Z","end":"2026-07-15T04:08:29.196757Z","steps":["trace[1184871242] 'agreement among raft nodes before linearized reading' (duration: 111.710832ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:29.196621Z","caller":"traceutil/trace.go:171","msg":"trace[1979457895] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26138; }","duration":"110.271584ms","start":"2026-07-15T04:08:29.086339Z","end":"2026-07-15T04:08:29.19661Z","steps":["trace[1979457895] 'agreement among raft nodes before linearized reading' (duration: 110.165182ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:29.502263Z","caller":"traceutil/trace.go:171","msg":"trace[1170759305] transaction","detail":"{read_only:false; response_revision:26144; number_of_response:1; }","duration":"112.070638ms","start":"2026-07-15T04:08:29.390164Z","end":"2026-07-15T04:08:29.502235Z","steps":["trace[1170759305] 'process raft request' (duration: 111.896855ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:29.695237Z","caller":"traceutil/trace.go:171","msg":"trace[1015590098] linearizableReadLoop","detail":"{readStateIndex:26981; appliedIndex:26980; }","duration":"101.408745ms","start":"2026-07-15T04:08:29.593803Z","end":"2026-07-15T04:08:29.695212Z","steps":["trace[1015590098] 'read index received' (duration: 96.533872ms)","trace[1015590098] 'applied index is now lower than readState.Index' (duration: 4.873673ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:08:29.695342Z","caller":"traceutil/trace.go:171","msg":"trace[219893721] transaction","detail":"{read_only:false; response_revision:26145; number_of_response:1; }","duration":"119.859847ms","start":"2026-07-15T04:08:29.575453Z","end":"2026-07-15T04:08:29.695313Z","steps":["trace[219893721] 'process raft request' (duration: 114.867681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:29.695374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.555158ms","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-07-15T04:08:29.695424Z","caller":"traceutil/trace.go:171","msg":"trace[161293877] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26145; }","duration":"101.6543ms","start":"2026-07-15T04:08:29.593754Z","end":"2026-07-15T04:08:29.695408Z","steps":["trace[161293877] 'agreement among raft nodes before linearized reading' (duration: 101.560179ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:29.92267Z","caller":"traceutil/trace.go:171","msg":"trace[1021138388] transaction","detail":"{read_only:false; response_revision:26147; number_of_response:1; }","duration":"106.680755ms","start":"2026-07-15T04:08:29.815963Z","end":"2026-07-15T04:08:29.922643Z","steps":["trace[1021138388] 'process raft request' (duration: 99.187302ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:29.958258Z","caller":"traceutil/trace.go:171","msg":"trace[1544444270] transaction","detail":"{read_only:false; response_revision:26148; number_of_response:1; }","duration":"131.52855ms","start":"2026-07-15T04:08:29.826716Z","end":"2026-07-15T04:08:29.958244Z","steps":["trace[1544444270] 'process raft request' (duration: 131.177353ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:30.811043Z","caller":"traceutil/trace.go:171","msg":"trace[2083185076] transaction","detail":"{read_only:false; response_revision:26151; number_of_response:1; }","duration":"104.561916ms","start":"2026-07-15T04:08:30.706448Z","end":"2026-07-15T04:08:30.811009Z","steps":["trace[2083185076] 'process raft request' (duration: 104.375392ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:31.324912Z","caller":"traceutil/trace.go:171","msg":"trace[747047313] transaction","detail":"{read_only:false; response_revision:26152; number_of_response:1; }","duration":"151.819107ms","start":"2026-07-15T04:08:31.173075Z","end":"2026-07-15T04:08:31.324894Z","steps":["trace[747047313] 'process raft request' (duration: 151.684284ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:31.361055Z","caller":"traceutil/trace.go:171","msg":"trace[1533249864] linearizableReadLoop","detail":"{readStateIndex:26990; appliedIndex:26988; }","duration":"132.081399ms","start":"2026-07-15T04:08:31.228957Z","end":"2026-07-15T04:08:31.361038Z","steps":["trace[1533249864] 'read index received' (duration: 95.791467ms)","trace[1533249864] 'applied index is now lower than readState.Index' (duration: 36.289372ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:08:31.36174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.768632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-15T04:08:31.361779Z","caller":"traceutil/trace.go:171","msg":"trace[1801531] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26153; }","duration":"132.847754ms","start":"2026-07-15T04:08:31.228921Z","end":"2026-07-15T04:08:31.361768Z","steps":["trace[1801531] 'agreement among raft nodes before linearized reading' (duration: 132.752512ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:31.361919Z","caller":"traceutil/trace.go:171","msg":"trace[1771126701] transaction","detail":"{read_only:false; response_revision:26153; number_of_response:1; }","duration":"158.762207ms","start":"2026-07-15T04:08:31.203099Z","end":"2026-07-15T04:08:31.361861Z","steps":["trace[1771126701] 'process raft request' (duration: 157.83575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:31.624985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.601597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T04:08:31.625143Z","caller":"traceutil/trace.go:171","msg":"trace[1473368378] transaction","detail":"{read_only:false; response_revision:26154; number_of_response:1; }","duration":"259.876377ms","start":"2026-07-15T04:08:31.365239Z","end":"2026-07-15T04:08:31.625115Z","steps":["trace[1473368378] 'process raft request' (duration: 113.060306ms)","trace[1473368378] 'compare' (duration: 146.476074ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:08:31.653766Z","caller":"traceutil/trace.go:171","msg":"trace[23197366] transaction","detail":"{read_only:false; response_revision:26157; number_of_response:1; }","duration":"287.977304ms","start":"2026-07-15T04:08:31.365765Z","end":"2026-07-15T04:08:31.653742Z","steps":["trace[23197366] 'process raft request' (duration: 287.905992ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:31.653813Z","caller":"traceutil/trace.go:171","msg":"trace[1362297432] transaction","detail":"{read_only:false; response_revision:26156; number_of_response:1; }","duration":"288.170047ms","start":"2026-07-15T04:08:31.365612Z","end":"2026-07-15T04:08:31.653782Z","steps":["trace[1362297432] 'process raft request' (duration: 288.017385ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:31.654033Z","caller":"traceutil/trace.go:171","msg":"trace[908885568] transaction","detail":"{read_only:false; response_revision:26158; number_of_response:1; }","duration":"287.7697ms","start":"2026-07-15T04:08:31.366213Z","end":"2026-07-15T04:08:31.653983Z","steps":["trace[908885568] 'process raft request' (duration: 287.495455ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:31.654239Z","caller":"traceutil/trace.go:171","msg":"trace[900780825] transaction","detail":"{read_only:false; response_revision:26155; number_of_response:1; }","duration":"288.667937ms","start":"2026-07-15T04:08:31.365553Z","end":"2026-07-15T04:08:31.654221Z","steps":["trace[900780825] 'process raft request' (duration: 287.941413ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:31.710982Z","caller":"traceutil/trace.go:171","msg":"trace[1638991962] linearizableReadLoop","detail":"{readStateIndex:26997; appliedIndex:26991; }","duration":"103.401333ms","start":"2026-07-15T04:08:31.607546Z","end":"2026-07-15T04:08:31.710947Z","steps":["trace[1638991962] 'read index received' (duration: 46.051519ms)","trace[1638991962] 'applied index is now lower than readState.Index' (duration: 57.348324ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:08:31.711031Z","caller":"traceutil/trace.go:171","msg":"trace[1505961593] transaction","detail":"{read_only:false; response_revision:26159; number_of_response:1; }","duration":"203.665845ms","start":"2026-07-15T04:08:31.507324Z","end":"2026-07-15T04:08:31.71099Z","steps":["trace[1505961593] 'process raft request' (duration: 203.244357ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:31.711085Z","caller":"traceutil/trace.go:171","msg":"trace[1484135958] transaction","detail":"{read_only:false; response_revision:26160; number_of_response:1; }","duration":"142.999078ms","start":"2026-07-15T04:08:31.56803Z","end":"2026-07-15T04:08:31.711029Z","steps":["trace[1484135958] 'process raft request' (duration: 142.843555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:31.711188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.606076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T04:08:31.711243Z","caller":"traceutil/trace.go:171","msg":"trace[1177111208] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:26160; }","duration":"103.700778ms","start":"2026-07-15T04:08:31.607529Z","end":"2026-07-15T04:08:31.711229Z","steps":["trace[1177111208] 'agreement among raft nodes before linearized reading' (duration: 103.563645ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:32.319818Z","caller":"traceutil/trace.go:171","msg":"trace[1868878773] linearizableReadLoop","detail":"{readStateIndex:27000; appliedIndex:26999; }","duration":"144.520366ms","start":"2026-07-15T04:08:32.175277Z","end":"2026-07-15T04:08:32.319798Z","steps":["trace[1868878773] 'read index received' (duration: 144.397284ms)","trace[1868878773] 'applied index is now lower than readState.Index' (duration: 121.892µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:08:32.319945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.646998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:08:32.319914Z","caller":"traceutil/trace.go:171","msg":"trace[1349119225] transaction","detail":"{read_only:false; response_revision:26163; number_of_response:1; }","duration":"353.415742ms","start":"2026-07-15T04:08:31.966465Z","end":"2026-07-15T04:08:32.319881Z","steps":["trace[1349119225] 'process raft request' (duration: 353.187747ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:32.319969Z","caller":"traceutil/trace.go:171","msg":"trace[129014019] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:26163; }","duration":"144.71845ms","start":"2026-07-15T04:08:32.175243Z","end":"2026-07-15T04:08:32.319961Z","steps":["trace[129014019] 'agreement among raft nodes before linearized reading' (duration: 144.631888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:32.320074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:08:31.966443Z","time spent":"353.544905ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:08:32.600512Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22669} {"level":"info","ts":"2026-07-15T04:08:32.600619Z","caller":"traceutil/trace.go:171","msg":"trace[1597471787] compact","detail":"{revision:22669; response_revision:26164; }","duration":"135.829361ms","start":"2026-07-15T04:08:32.464731Z","end":"2026-07-15T04:08:32.60056Z","steps":["trace[1597471787] 'process raft request' (duration: 85.304007ms)","trace[1597471787] 'check and update compact revision' (duration: 50.237308ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:08:32.950606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.698062ms","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-07-15T04:08:32.950681Z","caller":"traceutil/trace.go:171","msg":"trace[1330098378] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26165; }","duration":"132.838225ms","start":"2026-07-15T04:08:32.817822Z","end":"2026-07-15T04:08:32.95066Z","steps":["trace[1330098378] 'count revisions from in-memory index tree' (duration: 132.621641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:32.950854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.2957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-15T04:08:32.950916Z","caller":"traceutil/trace.go:171","msg":"trace[1630077814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26165; }","duration":"136.397703ms","start":"2026-07-15T04:08:32.814503Z","end":"2026-07-15T04:08:32.950901Z","steps":["trace[1630077814] 'range keys from in-memory index tree' (duration: 136.126797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:33.112123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.17982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T04:08:33.112208Z","caller":"traceutil/trace.go:171","msg":"trace[1179618108] transaction","detail":"{read_only:false; response_revision:26166; number_of_response:1; }","duration":"157.27749ms","start":"2026-07-15T04:08:32.954912Z","end":"2026-07-15T04:08:33.11219Z","steps":["trace[1179618108] 'process raft request' (duration: 44.964267ms)","trace[1179618108] 'compare' (duration: 77.043671ms)","trace[1179618108] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:420; } (duration: 34.692861ms)"],"step_count":3} {"level":"info","ts":"2026-07-15T04:08:33.328247Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22669,"took":"723.245677ms","hash":2254561488,"current-db-size-bytes":58359808,"current-db-size":"58 MB","current-db-size-in-use-bytes":46882816,"current-db-size-in-use":"47 MB"} {"level":"info","ts":"2026-07-15T04:08:33.328327Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2254561488,"revision":22669,"compact-revision":20236} {"level":"info","ts":"2026-07-15T04:08:33.94881Z","caller":"traceutil/trace.go:171","msg":"trace[947167607] transaction","detail":"{read_only:false; response_revision:26169; number_of_response:1; }","duration":"273.881535ms","start":"2026-07-15T04:08:33.674904Z","end":"2026-07-15T04:08:33.948786Z","steps":["trace[947167607] 'process raft request' (duration: 273.732622ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:34.097963Z","caller":"traceutil/trace.go:171","msg":"trace[1899215139] transaction","detail":"{read_only:false; response_revision:26170; number_of_response:1; }","duration":"422.48211ms","start":"2026-07-15T04:08:33.675465Z","end":"2026-07-15T04:08:34.097947Z","steps":["trace[1899215139] 'process raft request' (duration: 383.76326ms)","trace[1899215139] 'compare' (duration: 38.474245ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:08:34.098068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:08:33.67544Z","time spent":"422.578262ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:08:34.098161Z","caller":"traceutil/trace.go:171","msg":"trace[486267828] transaction","detail":"{read_only:false; response_revision:26171; number_of_response:1; }","duration":"422.628172ms","start":"2026-07-15T04:08:33.675508Z","end":"2026-07-15T04:08:34.098136Z","steps":["trace[486267828] 'process raft request' (duration: 422.408458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:34.098298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:08:33.675471Z","time spent":"422.752225ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:08:34.098538Z","caller":"traceutil/trace.go:171","msg":"trace[374073096] transaction","detail":"{read_only:false; response_revision:26172; number_of_response:1; }","duration":"422.984959ms","start":"2026-07-15T04:08:33.675531Z","end":"2026-07-15T04:08:34.098516Z","steps":["trace[374073096] 'process raft request' (duration: 422.51158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:34.098685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:08:33.675521Z","time spent":"423.088091ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:08:34.09889Z","caller":"traceutil/trace.go:171","msg":"trace[1472304213] linearizableReadLoop","detail":"{readStateIndex:27011; appliedIndex:27006; }","duration":"397.438341ms","start":"2026-07-15T04:08:33.70144Z","end":"2026-07-15T04:08:34.098879Z","steps":["trace[1472304213] 'read index received' (duration: 247.256666ms)","trace[1472304213] 'applied index is now lower than readState.Index' (duration: 150.180875ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:08:34.098902Z","caller":"traceutil/trace.go:171","msg":"trace[1630001754] transaction","detail":"{read_only:false; response_revision:26174; number_of_response:1; }","duration":"377.934639ms","start":"2026-07-15T04:08:33.720948Z","end":"2026-07-15T04:08:34.098883Z","steps":["trace[1630001754] 'process raft request' (duration: 377.858178ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:34.098901Z","caller":"traceutil/trace.go:171","msg":"trace[1254872094] transaction","detail":"{read_only:false; response_revision:26173; number_of_response:1; }","duration":"423.301835ms","start":"2026-07-15T04:08:33.675563Z","end":"2026-07-15T04:08:34.098865Z","steps":["trace[1254872094] 'process raft request' (duration: 422.878477ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:34.098926Z","caller":"traceutil/trace.go:171","msg":"trace[1401611195] transaction","detail":"{read_only:false; response_revision:26175; number_of_response:1; }","duration":"377.693925ms","start":"2026-07-15T04:08:33.721219Z","end":"2026-07-15T04:08:34.098913Z","steps":["trace[1401611195] 'process raft request' (duration: 377.628673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:34.098983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:08:33.721202Z","time spent":"377.755296ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T04:08:34.098996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.559183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-15T04:08:34.098993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:08:33.720909Z","time spent":"378.040141ms","remote":"127.0.0.1:45970","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-07-15T04:08:34.099005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:08:33.675555Z","time spent":"423.402088ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:08:34.09903Z","caller":"traceutil/trace.go:171","msg":"trace[1967455946] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:26175; }","duration":"397.638235ms","start":"2026-07-15T04:08:33.701379Z","end":"2026-07-15T04:08:34.099018Z","steps":["trace[1967455946] 'agreement among raft nodes before linearized reading' (duration: 397.565763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:34.099058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:08:33.701354Z","time spent":"397.695937ms","remote":"127.0.0.1:54790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":9,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-07-15T04:08:34.10465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.528602ms","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-07-15T04:08:34.104716Z","caller":"traceutil/trace.go:171","msg":"trace[229831249] 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:26176; }","duration":"280.656445ms","start":"2026-07-15T04:08:33.824044Z","end":"2026-07-15T04:08:34.104701Z","steps":["trace[229831249] 'agreement among raft nodes before linearized reading' (duration: 280.509121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:50.641281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.846914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:08:50.641407Z","caller":"traceutil/trace.go:171","msg":"trace[993575639] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:26306; }","duration":"101.012457ms","start":"2026-07-15T04:08:50.540362Z","end":"2026-07-15T04:08:50.641374Z","steps":["trace[993575639] 'count revisions from in-memory index tree' (duration: 100.63367ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:56.084968Z","caller":"traceutil/trace.go:171","msg":"trace[710032187] transaction","detail":"{read_only:false; response_revision:26343; number_of_response:1; }","duration":"169.33441ms","start":"2026-07-15T04:08:55.915596Z","end":"2026-07-15T04:08:56.08493Z","steps":["trace[710032187] 'process raft request' (duration: 169.081126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:56.258748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.691864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T04:08:56.258858Z","caller":"traceutil/trace.go:171","msg":"trace[2053719275] transaction","detail":"{read_only:false; response_revision:26344; number_of_response:1; }","duration":"187.282483ms","start":"2026-07-15T04:08:56.071557Z","end":"2026-07-15T04:08:56.258839Z","steps":["trace[2053719275] 'process raft request' (duration: 57.419336ms)","trace[2053719275] 'compare' (duration: 129.563561ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:08:56.268657Z","caller":"traceutil/trace.go:171","msg":"trace[1658452573] linearizableReadLoop","detail":"{readStateIndex:27188; appliedIndex:27186; }","duration":"167.977454ms","start":"2026-07-15T04:08:56.10066Z","end":"2026-07-15T04:08:56.268637Z","steps":["trace[1658452573] 'read index received' (duration: 28.456944ms)","trace[1658452573] 'applied index is now lower than readState.Index' (duration: 139.51969ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:08:56.268758Z","caller":"traceutil/trace.go:171","msg":"trace[1617818912] transaction","detail":"{read_only:false; response_revision:26345; number_of_response:1; }","duration":"179.513484ms","start":"2026-07-15T04:08:56.089215Z","end":"2026-07-15T04:08:56.268729Z","steps":["trace[1617818912] 'process raft request' (duration: 179.321411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:56.268824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.156057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T04:08:56.268862Z","caller":"traceutil/trace.go:171","msg":"trace[1630858449] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:26345; }","duration":"168.239519ms","start":"2026-07-15T04:08:56.100611Z","end":"2026-07-15T04:08:56.26885Z","steps":["trace[1630858449] 'agreement among raft nodes before linearized reading' (duration: 168.136988ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:56.583259Z","caller":"traceutil/trace.go:171","msg":"trace[659711283] transaction","detail":"{read_only:false; response_revision:26346; number_of_response:1; }","duration":"407.091737ms","start":"2026-07-15T04:08:56.176148Z","end":"2026-07-15T04:08:56.583239Z","steps":["trace[659711283] 'process raft request' (duration: 406.885413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:56.583517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:08:56.176121Z","time spent":"407.20882ms","remote":"127.0.0.1:45970","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-07-15T04:08:56.583551Z","caller":"traceutil/trace.go:171","msg":"trace[701213755] transaction","detail":"{read_only:false; response_revision:26347; number_of_response:1; }","duration":"403.930615ms","start":"2026-07-15T04:08:56.179599Z","end":"2026-07-15T04:08:56.58353Z","steps":["trace[701213755] 'process raft request' (duration: 403.599849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:56.583666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:08:56.179573Z","time spent":"404.027448ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:08:56.583709Z","caller":"traceutil/trace.go:171","msg":"trace[1245644953] linearizableReadLoop","detail":"{readStateIndex:27191; appliedIndex:27188; }","duration":"314.887017ms","start":"2026-07-15T04:08:56.268708Z","end":"2026-07-15T04:08:56.583595Z","steps":["trace[1245644953] 'read index received' (duration: 314.369288ms)","trace[1245644953] 'applied index is now lower than readState.Index' (duration: 516.849µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:08:56.583854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.643398ms","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-07-15T04:08:56.583883Z","caller":"traceutil/trace.go:171","msg":"trace[967652232] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26348; }","duration":"372.69088ms","start":"2026-07-15T04:08:56.211184Z","end":"2026-07-15T04:08:56.583875Z","steps":["trace[967652232] 'agreement among raft nodes before linearized reading' (duration: 372.594798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:56.583903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:08:56.211174Z","time spent":"372.72465ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-15T04:08:56.583944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.940534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-15T04:08:56.583985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.068778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-07-15T04:08:56.584024Z","caller":"traceutil/trace.go:171","msg":"trace[1578484441] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26348; }","duration":"373.093027ms","start":"2026-07-15T04:08:56.210906Z","end":"2026-07-15T04:08:56.583999Z","steps":["trace[1578484441] 'agreement among raft nodes before linearized reading' (duration: 372.911874ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:56.584039Z","caller":"traceutil/trace.go:171","msg":"trace[695019805] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:26348; }","duration":"372.21965ms","start":"2026-07-15T04:08:56.211801Z","end":"2026-07-15T04:08:56.584021Z","steps":["trace[695019805] 'agreement among raft nodes before linearized reading' (duration: 371.992026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:56.584055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:08:56.210889Z","time spent":"373.157828ms","remote":"127.0.0.1:45970","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":"info","ts":"2026-07-15T04:08:56.584074Z","caller":"traceutil/trace.go:171","msg":"trace[2072455779] transaction","detail":"{read_only:false; response_revision:26348; number_of_response:1; }","duration":"392.406326ms","start":"2026-07-15T04:08:56.191637Z","end":"2026-07-15T04:08:56.584044Z","steps":["trace[2072455779] 'process raft request' (duration: 391.775454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:56.584082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:08:56.211784Z","time spent":"372.281482ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-15T04:08:56.584148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:08:56.191611Z","time spent":"392.497987ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T04:08:56.585057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.417807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-15T04:08:56.585158Z","caller":"traceutil/trace.go:171","msg":"trace[1709158960] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26348; }","duration":"369.539919ms","start":"2026-07-15T04:08:56.215585Z","end":"2026-07-15T04:08:56.585125Z","steps":["trace[1709158960] 'agreement among raft nodes before linearized reading' (duration: 368.024961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:56.585213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:08:56.215574Z","time spent":"369.629572ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-15T04:08:56.585707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.683999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:186 size:1713255"} {"level":"info","ts":"2026-07-15T04:08:56.585788Z","caller":"traceutil/trace.go:171","msg":"trace[1012314724] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:186; response_revision:26348; }","duration":"482.7837ms","start":"2026-07-15T04:08:56.102986Z","end":"2026-07-15T04:08:56.58577Z","steps":["trace[1012314724] 'agreement among raft nodes before linearized reading' (duration: 481.030686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:56.58585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:08:56.102979Z","time spent":"482.850541ms","remote":"127.0.0.1:45834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":186,"response size":1713277,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"warn","ts":"2026-07-15T04:08:56.798134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.15322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T04:08:56.798355Z","caller":"traceutil/trace.go:171","msg":"trace[2041262692] linearizableReadLoop","detail":"{readStateIndex:27192; appliedIndex:27191; }","duration":"214.540462ms","start":"2026-07-15T04:08:56.583792Z","end":"2026-07-15T04:08:56.798332Z","steps":["trace[2041262692] 'read index received' (duration: 81.095337ms)","trace[2041262692] 'applied index is now lower than readState.Index' (duration: 133.442205ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:08:56.798517Z","caller":"traceutil/trace.go:171","msg":"trace[1071250950] transaction","detail":"{read_only:false; response_revision:26349; number_of_response:1; }","duration":"311.88193ms","start":"2026-07-15T04:08:56.486615Z","end":"2026-07-15T04:08:56.798497Z","steps":["trace[1071250950] 'process raft request' (duration: 178.262881ms)","trace[1071250950] 'compare' (duration: 132.952006ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:08:56.798578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.980959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:08:56.79866Z","caller":"traceutil/trace.go:171","msg":"trace[1970354603] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:26349; }","duration":"250.117092ms","start":"2026-07-15T04:08:56.548523Z","end":"2026-07-15T04:08:56.79864Z","steps":["trace[1970354603] 'agreement among raft nodes before linearized reading' (duration: 249.971159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:56.79863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.400852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:08:56.798737Z","caller":"traceutil/trace.go:171","msg":"trace[63818226] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:26349; }","duration":"462.605305ms","start":"2026-07-15T04:08:56.336103Z","end":"2026-07-15T04:08:56.798709Z","steps":["trace[63818226] 'agreement among raft nodes before linearized reading' (duration: 462.393291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:08:56.798831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:08:56.486588Z","time spent":"312.031114ms","remote":"127.0.0.1:45970","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-07-15T04:08:56.798809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:08:56.336083Z","time spent":"462.708298ms","remote":"127.0.0.1:34512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true "} {"level":"info","ts":"2026-07-15T04:08:56.806573Z","caller":"traceutil/trace.go:171","msg":"trace[576091618] transaction","detail":"{read_only:false; response_revision:26350; number_of_response:1; }","duration":"217.961427ms","start":"2026-07-15T04:08:56.588587Z","end":"2026-07-15T04:08:56.806548Z","steps":["trace[576091618] 'process raft request' (duration: 217.759303ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:56.806682Z","caller":"traceutil/trace.go:171","msg":"trace[231294906] transaction","detail":"{read_only:false; response_revision:26352; number_of_response:1; }","duration":"217.58186ms","start":"2026-07-15T04:08:56.589076Z","end":"2026-07-15T04:08:56.806658Z","steps":["trace[231294906] 'process raft request' (duration: 217.411507ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:56.806721Z","caller":"traceutil/trace.go:171","msg":"trace[1057526592] transaction","detail":"{read_only:false; response_revision:26351; number_of_response:1; }","duration":"217.630781ms","start":"2026-07-15T04:08:56.589068Z","end":"2026-07-15T04:08:56.806699Z","steps":["trace[1057526592] 'process raft request' (duration: 217.381186ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:56.806838Z","caller":"traceutil/trace.go:171","msg":"trace[139798063] transaction","detail":"{read_only:false; response_revision:26353; number_of_response:1; }","duration":"217.59738ms","start":"2026-07-15T04:08:56.589212Z","end":"2026-07-15T04:08:56.80681Z","steps":["trace[139798063] 'process raft request' (duration: 217.314875ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:08:57.028307Z","caller":"traceutil/trace.go:171","msg":"trace[733488701] transaction","detail":"{read_only:false; response_revision:26354; number_of_response:1; }","duration":"217.794955ms","start":"2026-07-15T04:08:56.81048Z","end":"2026-07-15T04:08:57.028274Z","steps":["trace[733488701] 'process raft request' (duration: 142.339436ms)","trace[733488701] 'compare' (duration: 75.215964ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:08:57.724615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.204487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T04:08:57.724768Z","caller":"traceutil/trace.go:171","msg":"trace[2139497325] transaction","detail":"{read_only:false; response_revision:26358; number_of_response:1; }","duration":"189.425893ms","start":"2026-07-15T04:08:57.535314Z","end":"2026-07-15T04:08:57.72474Z","steps":["trace[2139497325] 'process raft request' (duration: 83.998702ms)","trace[2139497325] 'compare' (duration: 105.067454ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:13:32.607121Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26164} {"level":"info","ts":"2026-07-15T04:13:32.770286Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26164,"took":"158.819936ms","hash":1080397598,"current-db-size-bytes":59203584,"current-db-size":"59 MB","current-db-size-in-use-bytes":36982784,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-15T04:13:32.770389Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1080397598,"revision":26164,"compact-revision":22669} {"level":"info","ts":"2026-07-15T04:13:35.446876Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f170c75a1d97cc","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T04:13:35.450994Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-15T04:13:35.451082Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-15T04:18:32.619217Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29075} {"level":"info","ts":"2026-07-15T04:18:32.768858Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29075,"took":"144.805203ms","hash":276112755,"current-db-size-bytes":59203584,"current-db-size":"59 MB","current-db-size-in-use-bytes":31703040,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T04:18:32.768951Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":276112755,"revision":29075,"compact-revision":26164} {"level":"warn","ts":"2026-07-15T04:18:44.825334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.68395ms","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-07-15T04:18:44.825408Z","caller":"traceutil/trace.go:171","msg":"trace[1245638125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:31417; }","duration":"611.788552ms","start":"2026-07-15T04:18:44.213602Z","end":"2026-07-15T04:18:44.82539Z","steps":["trace[1245638125] 'range keys from in-memory index tree' (duration: 611.552418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:18:44.825452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:18:44.213587Z","time spent":"611.851274ms","remote":"127.0.0.1:45970","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-07-15T04:18:44.825716Z","caller":"traceutil/trace.go:171","msg":"trace[353861246] transaction","detail":"{read_only:false; response_revision:31418; number_of_response:1; }","duration":"142.850945ms","start":"2026-07-15T04:18:44.682844Z","end":"2026-07-15T04:18:44.825695Z","steps":["trace[353861246] 'process raft request' (duration: 119.633963ms)","trace[353861246] 'compare' (duration: 22.869876ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:18:44.825906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.170556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/podmonitors.monitoring.coreos.com\" ","response":"range_response_count:1 size:25393"} {"level":"info","ts":"2026-07-15T04:18:44.825941Z","caller":"traceutil/trace.go:171","msg":"trace[2115449707] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/podmonitors.monitoring.coreos.com; range_end:; response_count:1; response_revision:31417; }","duration":"801.218347ms","start":"2026-07-15T04:18:44.024716Z","end":"2026-07-15T04:18:44.825934Z","steps":["trace[2115449707] 'range keys from in-memory index tree' (duration: 800.32455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:18:44.825958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:18:44.024643Z","time spent":"801.311078ms","remote":"127.0.0.1:45784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":25415,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/podmonitors.monitoring.coreos.com\" "} {"level":"info","ts":"2026-07-15T04:18:44.831597Z","caller":"traceutil/trace.go:171","msg":"trace[1343856489] transaction","detail":"{read_only:false; response_revision:31419; number_of_response:1; }","duration":"119.690584ms","start":"2026-07-15T04:18:44.711879Z","end":"2026-07-15T04:18:44.83157Z","steps":["trace[1343856489] 'process raft request' (duration: 119.529491ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:18:44.831771Z","caller":"traceutil/trace.go:171","msg":"trace[916774377] transaction","detail":"{read_only:false; response_revision:31420; number_of_response:1; }","duration":"119.252815ms","start":"2026-07-15T04:18:44.712498Z","end":"2026-07-15T04:18:44.831751Z","steps":["trace[916774377] 'process raft request' (duration: 119.034491ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:21:05.952267Z","caller":"traceutil/trace.go:171","msg":"trace[702899275] linearizableReadLoop","detail":"{readStateIndex:33566; appliedIndex:33565; }","duration":"211.581924ms","start":"2026-07-15T04:21:05.740666Z","end":"2026-07-15T04:21:05.952248Z","steps":["trace[702899275] 'read index received' (duration: 211.472562ms)","trace[702899275] 'applied index is now lower than readState.Index' (duration: 108.132µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:21:05.952375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:21:05.643883Z","time spent":"308.489542ms","remote":"127.0.0.1:45766","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-15T04:21:05.952588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.905941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:21:05.952639Z","caller":"traceutil/trace.go:171","msg":"trace[154906281] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:32557; }","duration":"211.969572ms","start":"2026-07-15T04:21:05.740633Z","end":"2026-07-15T04:21:05.952603Z","steps":["trace[154906281] 'agreement among raft nodes before linearized reading' (duration: 211.909451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:21:05.95281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.937918ms","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-07-15T04:21:05.952825Z","caller":"traceutil/trace.go:171","msg":"trace[335460053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:32557; }","duration":"182.977968ms","start":"2026-07-15T04:21:05.769842Z","end":"2026-07-15T04:21:05.95282Z","steps":["trace[335460053] 'agreement among raft nodes before linearized reading' (duration: 182.903977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:21:05.952966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.67663ms","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-07-15T04:21:05.95298Z","caller":"traceutil/trace.go:171","msg":"trace[1876160053] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:32557; }","duration":"100.70637ms","start":"2026-07-15T04:21:05.852269Z","end":"2026-07-15T04:21:05.952975Z","steps":["trace[1876160053] 'agreement among raft nodes before linearized reading' (duration: 100.666159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:21:05.953056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.298719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:21:05.953068Z","caller":"traceutil/trace.go:171","msg":"trace[619068328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:32557; }","duration":"178.35225ms","start":"2026-07-15T04:21:05.774712Z","end":"2026-07-15T04:21:05.953064Z","steps":["trace[619068328] 'agreement among raft nodes before linearized reading' (duration: 178.326679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:21:06.783755Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938292845890358261,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-15T04:21:06.852599Z","caller":"traceutil/trace.go:171","msg":"trace[1749056239] transaction","detail":"{read_only:false; response_revision:32560; number_of_response:1; }","duration":"781.969309ms","start":"2026-07-15T04:21:06.070609Z","end":"2026-07-15T04:21:06.852578Z","steps":["trace[1749056239] 'process raft request' (duration: 780.091064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:21:06.852715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:21:06.070592Z","time spent":"782.062522ms","remote":"127.0.0.1:45970","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-07-15T04:21:06.85592Z","caller":"traceutil/trace.go:171","msg":"trace[1386070556] linearizableReadLoop","detail":"{readStateIndex:33571; appliedIndex:33568; }","duration":"573.926382ms","start":"2026-07-15T04:21:06.281962Z","end":"2026-07-15T04:21:06.855889Z","steps":["trace[1386070556] 'read index received' (duration: 568.844826ms)","trace[1386070556] 'applied index is now lower than readState.Index' (duration: 5.080766ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:21:06.856253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.43674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-07-15T04:21:06.856241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.254909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T04:21:06.85629Z","caller":"traceutil/trace.go:171","msg":"trace[1081785579] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:32562; }","duration":"535.495201ms","start":"2026-07-15T04:21:06.320786Z","end":"2026-07-15T04:21:06.856281Z","steps":["trace[1081785579] 'agreement among raft nodes before linearized reading' (duration: 535.379919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:21:06.856313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:21:06.32078Z","time spent":"535.527241ms","remote":"127.0.0.1:45970","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-07-15T04:21:06.856324Z","caller":"traceutil/trace.go:171","msg":"trace[624586283] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:32562; }","duration":"574.343591ms","start":"2026-07-15T04:21:06.281958Z","end":"2026-07-15T04:21:06.856302Z","steps":["trace[624586283] 'agreement among raft nodes before linearized reading' (duration: 574.196568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:21:06.856389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:21:06.281934Z","time spent":"574.439632ms","remote":"127.0.0.1:44596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true "} {"level":"info","ts":"2026-07-15T04:21:06.856631Z","caller":"traceutil/trace.go:171","msg":"trace[1707874254] transaction","detail":"{read_only:false; response_revision:32561; number_of_response:1; }","duration":"782.264626ms","start":"2026-07-15T04:21:06.074352Z","end":"2026-07-15T04:21:06.856617Z","steps":["trace[1707874254] 'process raft request' (duration: 781.395969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:21:06.856743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:21:06.074335Z","time spent":"782.333668ms","remote":"127.0.0.1:45970","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-07-15T04:21:06.856922Z","caller":"traceutil/trace.go:171","msg":"trace[2003996744] transaction","detail":"{read_only:false; response_revision:32562; number_of_response:1; }","duration":"597.346179ms","start":"2026-07-15T04:21:06.259562Z","end":"2026-07-15T04:21:06.856909Z","steps":["trace[2003996744] 'process raft request' (duration: 596.296259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:21:06.856999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:21:06.259545Z","time spent":"597.41323ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T04:21:06.857468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.378782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-07-15T04:21:06.857501Z","caller":"traceutil/trace.go:171","msg":"trace[631528329] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:32562; }","duration":"241.438683ms","start":"2026-07-15T04:21:06.616054Z","end":"2026-07-15T04:21:06.857492Z","steps":["trace[631528329] 'agreement among raft nodes before linearized reading' (duration: 241.334151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:21:06.857503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.428456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-15T04:21:06.857547Z","caller":"traceutil/trace.go:171","msg":"trace[217123911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:32562; }","duration":"313.501407ms","start":"2026-07-15T04:21:06.544032Z","end":"2026-07-15T04:21:06.857533Z","steps":["trace[217123911] 'agreement among raft nodes before linearized reading' (duration: 313.387395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:21:06.857599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:21:06.544014Z","time spent":"313.574019ms","remote":"127.0.0.1:45970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-15T04:21:06.859221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.105725ms","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-07-15T04:21:06.859285Z","caller":"traceutil/trace.go:171","msg":"trace[938382948] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:32562; }","duration":"243.178567ms","start":"2026-07-15T04:21:06.616094Z","end":"2026-07-15T04:21:06.859273Z","steps":["trace[938382948] 'agreement among raft nodes before linearized reading' (duration: 241.065016ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:23:32.62666Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31330} {"level":"info","ts":"2026-07-15T04:23:32.759674Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31330,"took":"125.45761ms","hash":987504511,"current-db-size-bytes":59203584,"current-db-size":"59 MB","current-db-size-in-use-bytes":31281152,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T04:23:32.759806Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":987504511,"revision":31330,"compact-revision":29075} {"level":"info","ts":"2026-07-15T04:28:32.635469Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33633} {"level":"info","ts":"2026-07-15T04:28:32.782933Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33633,"took":"142.921243ms","hash":2617062086,"current-db-size-bytes":59203584,"current-db-size":"59 MB","current-db-size-in-use-bytes":32796672,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-15T04:28:32.782981Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2617062086,"revision":33633,"compact-revision":31330} {"level":"info","ts":"2026-07-15T04:33:32.641707Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35965} {"level":"info","ts":"2026-07-15T04:33:32.786936Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35965,"took":"140.570535ms","hash":1489881099,"current-db-size-bytes":60133376,"current-db-size":"60 MB","current-db-size-in-use-bytes":32555008,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-15T04:33:32.787011Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1489881099,"revision":35965,"compact-revision":33633} {"level":"info","ts":"2026-07-15T04:34:44.75834Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f170c75a1d97cc","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T04:34:44.762704Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-15T04:34:44.762779Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}