{"level":"warn","ts":"2026-03-13T23:04:28.019611Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T23:04:28.019704Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115: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.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-03-13T23:04:28.019775Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T23:04:28.019788Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-03-13T23:04:28.019806Z","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-03-13T23:04:28.020474Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-03-13T23:04:28.020555Z","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.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-03-13T23:04:28.093836Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"73.023205ms"} {"level":"info","ts":"2026-03-13T23:04:28.116592Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-03-13T23:04:28.116799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-03-13T23:04:28.11687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-03-13T23:04:28.116893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-13T23:04:28.116949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-03-13T23:04:28.117036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-03-13T23:04:28.132464Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-13T23:04:28.134009Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-13T23:04:28.135308Z","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-03-13T23:04:28.137599Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-13T23:04:28.13775Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-13T23:04:28.137841Z","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-03-13T23:04:28.137963Z","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-03-13T23:04:28.137974Z","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-03-13T23:04:28.138679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-03-13T23:04:28.139493Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-03-13T23:04:28.142808Z","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-03-13T23:04:28.142937Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-03-13T23:04:28.142983Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-03-13T23:04:28.143276Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-13T23:04:28.143336Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-13T23:04:28.917498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-03-13T23:04:28.91757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-13T23:04:28.917608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-03-13T23:04:28.91763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-03-13T23:04:28.917647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-03-13T23:04:28.917664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-03-13T23:04:28.917699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-03-13T23:04:28.918605Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-03-13T23:04:28.91861Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T23:04:28.918659Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T23:04:28.918695Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:04:28.918967Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-13T23:04:28.919129Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-13T23:04:28.919672Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:04:28.919841Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:04:28.91991Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:04:28.921575Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-03-13T23:04:28.921736Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-13T23:04:42.071622Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.079581442s","expected-duration":"1s"} {"level":"info","ts":"2026-03-13T23:04:42.072504Z","caller":"traceutil/trace.go:171","msg":"trace[1822712530] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"1.08060306s","start":"2026-03-13T23:04:40.991866Z","end":"2026-03-13T23:04:42.072469Z","steps":["trace[1822712530] 'process raft request' (duration: 1.080188128s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:04:42.073015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:04:40.991827Z","time spent":"1.080816606s","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:04:42.634787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.230287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-13T23:04:42.634941Z","caller":"traceutil/trace.go:171","msg":"trace[1478074104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:398; }","duration":"243.529195ms","start":"2026-03-13T23:04:42.39138Z","end":"2026-03-13T23:04:42.634909Z","steps":["trace[1478074104] 'range keys from in-memory index tree' (duration: 242.973669ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:04:42.634787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.337972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-13T23:04:42.635176Z","caller":"traceutil/trace.go:171","msg":"trace[1723963396] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:398; }","duration":"242.724243ms","start":"2026-03-13T23:04:42.392309Z","end":"2026-03-13T23:04:42.635033Z","steps":["trace[1723963396] 'range keys from in-memory index tree' (duration: 242.188208ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:04:59.157617Z","caller":"traceutil/trace.go:171","msg":"trace[2113644000] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"395.834862ms","start":"2026-03-13T23:04:58.761756Z","end":"2026-03-13T23:04:59.157591Z","steps":["trace[2113644000] 'process raft request' (duration: 395.643666ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:04:59.157783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:04:58.761732Z","time spent":"395.967055ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:05:00.568475Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.411033673s","expected-duration":"1s"} {"level":"info","ts":"2026-03-13T23:05:00.569091Z","caller":"traceutil/trace.go:171","msg":"trace[1145383904] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:491; }","duration":"1.6560724s","start":"2026-03-13T23:04:58.912985Z","end":"2026-03-13T23:05:00.569058Z","steps":["trace[1145383904] 'read index received' (duration: 244.454732ms)","trace[1145383904] 'applied index is now lower than readState.Index' (duration: 1.411616188s)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:05:00.569208Z","caller":"traceutil/trace.go:171","msg":"trace[1427285669] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"1.807278244s","start":"2026-03-13T23:04:58.761899Z","end":"2026-03-13T23:05:00.569177Z","steps":["trace[1427285669] 'process raft request' (duration: 1.806761612s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:00.569325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.656293455s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-13T23:05:00.56947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.109631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-13T23:05:00.569453Z","caller":"traceutil/trace.go:171","msg":"trace[939022115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:476; }","duration":"1.656527533s","start":"2026-03-13T23:04:58.912903Z","end":"2026-03-13T23:05:00.569431Z","steps":["trace[939022115] 'agreement among raft nodes before linearized reading' (duration: 1.656274986s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:00.569371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:04:58.761862Z","time spent":"1.807414778s","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:05:00.569507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.824304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:05:00.569507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.43287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"warn","ts":"2026-03-13T23:05:00.569545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:04:58.912859Z","time spent":"1.656667447s","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-13T23:05:00.569635Z","caller":"traceutil/trace.go:171","msg":"trace[1415443410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:476; }","duration":"190.02678ms","start":"2026-03-13T23:05:00.379581Z","end":"2026-03-13T23:05:00.569608Z","steps":["trace[1415443410] 'agreement among raft nodes before linearized reading' (duration: 189.827995ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:00.569644Z","caller":"traceutil/trace.go:171","msg":"trace[2090145080] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:476; }","duration":"387.593764ms","start":"2026-03-13T23:05:00.182026Z","end":"2026-03-13T23:05:00.56962Z","steps":["trace[2090145080] 'agreement among raft nodes before linearized reading' (duration: 387.207344ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:00.569517Z","caller":"traceutil/trace.go:171","msg":"trace[1507209977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:476; }","duration":"363.172603ms","start":"2026-03-13T23:05:00.206327Z","end":"2026-03-13T23:05:00.5695Z","steps":["trace[1507209977] 'agreement among raft nodes before linearized reading' (duration: 363.097152ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:00.569703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:00.181946Z","time spent":"387.742898ms","remote":"127.0.0.1:53214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12692,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-03-13T23:05:00.569905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:00.206317Z","time spent":"363.477871ms","remote":"127.0.0.1:53332","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-03-13T23:05:10.474638Z","caller":"traceutil/trace.go:171","msg":"trace[646185129] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"467.447238ms","start":"2026-03-13T23:05:10.007145Z","end":"2026-03-13T23:05:10.474592Z","steps":["trace[646185129] 'process raft request' (duration: 467.110449ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:10.474973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:10.007095Z","time spent":"467.754886ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:05:10.980049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.252542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-13T23:05:10.980116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.975216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:05:10.980188Z","caller":"traceutil/trace.go:171","msg":"trace[597100675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"263.464937ms","start":"2026-03-13T23:05:10.716682Z","end":"2026-03-13T23:05:10.980147Z","steps":["trace[597100675] 'range keys from in-memory index tree' (duration: 262.939174ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:10.980244Z","caller":"traceutil/trace.go:171","msg":"trace[1172051936] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:559; }","duration":"157.195971ms","start":"2026-03-13T23:05:10.82302Z","end":"2026-03-13T23:05:10.980216Z","steps":["trace[1172051936] 'range keys from in-memory index tree' (duration: 156.883253ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:10.980171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.301835ms","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-03-13T23:05:10.98037Z","caller":"traceutil/trace.go:171","msg":"trace[1217043958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:559; }","duration":"318.546451ms","start":"2026-03-13T23:05:10.661793Z","end":"2026-03-13T23:05:10.98034Z","steps":["trace[1217043958] 'range keys from in-memory index tree' (duration: 318.047718ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:10.980434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:10.661763Z","time spent":"318.654053ms","remote":"127.0.0.1:53332","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-03-13T23:05:10.980426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.882999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-13T23:05:10.98055Z","caller":"traceutil/trace.go:171","msg":"trace[1987379062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"362.061866ms","start":"2026-03-13T23:05:10.618457Z","end":"2026-03-13T23:05:10.980519Z","steps":["trace[1987379062] 'range keys from in-memory index tree' (duration: 361.540791ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:10.980605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:10.618428Z","time spent":"362.166188ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-13T23:05:11.161645Z","caller":"traceutil/trace.go:171","msg":"trace[1926038225] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"175.669509ms","start":"2026-03-13T23:05:10.985936Z","end":"2026-03-13T23:05:11.161605Z","steps":["trace[1926038225] 'process raft request' (duration: 175.575686ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:11.161654Z","caller":"traceutil/trace.go:171","msg":"trace[1689375899] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"175.949976ms","start":"2026-03-13T23:05:10.985672Z","end":"2026-03-13T23:05:11.161622Z","steps":["trace[1689375899] 'process raft request' (duration: 129.774213ms)","trace[1689375899] 'compare' (duration: 45.797664ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:05:11.194152Z","caller":"traceutil/trace.go:171","msg":"trace[2114068791] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"100.890397ms","start":"2026-03-13T23:05:11.093247Z","end":"2026-03-13T23:05:11.194137Z","steps":["trace[2114068791] 'process raft request' (duration: 100.744143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:11.910229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.369699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:05:11.910472Z","caller":"traceutil/trace.go:171","msg":"trace[1861209912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"531.843482ms","start":"2026-03-13T23:05:11.37849Z","end":"2026-03-13T23:05:11.910334Z","steps":["trace[1861209912] 'range keys from in-memory index tree' (duration: 531.220356ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:11.910595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:11.378476Z","time spent":"532.101688ms","remote":"127.0.0.1:53098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-13T23:05:11.91024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.218786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-13T23:05:11.910879Z","caller":"traceutil/trace.go:171","msg":"trace[338911165] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:563; }","duration":"639.898614ms","start":"2026-03-13T23:05:11.270927Z","end":"2026-03-13T23:05:11.910826Z","steps":["trace[338911165] 'range keys from in-memory index tree' (duration: 638.990929ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:11.91106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:11.2709Z","time spent":"640.080469ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-13T23:05:12.520538Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830444266150349,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T23:05:12.755742Z","caller":"traceutil/trace.go:171","msg":"trace[570868428] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:582; }","duration":"735.823252ms","start":"2026-03-13T23:05:12.019884Z","end":"2026-03-13T23:05:12.755708Z","steps":["trace[570868428] 'read index received' (duration: 735.590676ms)","trace[570868428] 'applied index is now lower than readState.Index' (duration: 231.216µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:05:12.755954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.811787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:05:12.755943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.047659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-13T23:05:12.755998Z","caller":"traceutil/trace.go:171","msg":"trace[1241433461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"376.93327ms","start":"2026-03-13T23:05:12.379053Z","end":"2026-03-13T23:05:12.755986Z","steps":["trace[1241433461] 'agreement among raft nodes before linearized reading' (duration: 376.826047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:12.756044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:12.379026Z","time spent":"377.008752ms","remote":"127.0.0.1:53098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-13T23:05:12.756017Z","caller":"traceutil/trace.go:171","msg":"trace[1456995367] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:564; }","duration":"736.165752ms","start":"2026-03-13T23:05:12.019838Z","end":"2026-03-13T23:05:12.756004Z","steps":["trace[1456995367] 'agreement among raft nodes before linearized reading' (duration: 736.032678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:12.756131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:12.019818Z","time spent":"736.301365ms","remote":"127.0.0.1:53144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"info","ts":"2026-03-13T23:05:12.756315Z","caller":"traceutil/trace.go:171","msg":"trace[1873489266] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"839.833879ms","start":"2026-03-13T23:05:11.915984Z","end":"2026-03-13T23:05:12.755817Z","steps":["trace[1873489266] 'process raft request' (duration: 839.530271ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:12.756427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:11.915954Z","time spent":"840.401184ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:05:14.612617Z","caller":"traceutil/trace.go:171","msg":"trace[1571539158] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:710; }","duration":"233.146264ms","start":"2026-03-13T23:05:14.379425Z","end":"2026-03-13T23:05:14.612571Z","steps":["trace[1571539158] 'read index received' (duration: 232.516227ms)","trace[1571539158] 'applied index is now lower than readState.Index' (duration: 628.457µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:05:14.612738Z","caller":"traceutil/trace.go:171","msg":"trace[291174464] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"424.284763ms","start":"2026-03-13T23:05:14.188398Z","end":"2026-03-13T23:05:14.612683Z","steps":["trace[291174464] 'process raft request' (duration: 423.718838ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:14.612844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.38899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:05:14.612952Z","caller":"traceutil/trace.go:171","msg":"trace[92374413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"233.568395ms","start":"2026-03-13T23:05:14.379358Z","end":"2026-03-13T23:05:14.612926Z","steps":["trace[92374413] 'agreement among raft nodes before linearized reading' (duration: 233.36754ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:14.612948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:14.18835Z","time spent":"424.491928ms","remote":"127.0.0.1:53214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":211650,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:05:17.322321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.476159ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-13T23:05:17.322489Z","caller":"traceutil/trace.go:171","msg":"trace[829871778] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"132.698178ms","start":"2026-03-13T23:05:17.189764Z","end":"2026-03-13T23:05:17.322462Z","steps":["trace[829871778] 'read index received' (duration: 17.907452ms)","trace[829871778] 'applied index is now lower than readState.Index' (duration: 114.788506ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:05:17.322803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.006857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-13T23:05:17.322889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.011097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-13T23:05:17.322939Z","caller":"traceutil/trace.go:171","msg":"trace[1174709339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:753; }","duration":"133.202861ms","start":"2026-03-13T23:05:17.189698Z","end":"2026-03-13T23:05:17.322901Z","steps":["trace[1174709339] 'agreement among raft nodes before linearized reading' (duration: 132.834651ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:17.32295Z","caller":"traceutil/trace.go:171","msg":"trace[844541406] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:753; }","duration":"133.097739ms","start":"2026-03-13T23:05:17.189835Z","end":"2026-03-13T23:05:17.322933Z","steps":["trace[844541406] 'agreement among raft nodes before linearized reading' (duration: 132.859033ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:25.283284Z","caller":"traceutil/trace.go:171","msg":"trace[1710706239] linearizableReadLoop","detail":"{readStateIndex:887; appliedIndex:884; }","duration":"100.385723ms","start":"2026-03-13T23:05:25.182865Z","end":"2026-03-13T23:05:25.28325Z","steps":["trace[1710706239] 'read index received' (duration: 100.172668ms)","trace[1710706239] 'applied index is now lower than readState.Index' (duration: 211.865µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:05:25.283378Z","caller":"traceutil/trace.go:171","msg":"trace[1568354521] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"125.904593ms","start":"2026-03-13T23:05:25.157439Z","end":"2026-03-13T23:05:25.283344Z","steps":["trace[1568354521] 'process raft request' (duration: 125.705738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:25.283572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.675431ms","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-03-13T23:05:25.283617Z","caller":"traceutil/trace.go:171","msg":"trace[114471822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:864; }","duration":"100.776184ms","start":"2026-03-13T23:05:25.182828Z","end":"2026-03-13T23:05:25.283604Z","steps":["trace[114471822] 'agreement among raft nodes before linearized reading' (duration: 100.570039ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:25.283773Z","caller":"traceutil/trace.go:171","msg":"trace[1857025117] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"104.704655ms","start":"2026-03-13T23:05:25.179054Z","end":"2026-03-13T23:05:25.283759Z","steps":["trace[1857025117] 'process raft request' (duration: 104.15201ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:25.284094Z","caller":"traceutil/trace.go:171","msg":"trace[759290762] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"127.855683ms","start":"2026-03-13T23:05:25.156045Z","end":"2026-03-13T23:05:25.283901Z","steps":["trace[759290762] 'process raft request' (duration: 126.908169ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:32.335196Z","caller":"traceutil/trace.go:171","msg":"trace[1129061323] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"164.284675ms","start":"2026-03-13T23:05:32.170866Z","end":"2026-03-13T23:05:32.33515Z","steps":["trace[1129061323] 'process raft request' (duration: 164.07861ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:33.026458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.137475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-13T23:05:33.026628Z","caller":"traceutil/trace.go:171","msg":"trace[355043140] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:948; }","duration":"158.368922ms","start":"2026-03-13T23:05:32.868221Z","end":"2026-03-13T23:05:33.02659Z","steps":["trace[355043140] 'range keys from in-memory index tree' (duration: 157.92943ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:33.026824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.567735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-13T23:05:33.026909Z","caller":"traceutil/trace.go:171","msg":"trace[980690527] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:948; }","duration":"257.672708ms","start":"2026-03-13T23:05:32.769213Z","end":"2026-03-13T23:05:33.026886Z","steps":["trace[980690527] 'range keys from in-memory index tree' (duration: 257.3476ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:33.02701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.593543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:05:33.02713Z","caller":"traceutil/trace.go:171","msg":"trace[512273105] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:948; }","duration":"195.786899ms","start":"2026-03-13T23:05:32.831319Z","end":"2026-03-13T23:05:33.027106Z","steps":["trace[512273105] 'count revisions from in-memory index tree' (duration: 195.507591ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:41.302081Z","caller":"traceutil/trace.go:171","msg":"trace[654116179] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"108.427562ms","start":"2026-03-13T23:05:41.193614Z","end":"2026-03-13T23:05:41.302042Z","steps":["trace[654116179] 'process raft request' (duration: 108.198746ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:41.304837Z","caller":"traceutil/trace.go:171","msg":"trace[1136535220] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1027; }","duration":"107.936649ms","start":"2026-03-13T23:05:41.19686Z","end":"2026-03-13T23:05:41.304797Z","steps":["trace[1136535220] 'process raft request' (duration: 107.796106ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:41.755912Z","caller":"traceutil/trace.go:171","msg":"trace[826515923] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"192.544726ms","start":"2026-03-13T23:05:41.563336Z","end":"2026-03-13T23:05:41.755881Z","steps":["trace[826515923] 'process raft request' (duration: 192.380781ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:51.939535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.375347ms","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-03-13T23:05:51.939683Z","caller":"traceutil/trace.go:171","msg":"trace[1004370264] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1095; }","duration":"133.594602ms","start":"2026-03-13T23:05:51.80606Z","end":"2026-03-13T23:05:51.939654Z","steps":["trace[1004370264] 'range keys from in-memory index tree' (duration: 133.018067ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:58.71006Z","caller":"traceutil/trace.go:171","msg":"trace[309295645] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"177.145287ms","start":"2026-03-13T23:05:58.532877Z","end":"2026-03-13T23:05:58.710022Z","steps":["trace[309295645] 'process raft request' (duration: 176.946752ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:03.4498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.637981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/rabbitmqclusters.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:06:03.450019Z","caller":"traceutil/trace.go:171","msg":"trace[1039389610] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/rabbitmqclusters.rabbitmq.com; range_end:; response_count:0; response_revision:1305; }","duration":"112.935608ms","start":"2026-03-13T23:06:03.33704Z","end":"2026-03-13T23:06:03.449976Z","steps":["trace[1039389610] 'range keys from in-memory index tree' (duration: 112.522617ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:25.058346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211026573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-13T23:06:25.058451Z","caller":"traceutil/trace.go:171","msg":"trace[952786402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1614; }","duration":"1.211204988s","start":"2026-03-13T23:06:23.847224Z","end":"2026-03-13T23:06:25.058429Z","steps":["trace[952786402] 'range keys from in-memory index tree' (duration: 1.210808408s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:25.058451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.405682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-03-13T23:06:25.058498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:23.847193Z","time spent":"1.21129514s","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-13T23:06:25.058621Z","caller":"traceutil/trace.go:171","msg":"trace[1384208172] linearizableReadLoop","detail":"{readStateIndex:1675; appliedIndex:1674; }","duration":"938.757336ms","start":"2026-03-13T23:06:24.119841Z","end":"2026-03-13T23:06:25.058598Z","steps":["trace[1384208172] 'read index received' (duration: 63.001µs)","trace[1384208172] 'applied index is now lower than readState.Index' (duration: 938.692714ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:06:25.058684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061027636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-03-13T23:06:25.058804Z","caller":"traceutil/trace.go:171","msg":"trace[133040971] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1614; }","duration":"1.061216401s","start":"2026-03-13T23:06:23.997557Z","end":"2026-03-13T23:06:25.058773Z","steps":["trace[133040971] 'range keys from in-memory index tree' (duration: 1.060217425s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:25.058839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.005023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-13T23:06:25.058883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:23.997528Z","time spent":"1.061340974s","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-03-13T23:06:25.0589Z","caller":"traceutil/trace.go:171","msg":"trace[1868776885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1615; }","duration":"939.139246ms","start":"2026-03-13T23:06:24.119744Z","end":"2026-03-13T23:06:25.058883Z","steps":["trace[1868776885] 'agreement among raft nodes before linearized reading' (duration: 938.952381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:25.059334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:24.119721Z","time spent":"939.573457ms","remote":"127.0.0.1:53332","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-03-13T23:06:25.058775Z","caller":"traceutil/trace.go:171","msg":"trace[294457469] transaction","detail":"{read_only:false; response_revision:1615; number_of_response:1; }","duration":"1.001858687s","start":"2026-03-13T23:06:24.056856Z","end":"2026-03-13T23:06:25.058715Z","steps":["trace[294457469] 'process raft request' (duration: 45.062726ms)","trace[294457469] 'compare' (duration: 956.254828ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:06:25.059519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:24.056818Z","time spent":"1.002601117s","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:06:25.080912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.661812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:06:25.081021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.026193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:06:25.081085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.417798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:06:25.081108Z","caller":"traceutil/trace.go:171","msg":"trace[1329664028] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1616; }","duration":"361.169166ms","start":"2026-03-13T23:06:24.719922Z","end":"2026-03-13T23:06:25.081091Z","steps":["trace[1329664028] 'agreement among raft nodes before linearized reading' (duration: 361.006442ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:25.08114Z","caller":"traceutil/trace.go:171","msg":"trace[313672304] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1616; }","duration":"562.51125ms","start":"2026-03-13T23:06:24.518614Z","end":"2026-03-13T23:06:25.081125Z","steps":["trace[313672304] 'agreement among raft nodes before linearized reading' (duration: 562.402097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:25.081162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:24.719901Z","time spent":"361.253429ms","remote":"127.0.0.1:53240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-03-13T23:06:25.081185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:24.518591Z","time spent":"562.583502ms","remote":"127.0.0.1:53496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-03-13T23:06:25.081209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.491529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7d03c48a-62b7-4358-8fdc-dca38a76c026\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-03-13T23:06:25.081041Z","caller":"traceutil/trace.go:171","msg":"trace[1744305541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1616; }","duration":"700.858316ms","start":"2026-03-13T23:06:24.380157Z","end":"2026-03-13T23:06:25.081016Z","steps":["trace[1744305541] 'agreement among raft nodes before linearized reading' (duration: 700.640681ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:25.081261Z","caller":"traceutil/trace.go:171","msg":"trace[418649264] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7d03c48a-62b7-4358-8fdc-dca38a76c026; range_end:; response_count:1; response_revision:1616; }","duration":"632.582632ms","start":"2026-03-13T23:06:24.448666Z","end":"2026-03-13T23:06:25.081249Z","steps":["trace[418649264] 'agreement among raft nodes before linearized reading' (duration: 632.411097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:25.081316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:24.44864Z","time spent":"632.666194ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7d03c48a-62b7-4358-8fdc-dca38a76c026\" "} {"level":"info","ts":"2026-03-13T23:06:25.080934Z","caller":"traceutil/trace.go:171","msg":"trace[696318555] transaction","detail":"{read_only:false; response_revision:1616; number_of_response:1; }","duration":"683.110738ms","start":"2026-03-13T23:06:24.397782Z","end":"2026-03-13T23:06:25.080893Z","steps":["trace[696318555] 'process raft request' (duration: 682.821421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:25.08137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.024987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7d03c48a-62b7-4358-8fdc-dca38a76c026\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-03-13T23:06:25.081476Z","caller":"traceutil/trace.go:171","msg":"trace[496196643] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7d03c48a-62b7-4358-8fdc-dca38a76c026; range_end:; response_count:1; response_revision:1616; }","duration":"454.196001ms","start":"2026-03-13T23:06:24.627257Z","end":"2026-03-13T23:06:25.081453Z","steps":["trace[496196643] 'agreement among raft nodes before linearized reading' (duration: 453.869432ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:25.081001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.404757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-03-13T23:06:25.081531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:24.627234Z","time spent":"454.287104ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7d03c48a-62b7-4358-8fdc-dca38a76c026\" "} {"level":"warn","ts":"2026-03-13T23:06:25.081365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:24.380129Z","time spent":"701.181496ms","remote":"127.0.0.1:53098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-13T23:06:25.081603Z","caller":"traceutil/trace.go:171","msg":"trace[1564204130] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1616; }","duration":"404.042424ms","start":"2026-03-13T23:06:24.677524Z","end":"2026-03-13T23:06:25.081566Z","steps":["trace[1564204130] 'agreement among raft nodes before linearized reading' (duration: 403.324015ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:25.08165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:24.397751Z","time spent":"683.682284ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:06:25.081702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:24.677496Z","time spent":"404.177167ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-03-13T23:06:25.219429Z","caller":"traceutil/trace.go:171","msg":"trace[92239264] transaction","detail":"{read_only:false; response_revision:1620; number_of_response:1; }","duration":"130.047682ms","start":"2026-03-13T23:06:25.089329Z","end":"2026-03-13T23:06:25.219377Z","steps":["trace[92239264] 'process raft request' (duration: 77.955495ms)","trace[92239264] 'compare' (duration: 51.879822ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:25.455905Z","caller":"traceutil/trace.go:171","msg":"trace[1393841670] transaction","detail":"{read_only:false; response_revision:1622; number_of_response:1; }","duration":"140.447451ms","start":"2026-03-13T23:06:25.315422Z","end":"2026-03-13T23:06:25.455869Z","steps":["trace[1393841670] 'process raft request' (duration: 140.161493ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:25.886873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.259367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:06:25.886975Z","caller":"traceutil/trace.go:171","msg":"trace[1451925357] linearizableReadLoop","detail":"{readStateIndex:1683; appliedIndex:1682; }","duration":"156.575297ms","start":"2026-03-13T23:06:25.730382Z","end":"2026-03-13T23:06:25.886958Z","steps":["trace[1451925357] 'read index received' (duration: 73.202µs)","trace[1451925357] 'applied index is now lower than readState.Index' (duration: 156.500975ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:25.887021Z","caller":"traceutil/trace.go:171","msg":"trace[1560119299] transaction","detail":"{read_only:false; response_revision:1623; number_of_response:1; }","duration":"571.207184ms","start":"2026-03-13T23:06:25.31578Z","end":"2026-03-13T23:06:25.886987Z","steps":["trace[1560119299] 'process raft request' (duration: 274.753532ms)","trace[1560119299] 'compare' (duration: 296.128464ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:06:25.88713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.712811ms","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-03-13T23:06:25.887154Z","caller":"traceutil/trace.go:171","msg":"trace[135565105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1623; }","duration":"156.820283ms","start":"2026-03-13T23:06:25.730325Z","end":"2026-03-13T23:06:25.887146Z","steps":["trace[135565105] 'agreement among raft nodes before linearized reading' (duration: 156.673039ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:25.887161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:25.315758Z","time spent":"571.322848ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:06:30.969182Z","caller":"traceutil/trace.go:171","msg":"trace[2137476519] linearizableReadLoop","detail":"{readStateIndex:1729; appliedIndex:1728; }","duration":"215.86057ms","start":"2026-03-13T23:06:30.753246Z","end":"2026-03-13T23:06:30.969107Z","steps":["trace[2137476519] 'read index received' (duration: 215.656394ms)","trace[2137476519] 'applied index is now lower than readState.Index' (duration: 202.816µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:30.9692Z","caller":"traceutil/trace.go:171","msg":"trace[563208464] transaction","detail":"{read_only:false; response_revision:1667; number_of_response:1; }","duration":"318.132804ms","start":"2026-03-13T23:06:30.651029Z","end":"2026-03-13T23:06:30.969162Z","steps":["trace[563208464] 'process raft request' (duration: 317.885597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:30.969387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:30.651013Z","time spent":"318.279518ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:06:30.969398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.114897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:06:30.969507Z","caller":"traceutil/trace.go:171","msg":"trace[800012479] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1667; }","duration":"216.25333ms","start":"2026-03-13T23:06:30.753226Z","end":"2026-03-13T23:06:30.969479Z","steps":["trace[800012479] 'agreement among raft nodes before linearized reading' (duration: 216.045445ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:36.584705Z","caller":"traceutil/trace.go:171","msg":"trace[169764849] transaction","detail":"{read_only:false; response_revision:1705; number_of_response:1; }","duration":"217.249936ms","start":"2026-03-13T23:06:36.367401Z","end":"2026-03-13T23:06:36.58465Z","steps":["trace[169764849] 'process raft request' (duration: 171.294258ms)","trace[169764849] 'compare' (duration: 45.724882ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:38.019148Z","caller":"traceutil/trace.go:171","msg":"trace[2086264408] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"151.809114ms","start":"2026-03-13T23:06:37.867322Z","end":"2026-03-13T23:06:38.019131Z","steps":["trace[2086264408] 'process raft request' (duration: 151.63572ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:07:15.4699Z","caller":"traceutil/trace.go:171","msg":"trace[2071698918] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"108.767397ms","start":"2026-03-13T23:07:15.361098Z","end":"2026-03-13T23:07:15.469865Z","steps":["trace[2071698918] 'process raft request' (duration: 108.494519ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:16.783633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.156298ms","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":"warn","ts":"2026-03-13T23:07:16.783685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.014712ms","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-03-13T23:07:16.783785Z","caller":"traceutil/trace.go:171","msg":"trace[2108424010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1915; }","duration":"455.175067ms","start":"2026-03-13T23:07:16.328578Z","end":"2026-03-13T23:07:16.783753Z","steps":["trace[2108424010] 'range keys from in-memory index tree' (duration: 454.773697ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:07:16.783779Z","caller":"traceutil/trace.go:171","msg":"trace[1548998176] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1915; }","duration":"189.368334ms","start":"2026-03-13T23:07:16.594373Z","end":"2026-03-13T23:07:16.783741Z","steps":["trace[1548998176] 'count revisions from in-memory index tree' (duration: 189.048355ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:16.783822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:16.328545Z","time spent":"455.266479ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T23:07:16.78381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.406103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.189c893447a847f3\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-03-13T23:07:16.78393Z","caller":"traceutil/trace.go:171","msg":"trace[16123484] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.189c893447a847f3; range_end:; response_count:1; response_revision:1915; }","duration":"874.594739ms","start":"2026-03-13T23:07:15.909305Z","end":"2026-03-13T23:07:16.7839Z","steps":["trace[16123484] 'range keys from in-memory index tree' (duration: 874.118836ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:16.783992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:15.909282Z","time spent":"874.698101ms","remote":"127.0.0.1:53184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.189c893447a847f3\" "} {"level":"warn","ts":"2026-03-13T23:07:16.784161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.057619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-03-13T23:07:16.784119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.795739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-03-13T23:07:16.784162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.207384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-13T23:07:16.784201Z","caller":"traceutil/trace.go:171","msg":"trace[1620711316] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1915; }","duration":"225.135721ms","start":"2026-03-13T23:07:16.559051Z","end":"2026-03-13T23:07:16.784187Z","steps":["trace[1620711316] 'range keys from in-memory index tree' (duration: 224.251267ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:07:16.784215Z","caller":"traceutil/trace.go:171","msg":"trace[1667552709] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1915; }","duration":"456.318097ms","start":"2026-03-13T23:07:16.327885Z","end":"2026-03-13T23:07:16.784203Z","steps":["trace[1667552709] 'range keys from in-memory index tree' (duration: 455.552787ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:16.784182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009362848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-13T23:07:16.784227Z","caller":"traceutil/trace.go:171","msg":"trace[2034858849] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1915; }","duration":"785.974913ms","start":"2026-03-13T23:07:15.998225Z","end":"2026-03-13T23:07:16.7842Z","steps":["trace[2034858849] 'range keys from in-memory index tree' (duration: 785.292375ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:16.784254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:16.327854Z","time spent":"456.391718ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-13T23:07:16.784284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:15.9982Z","time spent":"786.072956ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-03-13T23:07:16.784288Z","caller":"traceutil/trace.go:171","msg":"trace[1498872213] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1915; }","duration":"1.009534962s","start":"2026-03-13T23:07:15.774729Z","end":"2026-03-13T23:07:16.784264Z","steps":["trace[1498872213] 'range keys from in-memory index tree' (duration: 1.009091441s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:16.784347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:15.774705Z","time spent":"1.009632464s","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-13T23:08:03.492632Z","caller":"traceutil/trace.go:171","msg":"trace[243566784] linearizableReadLoop","detail":"{readStateIndex:2408; appliedIndex:2407; }","duration":"289.838226ms","start":"2026-03-13T23:08:03.202769Z","end":"2026-03-13T23:08:03.492608Z","steps":["trace[243566784] 'read index received' (duration: 289.659871ms)","trace[243566784] 'applied index is now lower than readState.Index' (duration: 177.155µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:08:03.492703Z","caller":"traceutil/trace.go:171","msg":"trace[263136406] transaction","detail":"{read_only:false; response_revision:2319; number_of_response:1; }","duration":"349.268445ms","start":"2026-03-13T23:08:03.143386Z","end":"2026-03-13T23:08:03.492655Z","steps":["trace[263136406] 'process raft request' (duration: 349.061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:08:03.492915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:08:03.14335Z","time spent":"349.466241ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:08:03.492956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.412079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-13T23:08:03.492957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.165994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-13T23:08:03.492998Z","caller":"traceutil/trace.go:171","msg":"trace[50997073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2319; }","duration":"278.473211ms","start":"2026-03-13T23:08:03.214513Z","end":"2026-03-13T23:08:03.492986Z","steps":["trace[50997073] 'agreement among raft nodes before linearized reading' (duration: 278.315097ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:08:03.493068Z","caller":"traceutil/trace.go:171","msg":"trace[1537551754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2319; }","duration":"290.333098ms","start":"2026-03-13T23:08:03.202707Z","end":"2026-03-13T23:08:03.49304Z","steps":["trace[1537551754] 'agreement among raft nodes before linearized reading' (duration: 290.02101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:08:03.493034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.408743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:08:03.493158Z","caller":"traceutil/trace.go:171","msg":"trace[1870058903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2319; }","duration":"114.614938ms","start":"2026-03-13T23:08:03.378518Z","end":"2026-03-13T23:08:03.493133Z","steps":["trace[1870058903] 'agreement among raft nodes before linearized reading' (duration: 114.381133ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:08:37.790451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.078ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-13T23:11:19.036085Z","caller":"traceutil/trace.go:171","msg":"trace[402151224] transaction","detail":"{read_only:false; response_revision:3479; number_of_response:1; }","duration":"203.535923ms","start":"2026-03-13T23:11:18.832521Z","end":"2026-03-13T23:11:19.036056Z","steps":["trace[402151224] 'process raft request' (duration: 203.354698ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:19.197115Z","caller":"traceutil/trace.go:171","msg":"trace[888479259] transaction","detail":"{read_only:false; response_revision:3480; number_of_response:1; }","duration":"361.9368ms","start":"2026-03-13T23:11:18.835141Z","end":"2026-03-13T23:11:19.197078Z","steps":["trace[888479259] 'process raft request' (duration: 322.08097ms)","trace[888479259] 'compare' (duration: 39.650355ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:11:19.197332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:18.835103Z","time spent":"362.116135ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:11:19.197319Z","caller":"traceutil/trace.go:171","msg":"trace[468726263] linearizableReadLoop","detail":"{readStateIndex:3616; appliedIndex:3613; }","duration":"292.103615ms","start":"2026-03-13T23:11:18.905176Z","end":"2026-03-13T23:11:19.19728Z","steps":["trace[468726263] 'read index received' (duration: 130.756172ms)","trace[468726263] 'applied index is now lower than readState.Index' (duration: 161.343583ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:11:19.197711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.506514ms","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-03-13T23:11:19.197828Z","caller":"traceutil/trace.go:171","msg":"trace[516203469] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3481; }","duration":"292.69055ms","start":"2026-03-13T23:11:18.905109Z","end":"2026-03-13T23:11:19.197799Z","steps":["trace[516203469] 'agreement among raft nodes before linearized reading' (duration: 292.334931ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:19.197858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.587196ms","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-03-13T23:11:19.197985Z","caller":"traceutil/trace.go:171","msg":"trace[664553338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3481; }","duration":"205.768631ms","start":"2026-03-13T23:11:18.992189Z","end":"2026-03-13T23:11:19.197958Z","steps":["trace[664553338] 'agreement among raft nodes before linearized reading' (duration: 205.434452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:19.197987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.710898ms","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-03-13T23:11:19.198101Z","caller":"traceutil/trace.go:171","msg":"trace[1326258673] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3481; }","duration":"203.965164ms","start":"2026-03-13T23:11:18.994109Z","end":"2026-03-13T23:11:19.198074Z","steps":["trace[1326258673] 'agreement among raft nodes before linearized reading' (duration: 203.548953ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:19.197642Z","caller":"traceutil/trace.go:171","msg":"trace[954830033] transaction","detail":"{read_only:false; response_revision:3481; number_of_response:1; }","duration":"361.379755ms","start":"2026-03-13T23:11:18.835939Z","end":"2026-03-13T23:11:19.197319Z","steps":["trace[954830033] 'process raft request' (duration: 361.091757ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:19.199865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:18.835894Z","time spent":"363.571682ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:12:36.998371Z","caller":"traceutil/trace.go:171","msg":"trace[746814520] transaction","detail":"{read_only:false; response_revision:4163; number_of_response:1; }","duration":"120.097733ms","start":"2026-03-13T23:12:36.878237Z","end":"2026-03-13T23:12:36.998335Z","steps":["trace[746814520] 'process raft request' (duration: 119.921978ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:12:36.998722Z","caller":"traceutil/trace.go:171","msg":"trace[1937886612] transaction","detail":"{read_only:false; response_revision:4162; number_of_response:1; }","duration":"126.471039ms","start":"2026-03-13T23:12:36.87219Z","end":"2026-03-13T23:12:36.998661Z","steps":["trace[1937886612] 'process raft request' (duration: 119.761475ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:12:36.998932Z","caller":"traceutil/trace.go:171","msg":"trace[1516229954] transaction","detail":"{read_only:false; response_revision:4164; number_of_response:1; }","duration":"116.302876ms","start":"2026-03-13T23:12:36.88261Z","end":"2026-03-13T23:12:36.998912Z","steps":["trace[1516229954] 'process raft request' (duration: 115.619648ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:12:43.455087Z","caller":"traceutil/trace.go:171","msg":"trace[1886293491] transaction","detail":"{read_only:false; response_revision:4416; number_of_response:1; }","duration":"138.662223ms","start":"2026-03-13T23:12:43.316404Z","end":"2026-03-13T23:12:43.455066Z","steps":["trace[1886293491] 'process raft request' (duration: 138.504169ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:12:43.610749Z","caller":"traceutil/trace.go:171","msg":"trace[663722570] transaction","detail":"{read_only:false; response_revision:4417; number_of_response:1; }","duration":"110.643419ms","start":"2026-03-13T23:12:43.500044Z","end":"2026-03-13T23:12:43.610688Z","steps":["trace[663722570] 'process raft request' (duration: 104.581942ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:12:43.611808Z","caller":"traceutil/trace.go:171","msg":"trace[1493985379] linearizableReadLoop","detail":"{readStateIndex:4591; appliedIndex:4588; }","duration":"105.741282ms","start":"2026-03-13T23:12:43.506057Z","end":"2026-03-13T23:12:43.611798Z","steps":["trace[1493985379] 'read index received' (duration: 98.582696ms)","trace[1493985379] 'applied index is now lower than readState.Index' (duration: 7.157556ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:12:43.611901Z","caller":"traceutil/trace.go:171","msg":"trace[443890508] transaction","detail":"{read_only:false; response_revision:4418; number_of_response:1; }","duration":"110.094105ms","start":"2026-03-13T23:12:43.501776Z","end":"2026-03-13T23:12:43.61187Z","steps":["trace[443890508] 'process raft request' (duration: 109.91682ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:12:43.612177Z","caller":"traceutil/trace.go:171","msg":"trace[1882536489] transaction","detail":"{read_only:false; response_revision:4419; number_of_response:1; }","duration":"109.760126ms","start":"2026-03-13T23:12:43.502409Z","end":"2026-03-13T23:12:43.612169Z","steps":["trace[1882536489] 'process raft request' (duration: 109.350446ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:12:43.612592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.518613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7788be98-434b-41a2-826b-435c377e7e38\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-03-13T23:12:43.61263Z","caller":"traceutil/trace.go:171","msg":"trace[1211946037] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7788be98-434b-41a2-826b-435c377e7e38; range_end:; response_count:1; response_revision:4419; }","duration":"106.617135ms","start":"2026-03-13T23:12:43.506003Z","end":"2026-03-13T23:12:43.61262Z","steps":["trace[1211946037] 'agreement among raft nodes before linearized reading' (duration: 106.482092ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:12:44.133771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.91177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-03-13T23:12:44.133891Z","caller":"traceutil/trace.go:171","msg":"trace[163594834] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4428; }","duration":"110.135275ms","start":"2026-03-13T23:12:44.023733Z","end":"2026-03-13T23:12:44.133868Z","steps":["trace[163594834] 'range keys from in-memory index tree' (duration: 109.671694ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:12:45.803283Z","caller":"traceutil/trace.go:171","msg":"trace[1178639173] linearizableReadLoop","detail":"{readStateIndex:4612; appliedIndex:4610; }","duration":"107.670652ms","start":"2026-03-13T23:12:45.695588Z","end":"2026-03-13T23:12:45.803259Z","steps":["trace[1178639173] 'read index received' (duration: 4.490046ms)","trace[1178639173] 'applied index is now lower than readState.Index' (duration: 103.179426ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:12:45.803329Z","caller":"traceutil/trace.go:171","msg":"trace[7366590] transaction","detail":"{read_only:false; response_revision:4440; number_of_response:1; }","duration":"131.703983ms","start":"2026-03-13T23:12:45.671592Z","end":"2026-03-13T23:12:45.803296Z","steps":["trace[7366590] 'process raft request' (duration: 103.678569ms)","trace[7366590] 'compare' (duration: 27.773418ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:12:45.80348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.019176ms","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":"warn","ts":"2026-03-13T23:12:45.803488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.893817ms","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-03-13T23:12:45.803511Z","caller":"traceutil/trace.go:171","msg":"trace[1732803878] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4440; }","duration":"102.065977ms","start":"2026-03-13T23:12:45.701436Z","end":"2026-03-13T23:12:45.803502Z","steps":["trace[1732803878] 'agreement among raft nodes before linearized reading' (duration: 101.955715ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:12:45.803545Z","caller":"traceutil/trace.go:171","msg":"trace[1105428873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4440; }","duration":"107.9989ms","start":"2026-03-13T23:12:45.695531Z","end":"2026-03-13T23:12:45.80353Z","steps":["trace[1105428873] 'agreement among raft nodes before linearized reading' (duration: 107.811715ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:12:45.803516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.916548ms","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-03-13T23:12:45.8036Z","caller":"traceutil/trace.go:171","msg":"trace[490186331] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4440; }","duration":"108.01287ms","start":"2026-03-13T23:12:45.695573Z","end":"2026-03-13T23:12:45.803586Z","steps":["trace[490186331] 'agreement among raft nodes before linearized reading' (duration: 107.799025ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:12:46.079582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.342378ms","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-03-13T23:12:46.079717Z","caller":"traceutil/trace.go:171","msg":"trace[1309035911] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:4443; }","duration":"123.524232ms","start":"2026-03-13T23:12:45.956148Z","end":"2026-03-13T23:12:46.079673Z","steps":["trace[1309035911] 'count revisions from in-memory index tree' (duration: 123.156321ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:12:48.027189Z","caller":"traceutil/trace.go:171","msg":"trace[1093778982] transaction","detail":"{read_only:false; response_revision:4504; number_of_response:1; }","duration":"318.002826ms","start":"2026-03-13T23:12:47.709152Z","end":"2026-03-13T23:12:48.027155Z","steps":["trace[1093778982] 'process raft request' (duration: 317.644968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:12:48.027378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:12:47.709123Z","time spent":"318.174842ms","remote":"127.0.0.1:53214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":197468,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T23:12:48.032403Z","caller":"traceutil/trace.go:171","msg":"trace[1719041371] linearizableReadLoop","detail":"{readStateIndex:4683; appliedIndex:4681; }","duration":"224.412889ms","start":"2026-03-13T23:12:47.807966Z","end":"2026-03-13T23:12:48.032379Z","steps":["trace[1719041371] 'read index received' (duration: 218.758893ms)","trace[1719041371] 'applied index is now lower than readState.Index' (duration: 5.652846ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:12:48.032769Z","caller":"traceutil/trace.go:171","msg":"trace[413415246] transaction","detail":"{read_only:false; response_revision:4505; number_of_response:1; }","duration":"310.011339ms","start":"2026-03-13T23:12:47.722721Z","end":"2026-03-13T23:12:48.032732Z","steps":["trace[413415246] 'process raft request' (duration: 309.494427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:12:48.032968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:12:47.722696Z","time spent":"310.167584ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:12:48.033001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.397408ms","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-03-13T23:12:48.033042Z","caller":"traceutil/trace.go:171","msg":"trace[470251824] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4505; }","duration":"207.509452ms","start":"2026-03-13T23:12:47.825519Z","end":"2026-03-13T23:12:48.033028Z","steps":["trace[470251824] 'agreement among raft nodes before linearized reading' (duration: 207.353178ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:12:48.033116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.116887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-03-13T23:12:48.03321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.203436ms","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-03-13T23:12:48.033239Z","caller":"traceutil/trace.go:171","msg":"trace[979430499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4505; }","duration":"199.266498ms","start":"2026-03-13T23:12:47.833966Z","end":"2026-03-13T23:12:48.033232Z","steps":["trace[979430499] 'agreement among raft nodes before linearized reading' (duration: 199.192256ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:12:48.033258Z","caller":"traceutil/trace.go:171","msg":"trace[1028726706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4505; }","duration":"225.325211ms","start":"2026-03-13T23:12:47.807901Z","end":"2026-03-13T23:12:48.033226Z","steps":["trace[1028726706] 'agreement among raft nodes before linearized reading' (duration: 224.860639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:12:48.033173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.434347ms","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-03-13T23:12:48.033394Z","caller":"traceutil/trace.go:171","msg":"trace[298661851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4505; }","duration":"198.680952ms","start":"2026-03-13T23:12:47.834703Z","end":"2026-03-13T23:12:48.033384Z","steps":["trace[298661851] 'agreement among raft nodes before linearized reading' (duration: 198.337644ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:12:49.917841Z","caller":"traceutil/trace.go:171","msg":"trace[393536652] linearizableReadLoop","detail":"{readStateIndex:4797; appliedIndex:4795; }","duration":"165.406814ms","start":"2026-03-13T23:12:49.752407Z","end":"2026-03-13T23:12:49.917814Z","steps":["trace[393536652] 'read index received' (duration: 86.253489ms)","trace[393536652] 'applied index is now lower than readState.Index' (duration: 79.152425ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:12:49.91787Z","caller":"traceutil/trace.go:171","msg":"trace[1618625043] transaction","detail":"{read_only:false; response_revision:4617; number_of_response:1; }","duration":"168.169235ms","start":"2026-03-13T23:12:49.749659Z","end":"2026-03-13T23:12:49.917828Z","steps":["trace[1618625043] 'process raft request' (duration: 89.02655ms)","trace[1618625043] 'compare' (duration: 78.877028ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:12:49.917961Z","caller":"traceutil/trace.go:171","msg":"trace[624041629] transaction","detail":"{read_only:false; response_revision:4618; number_of_response:1; }","duration":"167.065076ms","start":"2026-03-13T23:12:49.750887Z","end":"2026-03-13T23:12:49.917952Z","steps":["trace[624041629] 'process raft request' (duration: 166.877671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:12:49.918226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.914085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-60e603de-22cb-48f0-beeb-e9613bf22af4\" ","response":"range_response_count:1 size:2535"} {"level":"warn","ts":"2026-03-13T23:12:49.918259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.812114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:12:49.918298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.837714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-60e603de-22cb-48f0-beeb-e9613bf22af4\" ","response":"range_response_count:1 size:2535"} {"level":"info","ts":"2026-03-13T23:12:49.918312Z","caller":"traceutil/trace.go:171","msg":"trace[1380751321] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-60e603de-22cb-48f0-beeb-e9613bf22af4; range_end:; response_count:1; response_revision:4618; }","duration":"162.044057ms","start":"2026-03-13T23:12:49.756246Z","end":"2026-03-13T23:12:49.918291Z","steps":["trace[1380751321] 'agreement among raft nodes before linearized reading' (duration: 161.78185ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:12:49.918314Z","caller":"traceutil/trace.go:171","msg":"trace[1036992008] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:4618; }","duration":"165.930077ms","start":"2026-03-13T23:12:49.75237Z","end":"2026-03-13T23:12:49.9183Z","steps":["trace[1036992008] 'agreement among raft nodes before linearized reading' (duration: 165.796584ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:12:49.918334Z","caller":"traceutil/trace.go:171","msg":"trace[949910203] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-60e603de-22cb-48f0-beeb-e9613bf22af4; range_end:; response_count:1; response_revision:4618; }","duration":"158.893106ms","start":"2026-03-13T23:12:49.759429Z","end":"2026-03-13T23:12:49.918322Z","steps":["trace[949910203] 'agreement among raft nodes before linearized reading' (duration: 158.735522ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:12:51.673013Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830444266159926,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T23:12:51.783918Z","caller":"traceutil/trace.go:171","msg":"trace[1313268298] transaction","detail":"{read_only:false; response_revision:4649; number_of_response:1; }","duration":"710.395925ms","start":"2026-03-13T23:12:51.073486Z","end":"2026-03-13T23:12:51.783882Z","steps":["trace[1313268298] 'process raft request' (duration: 710.21237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:12:51.784119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:12:51.073457Z","time spent":"710.555359ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:12:51.793701Z","caller":"traceutil/trace.go:171","msg":"trace[546235281] linearizableReadLoop","detail":"{readStateIndex:4830; appliedIndex:4828; }","duration":"621.491667ms","start":"2026-03-13T23:12:51.172181Z","end":"2026-03-13T23:12:51.793673Z","steps":["trace[546235281] 'read index received' (duration: 611.52597ms)","trace[546235281] 'applied index is now lower than readState.Index' (duration: 9.964727ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:12:51.793802Z","caller":"traceutil/trace.go:171","msg":"trace[997850480] transaction","detail":"{read_only:false; response_revision:4650; number_of_response:1; }","duration":"718.69612ms","start":"2026-03-13T23:12:51.075066Z","end":"2026-03-13T23:12:51.793762Z","steps":["trace[997850480] 'process raft request' (duration: 718.471063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:12:51.794028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.785371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:12:51.794096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.927299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lp5sr\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-13T23:12:51.794125Z","caller":"traceutil/trace.go:171","msg":"trace[552550088] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lp5sr; range_end:; response_count:1; response_revision:4650; }","duration":"621.991531ms","start":"2026-03-13T23:12:51.172126Z","end":"2026-03-13T23:12:51.794117Z","steps":["trace[552550088] 'agreement among raft nodes before linearized reading' (duration: 621.704054ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:12:51.794023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.135052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-c2dhs-tls\" ","response":"range_response_count:1 size:2184"} {"level":"warn","ts":"2026-03-13T23:12:51.794192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:12:51.172103Z","time spent":"622.081252ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-lp5sr\" "} {"level":"info","ts":"2026-03-13T23:12:51.7942Z","caller":"traceutil/trace.go:171","msg":"trace[2088174244] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-c2dhs-tls; range_end:; response_count:1; response_revision:4650; }","duration":"577.374967ms","start":"2026-03-13T23:12:51.216814Z","end":"2026-03-13T23:12:51.794189Z","steps":["trace[2088174244] 'agreement among raft nodes before linearized reading' (duration: 577.06503ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:12:51.794269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:12:51.216793Z","time spent":"577.452531ms","remote":"127.0.0.1:35426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":2207,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-c2dhs-tls\" "} {"level":"warn","ts":"2026-03-13T23:12:51.794023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.420262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-60e603de-22cb-48f0-beeb-e9613bf22af4\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-13T23:12:51.794383Z","caller":"traceutil/trace.go:171","msg":"trace[623504732] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-60e603de-22cb-48f0-beeb-e9613bf22af4; range_end:; response_count:1; response_revision:4650; }","duration":"185.831452ms","start":"2026-03-13T23:12:51.608529Z","end":"2026-03-13T23:12:51.79436Z","steps":["trace[623504732] 'agreement among raft nodes before linearized reading' (duration: 185.380301ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:12:51.7941Z","caller":"traceutil/trace.go:171","msg":"trace[1569948576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4650; }","duration":"413.912724ms","start":"2026-03-13T23:12:51.380174Z","end":"2026-03-13T23:12:51.794086Z","steps":["trace[1569948576] 'agreement among raft nodes before linearized reading' (duration: 413.774521ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:12:51.794485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:12:51.380154Z","time spent":"414.319855ms","remote":"127.0.0.1:53098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-13T23:12:51.794014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:12:51.075057Z","time spent":"718.837643ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:12:52.078257Z","caller":"traceutil/trace.go:171","msg":"trace[646188935] linearizableReadLoop","detail":"{readStateIndex:4834; appliedIndex:4833; }","duration":"131.93381ms","start":"2026-03-13T23:12:51.946291Z","end":"2026-03-13T23:12:52.078225Z","steps":["trace[646188935] 'read index received' (duration: 88.441446ms)","trace[646188935] 'applied index is now lower than readState.Index' (duration: 43.490934ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:12:52.078349Z","caller":"traceutil/trace.go:171","msg":"trace[1632428935] transaction","detail":"{read_only:false; response_revision:4654; number_of_response:1; }","duration":"140.44678ms","start":"2026-03-13T23:12:51.937851Z","end":"2026-03-13T23:12:52.078298Z","steps":["trace[1632428935] 'process raft request' (duration: 97.021968ms)","trace[1632428935] 'compare' (duration: 43.165715ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:12:52.078631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.312499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-60e603de-22cb-48f0-beeb-e9613bf22af4\" ","response":"range_response_count:1 size:3721"} {"level":"info","ts":"2026-03-13T23:12:52.078723Z","caller":"traceutil/trace.go:171","msg":"trace[1687512212] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-60e603de-22cb-48f0-beeb-e9613bf22af4; range_end:; response_count:1; response_revision:4654; }","duration":"132.468834ms","start":"2026-03-13T23:12:51.946233Z","end":"2026-03-13T23:12:52.078702Z","steps":["trace[1687512212] 'agreement among raft nodes before linearized reading' (duration: 132.129884ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:12:54.035655Z","caller":"traceutil/trace.go:171","msg":"trace[1058786251] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"246.422328ms","start":"2026-03-13T23:12:53.789204Z","end":"2026-03-13T23:12:54.035626Z","steps":["trace[1058786251] 'process raft request' (duration: 246.251433ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:12:54.044735Z","caller":"traceutil/trace.go:171","msg":"trace[1984553582] linearizableReadLoop","detail":"{readStateIndex:4876; appliedIndex:4874; }","duration":"238.437371ms","start":"2026-03-13T23:12:53.806263Z","end":"2026-03-13T23:12:54.044701Z","steps":["trace[1984553582] 'read index received' (duration: 229.186852ms)","trace[1984553582] 'applied index is now lower than readState.Index' (duration: 9.249149ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:12:54.044856Z","caller":"traceutil/trace.go:171","msg":"trace[341023490] transaction","detail":"{read_only:false; response_revision:4693; number_of_response:1; }","duration":"242.524016ms","start":"2026-03-13T23:12:53.802303Z","end":"2026-03-13T23:12:54.044827Z","steps":["trace[341023490] 'process raft request' (duration: 242.194158ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:12:54.045155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.499118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-60e603de-22cb-48f0-beeb-e9613bf22af4\" ","response":"range_response_count:1 size:3907"} {"level":"info","ts":"2026-03-13T23:12:54.045224Z","caller":"traceutil/trace.go:171","msg":"trace[2137663333] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-60e603de-22cb-48f0-beeb-e9613bf22af4; range_end:; response_count:1; response_revision:4693; }","duration":"186.629572ms","start":"2026-03-13T23:12:53.858577Z","end":"2026-03-13T23:12:54.045206Z","steps":["trace[2137663333] 'agreement among raft nodes before linearized reading' (duration: 186.391776ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:12:54.04551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.238242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lp5sr\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-13T23:12:54.045573Z","caller":"traceutil/trace.go:171","msg":"trace[2084011822] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lp5sr; range_end:; response_count:1; response_revision:4693; }","duration":"239.349465ms","start":"2026-03-13T23:12:53.806209Z","end":"2026-03-13T23:12:54.045558Z","steps":["trace[2084011822] 'agreement among raft nodes before linearized reading' (duration: 239.15202ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:12:54.045882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.245074ms","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-03-13T23:12:54.046149Z","caller":"traceutil/trace.go:171","msg":"trace[787671051] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4693; }","duration":"195.547903ms","start":"2026-03-13T23:12:53.850586Z","end":"2026-03-13T23:12:54.046134Z","steps":["trace[787671051] 'agreement among raft nodes before linearized reading' (duration: 195.210074ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:12:54.546853Z","caller":"traceutil/trace.go:171","msg":"trace[97228704] transaction","detail":"{read_only:false; response_revision:4708; number_of_response:1; }","duration":"131.483998ms","start":"2026-03-13T23:12:54.41533Z","end":"2026-03-13T23:12:54.546814Z","steps":["trace[97228704] 'process raft request' (duration: 131.171681ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:12:54.850098Z","caller":"traceutil/trace.go:171","msg":"trace[955095985] linearizableReadLoop","detail":"{readStateIndex:4894; appliedIndex:4893; }","duration":"105.917997ms","start":"2026-03-13T23:12:54.744151Z","end":"2026-03-13T23:12:54.850069Z","steps":["trace[955095985] 'read index received' (duration: 105.676761ms)","trace[955095985] 'applied index is now lower than readState.Index' (duration: 240.206µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:12:54.850273Z","caller":"traceutil/trace.go:171","msg":"trace[1850560430] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"162.351725ms","start":"2026-03-13T23:12:54.68784Z","end":"2026-03-13T23:12:54.850192Z","steps":["trace[1850560430] 'process raft request' (duration: 162.011157ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:12:54.850343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.73014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-03-13T23:12:54.850498Z","caller":"traceutil/trace.go:171","msg":"trace[473682715] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4711; }","duration":"156.922855ms","start":"2026-03-13T23:12:54.693557Z","end":"2026-03-13T23:12:54.85048Z","steps":["trace[473682715] 'agreement among raft nodes before linearized reading' (duration: 156.655777ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:01.770897Z","caller":"traceutil/trace.go:171","msg":"trace[937929606] transaction","detail":"{read_only:false; response_revision:4845; number_of_response:1; }","duration":"123.43393ms","start":"2026-03-13T23:13:01.647411Z","end":"2026-03-13T23:13:01.770845Z","steps":["trace[937929606] 'process raft request' (duration: 122.401483ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:11.270732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.477298ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:13:11.270889Z","caller":"traceutil/trace.go:171","msg":"trace[395396478] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4993; }","duration":"186.644272ms","start":"2026-03-13T23:13:11.084222Z","end":"2026-03-13T23:13:11.270866Z","steps":["trace[395396478] 'range keys from in-memory index tree' (duration: 186.461937ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:11.271033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.432728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c2e266ca-ae95-44a6-92ab-dce2b9e89ede\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-13T23:13:11.27115Z","caller":"traceutil/trace.go:171","msg":"trace[1512652563] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c2e266ca-ae95-44a6-92ab-dce2b9e89ede; range_end:; response_count:1; response_revision:4993; }","duration":"801.568012ms","start":"2026-03-13T23:13:10.469552Z","end":"2026-03-13T23:13:11.27112Z","steps":["trace[1512652563] 'range keys from in-memory index tree' (duration: 801.171221ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:11.271193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:13:10.469478Z","time spent":"801.702595ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c2e266ca-ae95-44a6-92ab-dce2b9e89ede\" "} {"level":"warn","ts":"2026-03-13T23:13:11.271167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.193595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:13:11.271294Z","caller":"traceutil/trace.go:171","msg":"trace[1682652233] linearizableReadLoop","detail":"{readStateIndex:5182; appliedIndex:5181; }","duration":"434.757184ms","start":"2026-03-13T23:13:10.836516Z","end":"2026-03-13T23:13:11.271273Z","steps":["trace[1682652233] 'read index received' (duration: 47.841µs)","trace[1682652233] 'applied index is now lower than readState.Index' (duration: 434.708123ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:13:11.271381Z","caller":"traceutil/trace.go:171","msg":"trace[1823864657] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"637.298507ms","start":"2026-03-13T23:13:10.634071Z","end":"2026-03-13T23:13:11.271369Z","steps":["trace[1823864657] 'process raft request' (duration: 69.744442ms)","trace[1823864657] 'compare' (duration: 566.777305ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:13:11.271442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:13:10.634029Z","time spent":"637.377979ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:13:11.272337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.797231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c2e266ca-ae95-44a6-92ab-dce2b9e89ede\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-13T23:13:11.272453Z","caller":"traceutil/trace.go:171","msg":"trace[1091067461] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c2e266ca-ae95-44a6-92ab-dce2b9e89ede; range_end:; response_count:1; response_revision:4994; }","duration":"435.958716ms","start":"2026-03-13T23:13:10.836461Z","end":"2026-03-13T23:13:11.27242Z","steps":["trace[1091067461] 'agreement among raft nodes before linearized reading' (duration: 435.178345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:11.27263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:13:10.836437Z","time spent":"436.182071ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c2e266ca-ae95-44a6-92ab-dce2b9e89ede\" "} {"level":"warn","ts":"2026-03-13T23:13:11.353574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.829427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:13:11.353686Z","caller":"traceutil/trace.go:171","msg":"trace[1924271372] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:4995; }","duration":"395.995692ms","start":"2026-03-13T23:13:10.957665Z","end":"2026-03-13T23:13:11.35366Z","steps":["trace[1924271372] 'agreement among raft nodes before linearized reading' (duration: 395.736624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:11.353739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:13:10.957644Z","time spent":"396.083514ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":25,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-03-13T23:13:11.353997Z","caller":"traceutil/trace.go:171","msg":"trace[297148686] transaction","detail":"{read_only:false; response_revision:4995; number_of_response:1; }","duration":"374.768583ms","start":"2026-03-13T23:13:10.979166Z","end":"2026-03-13T23:13:11.353935Z","steps":["trace[297148686] 'process raft request' (duration: 374.058065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:11.354182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:13:10.979135Z","time spent":"374.944678ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:13:11.548033Z","caller":"traceutil/trace.go:171","msg":"trace[1882530312] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"267.756618ms","start":"2026-03-13T23:13:11.280253Z","end":"2026-03-13T23:13:11.548009Z","steps":["trace[1882530312] 'process raft request' (duration: 267.481931ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:11.548177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.975771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:13:11.548237Z","caller":"traceutil/trace.go:171","msg":"trace[181544357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4996; }","duration":"168.120784ms","start":"2026-03-13T23:13:11.380098Z","end":"2026-03-13T23:13:11.548219Z","steps":["trace[181544357] 'agreement among raft nodes before linearized reading' (duration: 167.985241ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:11.547982Z","caller":"traceutil/trace.go:171","msg":"trace[301572722] linearizableReadLoop","detail":"{readStateIndex:5184; appliedIndex:5183; }","duration":"167.766326ms","start":"2026-03-13T23:13:11.380186Z","end":"2026-03-13T23:13:11.547952Z","steps":["trace[301572722] 'read index received' (duration: 167.419887ms)","trace[301572722] 'applied index is now lower than readState.Index' (duration: 345.199µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:13:13.084285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.984997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:13:13.084546Z","caller":"traceutil/trace.go:171","msg":"trace[1930632276] linearizableReadLoop","detail":"{readStateIndex:5187; appliedIndex:5184; }","duration":"926.560011ms","start":"2026-03-13T23:13:12.157968Z","end":"2026-03-13T23:13:13.084528Z","steps":["trace[1930632276] 'read index received' (duration: 148.542058ms)","trace[1930632276] 'applied index is now lower than readState.Index' (duration: 778.016953ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:13:13.084585Z","caller":"traceutil/trace.go:171","msg":"trace[1905911292] transaction","detail":"{read_only:false; response_revision:4997; number_of_response:1; }","duration":"982.072815ms","start":"2026-03-13T23:13:12.10246Z","end":"2026-03-13T23:13:13.084533Z","steps":["trace[1905911292] 'process raft request' (duration: 536.717438ms)","trace[1905911292] 'compare' (duration: 444.476145ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:13:13.08463Z","caller":"traceutil/trace.go:171","msg":"trace[1385886231] transaction","detail":"{read_only:false; response_revision:4998; number_of_response:1; }","duration":"968.626309ms","start":"2026-03-13T23:13:12.115993Z","end":"2026-03-13T23:13:13.08462Z","steps":["trace[1385886231] 'process raft request' (duration: 968.416843ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:13.0849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.918441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lp5sr\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-13T23:13:13.084982Z","caller":"traceutil/trace.go:171","msg":"trace[1513056263] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lp5sr; range_end:; response_count:1; response_revision:4998; }","duration":"927.110696ms","start":"2026-03-13T23:13:12.157852Z","end":"2026-03-13T23:13:13.084962Z","steps":["trace[1513056263] 'agreement among raft nodes before linearized reading' (duration: 926.793847ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:13.084802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:13:12.102425Z","time spent":"982.26789ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:13:13.085029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:13:12.157798Z","time spent":"927.223529ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-lp5sr\" "} {"level":"warn","ts":"2026-03-13T23:13:13.084978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:13:12.11597Z","time spent":"968.893206ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:13:13.092617Z","caller":"traceutil/trace.go:171","msg":"trace[1543274053] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"784.317126ms","start":"2026-03-13T23:13:12.308277Z","end":"2026-03-13T23:13:13.092594Z","steps":["trace[1543274053] 'process raft request' (duration: 784.155313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:13.092781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:13:12.308248Z","time spent":"784.44005ms","remote":"127.0.0.1:53144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:13:13.093202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.722742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-03-13T23:13:13.093251Z","caller":"traceutil/trace.go:171","msg":"trace[51360118] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4999; }","duration":"850.804394ms","start":"2026-03-13T23:13:12.24243Z","end":"2026-03-13T23:13:13.093234Z","steps":["trace[51360118] 'agreement among raft nodes before linearized reading' (duration: 850.598029ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:13.093284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:13:12.242406Z","time spent":"850.869135ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-03-13T23:13:13.093294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.994114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-13T23:13:13.093359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.358143ms","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-03-13T23:13:13.093419Z","caller":"traceutil/trace.go:171","msg":"trace[760524551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4999; }","duration":"756.447636ms","start":"2026-03-13T23:13:12.336955Z","end":"2026-03-13T23:13:13.093403Z","steps":["trace[760524551] 'agreement among raft nodes before linearized reading' (duration: 756.100047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:13.093393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.634211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-03-13T23:13:13.093393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.164269ms","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-03-13T23:13:13.093488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:13:12.336948Z","time spent":"756.528818ms","remote":"127.0.0.1:53332","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-03-13T23:13:13.093502Z","caller":"traceutil/trace.go:171","msg":"trace[592619527] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4999; }","duration":"756.351734ms","start":"2026-03-13T23:13:12.337138Z","end":"2026-03-13T23:13:13.09349Z","steps":["trace[592619527] 'agreement among raft nodes before linearized reading' (duration: 756.065386ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:13.093499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.67573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-13T23:13:13.093502Z","caller":"traceutil/trace.go:171","msg":"trace[187751252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4999; }","duration":"756.794615ms","start":"2026-03-13T23:13:12.336687Z","end":"2026-03-13T23:13:13.093482Z","steps":["trace[187751252] 'agreement among raft nodes before linearized reading' (duration: 756.591589ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:13.09355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:13:12.337114Z","time spent":"756.425616ms","remote":"127.0.0.1:53332","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-03-13T23:13:13.093555Z","caller":"traceutil/trace.go:171","msg":"trace[1322900439] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4999; }","duration":"855.945608ms","start":"2026-03-13T23:13:12.237597Z","end":"2026-03-13T23:13:13.093543Z","steps":["trace[1322900439] 'agreement among raft nodes before linearized reading' (duration: 855.620529ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:13.093559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:13:12.336669Z","time spent":"756.882027ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-13T23:13:13.093431Z","caller":"traceutil/trace.go:171","msg":"trace[1929051954] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4999; }","duration":"833.16432ms","start":"2026-03-13T23:13:12.260215Z","end":"2026-03-13T23:13:13.09338Z","steps":["trace[1929051954] 'agreement among raft nodes before linearized reading' (duration: 832.80419ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:13.093602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:13:12.237575Z","time spent":"856.018669ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-13T23:13:13.093635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:13:12.260197Z","time spent":"833.430036ms","remote":"127.0.0.1:53332","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-03-13T23:13:13.093685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.363311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c2e266ca-ae95-44a6-92ab-dce2b9e89ede\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-03-13T23:13:13.093818Z","caller":"traceutil/trace.go:171","msg":"trace[1490757634] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c2e266ca-ae95-44a6-92ab-dce2b9e89ede; range_end:; response_count:1; response_revision:4999; }","duration":"817.575696ms","start":"2026-03-13T23:13:12.276211Z","end":"2026-03-13T23:13:13.093786Z","steps":["trace[1490757634] 'agreement among raft nodes before linearized reading' (duration: 817.144744ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:13.093881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:13:12.276187Z","time spent":"817.681089ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3783,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c2e266ca-ae95-44a6-92ab-dce2b9e89ede\" "} {"level":"info","ts":"2026-03-13T23:13:17.335248Z","caller":"traceutil/trace.go:171","msg":"trace[1996483255] linearizableReadLoop","detail":"{readStateIndex:5269; appliedIndex:5267; }","duration":"166.983773ms","start":"2026-03-13T23:13:17.168219Z","end":"2026-03-13T23:13:17.335203Z","steps":["trace[1996483255] 'read index received' (duration: 34.044ms)","trace[1996483255] 'applied index is now lower than readState.Index' (duration: 132.937613ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:13:17.335311Z","caller":"traceutil/trace.go:171","msg":"trace[1524643138] transaction","detail":"{read_only:false; response_revision:5077; number_of_response:1; }","duration":"171.780339ms","start":"2026-03-13T23:13:17.163485Z","end":"2026-03-13T23:13:17.335265Z","steps":["trace[1524643138] 'process raft request' (duration: 73.645173ms)","trace[1524643138] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/messaging-topology-operator-leader-election; req_size:551; } (duration: 97.663094ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:13:17.343352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.113155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lp5sr\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-13T23:13:17.343449Z","caller":"traceutil/trace.go:171","msg":"trace[1258906953] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lp5sr; range_end:; response_count:1; response_revision:5077; }","duration":"175.276588ms","start":"2026-03-13T23:13:17.168151Z","end":"2026-03-13T23:13:17.343427Z","steps":["trace[1258906953] 'agreement among raft nodes before linearized reading' (duration: 167.19112ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:17.343465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.42236ms","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-03-13T23:13:17.343508Z","caller":"traceutil/trace.go:171","msg":"trace[1322591763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5077; }","duration":"173.485862ms","start":"2026-03-13T23:13:17.170008Z","end":"2026-03-13T23:13:17.343494Z","steps":["trace[1322591763] 'agreement among raft nodes before linearized reading' (duration: 173.40071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:17.343555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.39806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-03-13T23:13:17.343655Z","caller":"traceutil/trace.go:171","msg":"trace[971784160] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5077; }","duration":"173.540363ms","start":"2026-03-13T23:13:17.17009Z","end":"2026-03-13T23:13:17.34363Z","steps":["trace[971784160] 'agreement among raft nodes before linearized reading' (duration: 173.245846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:17.343674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.359515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-13T23:13:17.343356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.250692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-13T23:13:17.343706Z","caller":"traceutil/trace.go:171","msg":"trace[1697760760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5077; }","duration":"174.405907ms","start":"2026-03-13T23:13:17.169291Z","end":"2026-03-13T23:13:17.343697Z","steps":["trace[1697760760] 'agreement among raft nodes before linearized reading' (duration: 174.326114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:17.343682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.387777ms","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-03-13T23:13:17.343779Z","caller":"traceutil/trace.go:171","msg":"trace[1497160283] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5077; }","duration":"174.54959ms","start":"2026-03-13T23:13:17.169205Z","end":"2026-03-13T23:13:17.343754Z","steps":["trace[1497160283] 'agreement among raft nodes before linearized reading' (duration: 174.255882ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:17.343768Z","caller":"traceutil/trace.go:171","msg":"trace[1211812492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5077; }","duration":"174.689324ms","start":"2026-03-13T23:13:17.169048Z","end":"2026-03-13T23:13:17.343737Z","steps":["trace[1211812492] 'agreement among raft nodes before linearized reading' (duration: 174.200451ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:17.343795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.461422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-03-13T23:13:17.343892Z","caller":"traceutil/trace.go:171","msg":"trace[777864549] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5077; }","duration":"120.592676ms","start":"2026-03-13T23:13:17.223273Z","end":"2026-03-13T23:13:17.343865Z","steps":["trace[777864549] 'agreement among raft nodes before linearized reading' (duration: 120.296258ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:17.343856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.522169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:13:17.344029Z","caller":"traceutil/trace.go:171","msg":"trace[1078438656] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:5077; }","duration":"143.681593ms","start":"2026-03-13T23:13:17.200287Z","end":"2026-03-13T23:13:17.343968Z","steps":["trace[1078438656] 'agreement among raft nodes before linearized reading' (duration: 143.409286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:17.344299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.051673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-13T23:13:17.34437Z","caller":"traceutil/trace.go:171","msg":"trace[1062018327] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5077; }","duration":"175.139306ms","start":"2026-03-13T23:13:17.169213Z","end":"2026-03-13T23:13:17.344353Z","steps":["trace[1062018327] 'agreement among raft nodes before linearized reading' (duration: 174.963341ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:24.200899Z","caller":"traceutil/trace.go:171","msg":"trace[1452736962] transaction","detail":"{read_only:false; response_revision:5169; number_of_response:1; }","duration":"151.564686ms","start":"2026-03-13T23:13:24.049305Z","end":"2026-03-13T23:13:24.20087Z","steps":["trace[1452736962] 'process raft request' (duration: 150.667053ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:28.435204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.404536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-03-13T23:13:28.435374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.712596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:13:28.435482Z","caller":"traceutil/trace.go:171","msg":"trace[1249134115] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:5195; }","duration":"128.846089ms","start":"2026-03-13T23:13:28.306611Z","end":"2026-03-13T23:13:28.435457Z","steps":["trace[1249134115] 'count revisions from in-memory index tree' (duration: 128.599004ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:28.435254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.99992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-03-13T23:13:28.43577Z","caller":"traceutil/trace.go:171","msg":"trace[288122603] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5195; }","duration":"358.581485ms","start":"2026-03-13T23:13:28.077158Z","end":"2026-03-13T23:13:28.43574Z","steps":["trace[288122603] 'range keys from in-memory index tree' (duration: 357.760164ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:28.435997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:13:28.077132Z","time spent":"358.705989ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-03-13T23:13:28.435398Z","caller":"traceutil/trace.go:171","msg":"trace[1907131792] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5195; }","duration":"263.629092ms","start":"2026-03-13T23:13:28.17171Z","end":"2026-03-13T23:13:28.43534Z","steps":["trace[1907131792] 'range keys from in-memory index tree' (duration: 263.093988ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:28.876404Z","caller":"traceutil/trace.go:171","msg":"trace[1311955741] transaction","detail":"{read_only:false; response_revision:5196; number_of_response:1; }","duration":"429.791426ms","start":"2026-03-13T23:13:28.446589Z","end":"2026-03-13T23:13:28.876381Z","steps":["trace[1311955741] 'process raft request' (duration: 429.61813ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:28.876549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:13:28.446535Z","time spent":"429.942209ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:13:29.039986Z","caller":"traceutil/trace.go:171","msg":"trace[1598904084] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"154.645006ms","start":"2026-03-13T23:13:28.885325Z","end":"2026-03-13T23:13:29.03997Z","steps":["trace[1598904084] 'process raft request' (duration: 154.533493ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:29.039971Z","caller":"traceutil/trace.go:171","msg":"trace[1379806365] transaction","detail":"{read_only:false; response_revision:5199; number_of_response:1; }","duration":"154.949243ms","start":"2026-03-13T23:13:28.884989Z","end":"2026-03-13T23:13:29.039938Z","steps":["trace[1379806365] 'process raft request' (duration: 154.80085ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:29.040343Z","caller":"traceutil/trace.go:171","msg":"trace[474535156] transaction","detail":"{read_only:false; response_revision:5201; number_of_response:1; }","duration":"144.883824ms","start":"2026-03-13T23:13:28.89545Z","end":"2026-03-13T23:13:29.040334Z","steps":["trace[474535156] 'process raft request' (duration: 144.454402ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:29.03995Z","caller":"traceutil/trace.go:171","msg":"trace[1669111470] transaction","detail":"{read_only:false; response_revision:5198; number_of_response:1; }","duration":"155.073206ms","start":"2026-03-13T23:13:28.884825Z","end":"2026-03-13T23:13:29.039899Z","steps":["trace[1669111470] 'process raft request' (duration: 134.660919ms)","trace[1669111470] 'compare' (duration: 20.138541ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:13:29.218772Z","caller":"traceutil/trace.go:171","msg":"trace[2085754690] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"110.623079ms","start":"2026-03-13T23:13:29.108097Z","end":"2026-03-13T23:13:29.21872Z","steps":["trace[2085754690] 'process raft request' (duration: 110.29254ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:29.483968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.464428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:13:29.484075Z","caller":"traceutil/trace.go:171","msg":"trace[846462260] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:5204; }","duration":"157.623274ms","start":"2026-03-13T23:13:29.326428Z","end":"2026-03-13T23:13:29.484051Z","steps":["trace[846462260] 'count revisions from in-memory index tree' (duration: 157.380766ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:31.271127Z","caller":"traceutil/trace.go:171","msg":"trace[744156166] transaction","detail":"{read_only:false; response_revision:5220; number_of_response:1; }","duration":"157.988822ms","start":"2026-03-13T23:13:31.113104Z","end":"2026-03-13T23:13:31.271093Z","steps":["trace[744156166] 'process raft request' (duration: 157.752386ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:31.45605Z","caller":"traceutil/trace.go:171","msg":"trace[1341204503] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"330.674904ms","start":"2026-03-13T23:13:31.125344Z","end":"2026-03-13T23:13:31.456019Z","steps":["trace[1341204503] 'process raft request' (duration: 279.00315ms)","trace[1341204503] 'compare' (duration: 51.416388ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:13:31.45627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:13:31.125318Z","time spent":"330.83393ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:13:31.456446Z","caller":"traceutil/trace.go:171","msg":"trace[1890914767] linearizableReadLoop","detail":"{readStateIndex:5416; appliedIndex:5414; }","duration":"249.274961ms","start":"2026-03-13T23:13:31.207137Z","end":"2026-03-13T23:13:31.456412Z","steps":["trace[1890914767] 'read index received' (duration: 63.728206ms)","trace[1890914767] 'applied index is now lower than readState.Index' (duration: 185.543535ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:13:31.456581Z","caller":"traceutil/trace.go:171","msg":"trace[293428078] transaction","detail":"{read_only:false; response_revision:5222; number_of_response:1; }","duration":"202.495753ms","start":"2026-03-13T23:13:31.254071Z","end":"2026-03-13T23:13:31.456566Z","steps":["trace[293428078] 'process raft request' (duration: 201.885627ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:31.456761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.597099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-03-13T23:13:31.456798Z","caller":"traceutil/trace.go:171","msg":"trace[1142505297] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5222; }","duration":"249.706151ms","start":"2026-03-13T23:13:31.207079Z","end":"2026-03-13T23:13:31.456785Z","steps":["trace[1142505297] 'agreement among raft nodes before linearized reading' (duration: 249.500556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:31.738595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.720061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:13:31.738709Z","caller":"traceutil/trace.go:171","msg":"trace[723415782] linearizableReadLoop","detail":"{readStateIndex:5418; appliedIndex:5417; }","duration":"234.51865ms","start":"2026-03-13T23:13:31.504174Z","end":"2026-03-13T23:13:31.738692Z","steps":["trace[723415782] 'read index received' (duration: 94.588114ms)","trace[723415782] 'applied index is now lower than readState.Index' (duration: 139.929745ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:13:31.738749Z","caller":"traceutil/trace.go:171","msg":"trace[1073020548] transaction","detail":"{read_only:false; response_revision:5223; number_of_response:1; }","duration":"240.398311ms","start":"2026-03-13T23:13:31.498318Z","end":"2026-03-13T23:13:31.738717Z","steps":["trace[1073020548] 'process raft request' (duration: 100.440635ms)","trace[1073020548] 'compare' (duration: 139.584817ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:13:31.738903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.738095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lp5sr\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-13T23:13:31.738931Z","caller":"traceutil/trace.go:171","msg":"trace[370214148] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lp5sr; range_end:; response_count:1; response_revision:5223; }","duration":"234.810737ms","start":"2026-03-13T23:13:31.504112Z","end":"2026-03-13T23:13:31.738923Z","steps":["trace[370214148] 'agreement among raft nodes before linearized reading' (duration: 234.668764ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:31.739192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.719254ms","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-03-13T23:13:31.739251Z","caller":"traceutil/trace.go:171","msg":"trace[104881496] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5223; }","duration":"191.810936ms","start":"2026-03-13T23:13:31.547422Z","end":"2026-03-13T23:13:31.739233Z","steps":["trace[104881496] 'agreement among raft nodes before linearized reading' (duration: 191.631041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:31.739245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.923922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-13T23:13:31.739335Z","caller":"traceutil/trace.go:171","msg":"trace[1454747238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5223; }","duration":"220.053666ms","start":"2026-03-13T23:13:31.519262Z","end":"2026-03-13T23:13:31.739316Z","steps":["trace[1454747238] 'agreement among raft nodes before linearized reading' (duration: 219.788009ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:31.739408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.243691ms","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-03-13T23:13:31.739461Z","caller":"traceutil/trace.go:171","msg":"trace[372994609] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5223; }","duration":"191.355924ms","start":"2026-03-13T23:13:31.548095Z","end":"2026-03-13T23:13:31.739451Z","steps":["trace[372994609] 'agreement among raft nodes before linearized reading' (duration: 191.120568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:31.73943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.448186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-03-13T23:13:31.739512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.244976ms","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-03-13T23:13:31.739413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.020091ms","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-03-13T23:13:31.739546Z","caller":"traceutil/trace.go:171","msg":"trace[316464228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5223; }","duration":"192.298758ms","start":"2026-03-13T23:13:31.547239Z","end":"2026-03-13T23:13:31.739538Z","steps":["trace[316464228] 'agreement among raft nodes before linearized reading' (duration: 192.210116ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:31.739562Z","caller":"traceutil/trace.go:171","msg":"trace[2125697046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5223; }","duration":"192.224366ms","start":"2026-03-13T23:13:31.547324Z","end":"2026-03-13T23:13:31.739548Z","steps":["trace[2125697046] 'agreement among raft nodes before linearized reading' (duration: 192.034562ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:31.739545Z","caller":"traceutil/trace.go:171","msg":"trace[781178459] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5223; }","duration":"191.593069ms","start":"2026-03-13T23:13:31.547916Z","end":"2026-03-13T23:13:31.739509Z","steps":["trace[781178459] 'agreement among raft nodes before linearized reading' (duration: 191.309263ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:31.86884Z","caller":"traceutil/trace.go:171","msg":"trace[645004851] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"125.317578ms","start":"2026-03-13T23:13:31.743499Z","end":"2026-03-13T23:13:31.868816Z","steps":["trace[645004851] 'process raft request' (duration: 115.499834ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:31.868943Z","caller":"traceutil/trace.go:171","msg":"trace[711425002] transaction","detail":"{read_only:false; response_revision:5226; number_of_response:1; }","duration":"125.128044ms","start":"2026-03-13T23:13:31.743787Z","end":"2026-03-13T23:13:31.868915Z","steps":["trace[711425002] 'process raft request' (duration: 124.96583ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:31.868947Z","caller":"traceutil/trace.go:171","msg":"trace[503813437] linearizableReadLoop","detail":"{readStateIndex:5420; appliedIndex:5418; }","duration":"125.39021ms","start":"2026-03-13T23:13:31.743527Z","end":"2026-03-13T23:13:31.868917Z","steps":["trace[503813437] 'read index received' (duration: 48.607275ms)","trace[503813437] 'applied index is now lower than readState.Index' (duration: 76.781795ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:13:31.869034Z","caller":"traceutil/trace.go:171","msg":"trace[954839514] transaction","detail":"{read_only:false; response_revision:5228; number_of_response:1; }","duration":"124.753713ms","start":"2026-03-13T23:13:31.744263Z","end":"2026-03-13T23:13:31.869017Z","steps":["trace[954839514] 'process raft request' (duration: 124.575029ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:31.869133Z","caller":"traceutil/trace.go:171","msg":"trace[2135187768] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"124.092177ms","start":"2026-03-13T23:13:31.745031Z","end":"2026-03-13T23:13:31.869123Z","steps":["trace[2135187768] 'process raft request' (duration: 123.83767ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:31.869141Z","caller":"traceutil/trace.go:171","msg":"trace[312064462] transaction","detail":"{read_only:false; response_revision:5227; number_of_response:1; }","duration":"125.169904ms","start":"2026-03-13T23:13:31.743962Z","end":"2026-03-13T23:13:31.869132Z","steps":["trace[312064462] 'process raft request' (duration: 124.841426ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:31.869302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.75831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:81781"} {"level":"info","ts":"2026-03-13T23:13:31.869347Z","caller":"traceutil/trace.go:171","msg":"trace[1031337122] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5229; }","duration":"125.865533ms","start":"2026-03-13T23:13:31.743471Z","end":"2026-03-13T23:13:31.869337Z","steps":["trace[1031337122] 'agreement among raft nodes before linearized reading' (duration: 125.565664ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:14:12.126926Z","caller":"traceutil/trace.go:171","msg":"trace[2134231240] transaction","detail":"{read_only:false; response_revision:5553; number_of_response:1; }","duration":"140.026801ms","start":"2026-03-13T23:14:11.986855Z","end":"2026-03-13T23:14:12.126882Z","steps":["trace[2134231240] 'process raft request' (duration: 139.361674ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:14:22.532206Z","caller":"traceutil/trace.go:171","msg":"trace[1543925811] linearizableReadLoop","detail":"{readStateIndex:5839; appliedIndex:5838; }","duration":"153.334995ms","start":"2026-03-13T23:14:22.378825Z","end":"2026-03-13T23:14:22.53216Z","steps":["trace[1543925811] 'read index received' (duration: 131.247876ms)","trace[1543925811] 'applied index is now lower than readState.Index' (duration: 22.085409ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:14:22.532259Z","caller":"traceutil/trace.go:171","msg":"trace[804703772] transaction","detail":"{read_only:false; response_revision:5631; number_of_response:1; }","duration":"182.081177ms","start":"2026-03-13T23:14:22.35014Z","end":"2026-03-13T23:14:22.532221Z","steps":["trace[804703772] 'process raft request' (duration: 159.886345ms)","trace[804703772] 'compare' (duration: 21.805882ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:14:22.53244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.582842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:14:22.53258Z","caller":"traceutil/trace.go:171","msg":"trace[1057950709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5631; }","duration":"153.775866ms","start":"2026-03-13T23:14:22.37877Z","end":"2026-03-13T23:14:22.532546Z","steps":["trace[1057950709] 'agreement among raft nodes before linearized reading' (duration: 153.545151ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:14:28.952343Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2765} {"level":"info","ts":"2026-03-13T23:14:29.014886Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2765,"took":"60.801598ms","hash":3395412639,"current-db-size-bytes":40517632,"current-db-size":"40 MB","current-db-size-in-use-bytes":30375936,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-13T23:14:29.014983Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3395412639,"revision":2765,"compact-revision":-1} {"level":"info","ts":"2026-03-13T23:18:58.200234Z","caller":"traceutil/trace.go:171","msg":"trace[1780698861] transaction","detail":"{read_only:false; response_revision:7411; number_of_response:1; }","duration":"273.907657ms","start":"2026-03-13T23:18:57.926301Z","end":"2026-03-13T23:18:58.200209Z","steps":["trace[1780698861] 'process raft request' (duration: 273.758094ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:10.324646Z","caller":"traceutil/trace.go:171","msg":"trace[1502687510] transaction","detail":"{read_only:false; response_revision:7513; number_of_response:1; }","duration":"117.353125ms","start":"2026-03-13T23:19:10.207258Z","end":"2026-03-13T23:19:10.324612Z","steps":["trace[1502687510] 'process raft request' (duration: 117.112139ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:10.325083Z","caller":"traceutil/trace.go:171","msg":"trace[1716610903] transaction","detail":"{read_only:false; response_revision:7515; number_of_response:1; }","duration":"114.937164ms","start":"2026-03-13T23:19:10.210114Z","end":"2026-03-13T23:19:10.325051Z","steps":["trace[1716610903] 'process raft request' (duration: 114.714637ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:10.325104Z","caller":"traceutil/trace.go:171","msg":"trace[220248632] transaction","detail":"{read_only:false; response_revision:7514; number_of_response:1; }","duration":"117.604072ms","start":"2026-03-13T23:19:10.207424Z","end":"2026-03-13T23:19:10.325028Z","steps":["trace[220248632] 'process raft request' (duration: 117.111569ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:13.571132Z","caller":"traceutil/trace.go:171","msg":"trace[1711167338] transaction","detail":"{read_only:false; response_revision:7531; number_of_response:1; }","duration":"203.101867ms","start":"2026-03-13T23:19:13.367838Z","end":"2026-03-13T23:19:13.57094Z","steps":["trace[1711167338] 'process raft request' (duration: 121.235906ms)","trace[1711167338] 'compare' (duration: 81.730487ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:19:13.627794Z","caller":"traceutil/trace.go:171","msg":"trace[34674895] linearizableReadLoop","detail":"{readStateIndex:7811; appliedIndex:7808; }","duration":"179.627291ms","start":"2026-03-13T23:19:13.448152Z","end":"2026-03-13T23:19:13.627779Z","steps":["trace[34674895] 'read index received' (duration: 41.009577ms)","trace[34674895] 'applied index is now lower than readState.Index' (duration: 138.616574ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:19:13.627983Z","caller":"traceutil/trace.go:171","msg":"trace[1083005452] transaction","detail":"{read_only:false; response_revision:7532; number_of_response:1; }","duration":"230.773511ms","start":"2026-03-13T23:19:13.397201Z","end":"2026-03-13T23:19:13.627974Z","steps":["trace[1083005452] 'process raft request' (duration: 230.459952ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:13.628168Z","caller":"traceutil/trace.go:171","msg":"trace[674007745] transaction","detail":"{read_only:false; response_revision:7533; number_of_response:1; }","duration":"230.827012ms","start":"2026-03-13T23:19:13.397332Z","end":"2026-03-13T23:19:13.628159Z","steps":["trace[674007745] 'process raft request' (duration: 230.408901ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:13.628329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.171056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:19:13.628362Z","caller":"traceutil/trace.go:171","msg":"trace[1359047284] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:7533; }","duration":"180.269018ms","start":"2026-03-13T23:19:13.448082Z","end":"2026-03-13T23:19:13.628351Z","steps":["trace[1359047284] 'agreement among raft nodes before linearized reading' (duration: 180.174936ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:13.715252Z","caller":"traceutil/trace.go:171","msg":"trace[648680336] transaction","detail":"{read_only:false; response_revision:7534; number_of_response:1; }","duration":"134.386745ms","start":"2026-03-13T23:19:13.580809Z","end":"2026-03-13T23:19:13.715196Z","steps":["trace[648680336] 'process raft request' (duration: 115.14719ms)","trace[648680336] 'compare' (duration: 18.836705ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:19:13.716241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.968764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:19:13.716333Z","caller":"traceutil/trace.go:171","msg":"trace[1744997489] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:7534; }","duration":"101.107527ms","start":"2026-03-13T23:19:13.615203Z","end":"2026-03-13T23:19:13.716311Z","steps":["trace[1744997489] 'agreement among raft nodes before linearized reading' (duration: 100.320437ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:18.505425Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830444266167941,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T23:19:18.611243Z","caller":"traceutil/trace.go:171","msg":"trace[1934744189] transaction","detail":"{read_only:false; response_revision:7680; number_of_response:1; }","duration":"608.534252ms","start":"2026-03-13T23:19:18.002683Z","end":"2026-03-13T23:19:18.611217Z","steps":["trace[1934744189] 'process raft request' (duration: 608.349507ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:18.61143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:18.002664Z","time spent":"608.680655ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:19:18.675661Z","caller":"traceutil/trace.go:171","msg":"trace[2124252634] linearizableReadLoop","detail":"{readStateIndex:7960; appliedIndex:7958; }","duration":"671.32115ms","start":"2026-03-13T23:19:18.004318Z","end":"2026-03-13T23:19:18.675639Z","steps":["trace[2124252634] 'read index received' (duration: 606.689263ms)","trace[2124252634] 'applied index is now lower than readState.Index' (duration: 64.630707ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:19:18.675705Z","caller":"traceutil/trace.go:171","msg":"trace[332554980] transaction","detail":"{read_only:false; response_revision:7681; number_of_response:1; }","duration":"671.782392ms","start":"2026-03-13T23:19:18.00389Z","end":"2026-03-13T23:19:18.675672Z","steps":["trace[332554980] 'process raft request' (duration: 671.35284ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:18.675881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:18.003864Z","time spent":"671.929526ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:19:18.675901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.463721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:19:18.675934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.044033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-13T23:19:18.675977Z","caller":"traceutil/trace.go:171","msg":"trace[401686336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7681; }","duration":"664.099344ms","start":"2026-03-13T23:19:18.011867Z","end":"2026-03-13T23:19:18.675966Z","steps":["trace[401686336] 'agreement among raft nodes before linearized reading' (duration: 663.91834ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:18.675983Z","caller":"traceutil/trace.go:171","msg":"trace[608282747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7681; }","duration":"297.581113ms","start":"2026-03-13T23:19:18.37838Z","end":"2026-03-13T23:19:18.675961Z","steps":["trace[608282747] 'agreement among raft nodes before linearized reading' (duration: 297.44304ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:18.676019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:18.011836Z","time spent":"664.172026ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-13T23:19:18.675987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.645928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-03-13T23:19:18.676075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.786035ms","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-03-13T23:19:18.67611Z","caller":"traceutil/trace.go:171","msg":"trace[2090592093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7681; }","duration":"671.817092ms","start":"2026-03-13T23:19:18.00427Z","end":"2026-03-13T23:19:18.676087Z","steps":["trace[2090592093] 'agreement among raft nodes before linearized reading' (duration: 671.451623ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:18.676133Z","caller":"traceutil/trace.go:171","msg":"trace[1812278668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7681; }","duration":"663.878578ms","start":"2026-03-13T23:19:18.012239Z","end":"2026-03-13T23:19:18.676118Z","steps":["trace[1812278668] 'agreement among raft nodes before linearized reading' (duration: 663.677163ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:18.676172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:18.004251Z","time spent":"671.909205ms","remote":"127.0.0.1:53332","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-03-13T23:19:18.676185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:18.012219Z","time spent":"663.95788ms","remote":"127.0.0.1:53332","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-03-13T23:19:19.319749Z","caller":"traceutil/trace.go:171","msg":"trace[908725520] transaction","detail":"{read_only:false; response_revision:7683; number_of_response:1; }","duration":"638.70494ms","start":"2026-03-13T23:19:18.680999Z","end":"2026-03-13T23:19:19.319704Z","steps":["trace[908725520] 'process raft request' (duration: 592.100438ms)","trace[908725520] 'compare' (duration: 46.034897ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:19:19.31985Z","caller":"traceutil/trace.go:171","msg":"trace[2066621160] transaction","detail":"{read_only:false; response_revision:7687; number_of_response:1; }","duration":"136.250154ms","start":"2026-03-13T23:19:19.183572Z","end":"2026-03-13T23:19:19.319822Z","steps":["trace[2066621160] 'process raft request' (duration: 136.202723ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:19.319962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:18.680977Z","time spent":"638.874384ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:19:19.320133Z","caller":"traceutil/trace.go:171","msg":"trace[1501509554] transaction","detail":"{read_only:false; response_revision:7684; number_of_response:1; }","duration":"639.036138ms","start":"2026-03-13T23:19:18.681087Z","end":"2026-03-13T23:19:19.320123Z","steps":["trace[1501509554] 'process raft request' (duration: 638.471793ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:19.320248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:18.681077Z","time spent":"639.090689ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:19:19.320444Z","caller":"traceutil/trace.go:171","msg":"trace[1103220484] transaction","detail":"{read_only:false; response_revision:7686; number_of_response:1; }","duration":"211.454713ms","start":"2026-03-13T23:19:19.108938Z","end":"2026-03-13T23:19:19.320393Z","steps":["trace[1103220484] 'process raft request' (duration: 210.794695ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:19.320723Z","caller":"traceutil/trace.go:171","msg":"trace[325921393] linearizableReadLoop","detail":"{readStateIndex:7963; appliedIndex:7961; }","duration":"519.70376ms","start":"2026-03-13T23:19:18.801006Z","end":"2026-03-13T23:19:19.320709Z","steps":["trace[325921393] 'read index received' (duration: 472.137394ms)","trace[325921393] 'applied index is now lower than readState.Index' (duration: 47.564306ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:19:19.320836Z","caller":"traceutil/trace.go:171","msg":"trace[884375811] transaction","detail":"{read_only:false; response_revision:7685; number_of_response:1; }","duration":"375.197605ms","start":"2026-03-13T23:19:18.945628Z","end":"2026-03-13T23:19:19.320825Z","steps":["trace[884375811] 'process raft request' (duration: 374.055796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:19.320946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:18.945593Z","time spent":"375.272627ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:19:19.321275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.273136ms","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-03-13T23:19:19.321331Z","caller":"traceutil/trace.go:171","msg":"trace[1235748733] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7687; }","duration":"520.362258ms","start":"2026-03-13T23:19:18.800952Z","end":"2026-03-13T23:19:19.321314Z","steps":["trace[1235748733] 'agreement among raft nodes before linearized reading' (duration: 520.099132ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:19.321368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:18.800929Z","time spent":"520.431659ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-03-13T23:19:19.321607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.202819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-13T23:19:19.321653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.855611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lp5sr\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-13T23:19:19.321694Z","caller":"traceutil/trace.go:171","msg":"trace[739491017] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:7687; }","duration":"272.319712ms","start":"2026-03-13T23:19:19.049355Z","end":"2026-03-13T23:19:19.321674Z","steps":["trace[739491017] 'agreement among raft nodes before linearized reading' (duration: 272.133547ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:19.321664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.791524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:19:19.321742Z","caller":"traceutil/trace.go:171","msg":"trace[258842661] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lp5sr; range_end:; response_count:1; response_revision:7687; }","duration":"489.987234ms","start":"2026-03-13T23:19:18.831733Z","end":"2026-03-13T23:19:19.32172Z","steps":["trace[258842661] 'agreement among raft nodes before linearized reading' (duration: 489.731658ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:19.322834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:18.831708Z","time spent":"491.106352ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-lp5sr\" "} {"level":"info","ts":"2026-03-13T23:19:19.323038Z","caller":"traceutil/trace.go:171","msg":"trace[206689676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7687; }","duration":"420.916823ms","start":"2026-03-13T23:19:18.901776Z","end":"2026-03-13T23:19:19.322693Z","steps":["trace[206689676] 'agreement among raft nodes before linearized reading' (duration: 419.720962ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:19.323088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:18.901738Z","time spent":"421.338944ms","remote":"127.0.0.1:53098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-13T23:19:24.898725Z","caller":"traceutil/trace.go:171","msg":"trace[2096263128] transaction","detail":"{read_only:false; response_revision:7711; number_of_response:1; }","duration":"114.944104ms","start":"2026-03-13T23:19:24.783758Z","end":"2026-03-13T23:19:24.898702Z","steps":["trace[2096263128] 'process raft request' (duration: 114.704038ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:28.959569Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5666} {"level":"info","ts":"2026-03-13T23:19:29.322657Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5666,"took":"361.236285ms","hash":656817257,"current-db-size-bytes":43151360,"current-db-size":"43 MB","current-db-size-in-use-bytes":23326720,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-13T23:19:29.322743Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":656817257,"revision":5666,"compact-revision":2765} {"level":"warn","ts":"2026-03-13T23:19:29.322927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.561224ms","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-03-13T23:19:29.323038Z","caller":"traceutil/trace.go:171","msg":"trace[2070743785] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7729; }","duration":"133.747379ms","start":"2026-03-13T23:19:29.189267Z","end":"2026-03-13T23:19:29.323015Z","steps":["trace[2070743785] 'count revisions from in-memory index tree' (duration: 133.342759ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:29.77743Z","caller":"traceutil/trace.go:171","msg":"trace[1267651383] transaction","detail":"{read_only:false; response_revision:7736; number_of_response:1; }","duration":"137.158656ms","start":"2026-03-13T23:19:29.640244Z","end":"2026-03-13T23:19:29.777403Z","steps":["trace[1267651383] 'process raft request' (duration: 136.989512ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:30.641356Z","caller":"traceutil/trace.go:171","msg":"trace[1711997706] linearizableReadLoop","detail":"{readStateIndex:8020; appliedIndex:8019; }","duration":"375.458891ms","start":"2026-03-13T23:19:30.265871Z","end":"2026-03-13T23:19:30.64133Z","steps":["trace[1711997706] 'read index received' (duration: 375.256466ms)","trace[1711997706] 'applied index is now lower than readState.Index' (duration: 200.915µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:19:30.64147Z","caller":"traceutil/trace.go:171","msg":"trace[1550521478] transaction","detail":"{read_only:false; response_revision:7737; number_of_response:1; }","duration":"481.32244ms","start":"2026-03-13T23:19:30.160094Z","end":"2026-03-13T23:19:30.641416Z","steps":["trace[1550521478] 'process raft request' (duration: 481.013212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:30.641662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.204353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:125275"} {"level":"warn","ts":"2026-03-13T23:19:30.641691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:30.160076Z","time spent":"481.493035ms","remote":"127.0.0.1:53184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1117,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:19:30.641714Z","caller":"traceutil/trace.go:171","msg":"trace[594454645] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:7737; }","duration":"373.296916ms","start":"2026-03-13T23:19:30.268403Z","end":"2026-03-13T23:19:30.6417Z","steps":["trace[594454645] 'agreement among raft nodes before linearized reading' (duration: 373.08867ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:30.641763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:30.268391Z","time spent":"373.359528ms","remote":"127.0.0.1:53174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":125298,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-03-13T23:19:30.641729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.405558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:19:30.641779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.861851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-03-13T23:19:30.641924Z","caller":"traceutil/trace.go:171","msg":"trace[1057873460] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:7737; }","duration":"376.064377ms","start":"2026-03-13T23:19:30.265829Z","end":"2026-03-13T23:19:30.641894Z","steps":["trace[1057873460] 'agreement among raft nodes before linearized reading' (duration: 375.659326ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:30.641987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:30.265804Z","time spent":"376.17283ms","remote":"127.0.0.1:53178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-03-13T23:19:30.641834Z","caller":"traceutil/trace.go:171","msg":"trace[8256177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7737; }","duration":"264.557312ms","start":"2026-03-13T23:19:30.377255Z","end":"2026-03-13T23:19:30.641812Z","steps":["trace[8256177] 'agreement among raft nodes before linearized reading' (duration: 264.363927ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:30.872637Z","caller":"traceutil/trace.go:171","msg":"trace[66627452] transaction","detail":"{read_only:false; response_revision:7738; number_of_response:1; }","duration":"188.423129ms","start":"2026-03-13T23:19:30.684165Z","end":"2026-03-13T23:19:30.872588Z","steps":["trace[66627452] 'process raft request' (duration: 188.182933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:31.984978Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830444266168119,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T23:19:32.111025Z","caller":"traceutil/trace.go:171","msg":"trace[1020937755] transaction","detail":"{read_only:false; response_revision:7744; number_of_response:1; }","duration":"628.473835ms","start":"2026-03-13T23:19:31.482525Z","end":"2026-03-13T23:19:32.110999Z","steps":["trace[1020937755] 'process raft request' (duration: 628.414183ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:32.111132Z","caller":"traceutil/trace.go:171","msg":"trace[422077763] transaction","detail":"{read_only:false; response_revision:7743; number_of_response:1; }","duration":"628.612549ms","start":"2026-03-13T23:19:31.482473Z","end":"2026-03-13T23:19:32.111085Z","steps":["trace[422077763] 'process raft request' (duration: 628.27551ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:32.111182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:31.482499Z","time spent":"628.597749ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:19:32.111357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:31.482439Z","time spent":"628.802904ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:19:32.126772Z","caller":"traceutil/trace.go:171","msg":"trace[899111964] linearizableReadLoop","detail":"{readStateIndex:8028; appliedIndex:8025; }","duration":"641.962373ms","start":"2026-03-13T23:19:31.484785Z","end":"2026-03-13T23:19:32.126748Z","steps":["trace[899111964] 'read index received' (duration: 626.055073ms)","trace[899111964] 'applied index is now lower than readState.Index' (duration: 15.90612ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:19:32.126918Z","caller":"traceutil/trace.go:171","msg":"trace[1571094624] transaction","detail":"{read_only:false; response_revision:7745; number_of_response:1; }","duration":"329.996248ms","start":"2026-03-13T23:19:31.796886Z","end":"2026-03-13T23:19:32.126882Z","steps":["trace[1571094624] 'process raft request' (duration: 329.717502ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:32.127066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.27355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lp5sr\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-13T23:19:32.127124Z","caller":"traceutil/trace.go:171","msg":"trace[419906226] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lp5sr; range_end:; response_count:1; response_revision:7745; }","duration":"642.367773ms","start":"2026-03-13T23:19:31.484741Z","end":"2026-03-13T23:19:32.127109Z","steps":["trace[419906226] 'agreement among raft nodes before linearized reading' (duration: 642.118447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:32.12716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:31.484723Z","time spent":"642.429894ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-lp5sr\" "} {"level":"warn","ts":"2026-03-13T23:19:32.127148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:31.796855Z","time spent":"330.149452ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:19:32.333606Z","caller":"traceutil/trace.go:171","msg":"trace[411802007] linearizableReadLoop","detail":"{readStateIndex:8029; appliedIndex:8028; }","duration":"201.688761ms","start":"2026-03-13T23:19:32.131886Z","end":"2026-03-13T23:19:32.333574Z","steps":["trace[411802007] 'read index received' (duration: 154.871543ms)","trace[411802007] 'applied index is now lower than readState.Index' (duration: 46.815488ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:19:32.334293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.379478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223790"} {"level":"info","ts":"2026-03-13T23:19:32.33437Z","caller":"traceutil/trace.go:171","msg":"trace[1820437056] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:7745; }","duration":"202.473991ms","start":"2026-03-13T23:19:32.131879Z","end":"2026-03-13T23:19:32.334353Z","steps":["trace[1820437056] 'agreement among raft nodes before linearized reading' (duration: 201.801843ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:34.248862Z","caller":"traceutil/trace.go:171","msg":"trace[95398997] transaction","detail":"{read_only:false; response_revision:7754; number_of_response:1; }","duration":"126.859021ms","start":"2026-03-13T23:19:34.121974Z","end":"2026-03-13T23:19:34.248833Z","steps":["trace[95398997] 'process raft request' (duration: 126.653866ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:34.253223Z","caller":"traceutil/trace.go:171","msg":"trace[1729875789] transaction","detail":"{read_only:false; response_revision:7756; number_of_response:1; }","duration":"120.396774ms","start":"2026-03-13T23:19:34.132788Z","end":"2026-03-13T23:19:34.253184Z","steps":["trace[1729875789] 'process raft request' (duration: 120.302962ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:34.253451Z","caller":"traceutil/trace.go:171","msg":"trace[412476194] transaction","detail":"{read_only:false; response_revision:7755; number_of_response:1; }","duration":"131.379729ms","start":"2026-03-13T23:19:34.122055Z","end":"2026-03-13T23:19:34.253435Z","steps":["trace[412476194] 'process raft request' (duration: 130.478725ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:55.598051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.594872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:19:55.598256Z","caller":"traceutil/trace.go:171","msg":"trace[1602881934] transaction","detail":"{read_only:false; response_revision:7866; number_of_response:1; }","duration":"409.944832ms","start":"2026-03-13T23:19:55.188263Z","end":"2026-03-13T23:19:55.598208Z","steps":["trace[1602881934] 'process raft request' (duration: 102.100274ms)","trace[1602881934] 'compare' (duration: 307.410086ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:19:55.598319Z","caller":"traceutil/trace.go:171","msg":"trace[532886823] linearizableReadLoop","detail":"{readStateIndex:8154; appliedIndex:8153; }","duration":"244.272429ms","start":"2026-03-13T23:19:55.354011Z","end":"2026-03-13T23:19:55.598283Z","steps":["trace[532886823] 'read index received' (duration: 74.112µs)","trace[532886823] 'applied index is now lower than readState.Index' (duration: 244.195596ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:19:55.598627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.594538ms","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:5"} {"level":"info","ts":"2026-03-13T23:19:55.598698Z","caller":"traceutil/trace.go:171","msg":"trace[607978844] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:7867; }","duration":"244.715391ms","start":"2026-03-13T23:19:55.353959Z","end":"2026-03-13T23:19:55.598675Z","steps":["trace[607978844] 'agreement among raft nodes before linearized reading' (duration: 244.514225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:55.598782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:55.188237Z","time spent":"410.110835ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:19:59.305006Z","caller":"traceutil/trace.go:171","msg":"trace[1809870086] linearizableReadLoop","detail":"{readStateIndex:8217; appliedIndex:8216; }","duration":"115.55576ms","start":"2026-03-13T23:19:59.189423Z","end":"2026-03-13T23:19:59.304979Z","steps":["trace[1809870086] 'read index received' (duration: 115.374794ms)","trace[1809870086] 'applied index is now lower than readState.Index' (duration: 179.686µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:19:59.305144Z","caller":"traceutil/trace.go:171","msg":"trace[1841260658] transaction","detail":"{read_only:false; response_revision:7926; number_of_response:1; }","duration":"192.260968ms","start":"2026-03-13T23:19:59.112833Z","end":"2026-03-13T23:19:59.305094Z","steps":["trace[1841260658] 'process raft request' (duration: 191.938309ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:59.305242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.795996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-13T23:19:59.305284Z","caller":"traceutil/trace.go:171","msg":"trace[578845707] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7926; }","duration":"115.885888ms","start":"2026-03-13T23:19:59.189387Z","end":"2026-03-13T23:19:59.305273Z","steps":["trace[578845707] 'agreement among raft nodes before linearized reading' (duration: 115.696993ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:02.147465Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830444266168826,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T23:20:02.648124Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830444266168826,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T23:20:03.149041Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830444266168826,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T23:20:03.403532Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.786587287s","expected-duration":"1s"} {"level":"info","ts":"2026-03-13T23:20:03.40398Z","caller":"traceutil/trace.go:171","msg":"trace[1889922859] transaction","detail":"{read_only:false; response_revision:7941; number_of_response:1; }","duration":"1.787134282s","start":"2026-03-13T23:20:01.616821Z","end":"2026-03-13T23:20:03.403956Z","steps":["trace[1889922859] 'process raft request' (duration: 1.786968417s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:03.404136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:01.616795Z","time spent":"1.787259945s","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:20:03.405314Z","caller":"traceutil/trace.go:171","msg":"trace[1607924244] linearizableReadLoop","detail":"{readStateIndex:8234; appliedIndex:8232; }","duration":"1.758654278s","start":"2026-03-13T23:20:01.646636Z","end":"2026-03-13T23:20:03.40529Z","steps":["trace[1607924244] 'read index received' (duration: 1.757303634s)","trace[1607924244] 'applied index is now lower than readState.Index' (duration: 1.349504ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:20:03.405612Z","caller":"traceutil/trace.go:171","msg":"trace[211594253] transaction","detail":"{read_only:false; response_revision:7943; number_of_response:1; }","duration":"482.364118ms","start":"2026-03-13T23:20:02.923227Z","end":"2026-03-13T23:20:03.405591Z","steps":["trace[211594253] 'process raft request' (duration: 482.018848ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:03.405757Z","caller":"traceutil/trace.go:171","msg":"trace[465987347] transaction","detail":"{read_only:false; response_revision:7942; number_of_response:1; }","duration":"1.010907536s","start":"2026-03-13T23:20:02.394817Z","end":"2026-03-13T23:20:03.405725Z","steps":["trace[465987347] 'process raft request' (duration: 1.01027354s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:03.405983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.145614ms","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":"warn","ts":"2026-03-13T23:20:03.405897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:02.923194Z","time spent":"482.468931ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:20:03.406026Z","caller":"traceutil/trace.go:171","msg":"trace[1458178125] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7943; }","duration":"944.212537ms","start":"2026-03-13T23:20:02.461797Z","end":"2026-03-13T23:20:03.406009Z","steps":["trace[1458178125] 'agreement among raft nodes before linearized reading' (duration: 944.094573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:03.406022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.484018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-03-13T23:20:03.406055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:02.461778Z","time spent":"944.271358ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-03-13T23:20:03.406078Z","caller":"traceutil/trace.go:171","msg":"trace[1709613136] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7943; }","duration":"943.57332ms","start":"2026-03-13T23:20:02.462491Z","end":"2026-03-13T23:20:03.406064Z","steps":["trace[1709613136] 'agreement among raft nodes before linearized reading' (duration: 943.360554ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:03.406117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:02.462473Z","time spent":"943.635971ms","remote":"127.0.0.1:53332","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-03-13T23:20:03.406171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:02.394788Z","time spent":"1.011166823s","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:20:03.406287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027839413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:20:03.406316Z","caller":"traceutil/trace.go:171","msg":"trace[1699057080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7943; }","duration":"1.027906054s","start":"2026-03-13T23:20:02.378403Z","end":"2026-03-13T23:20:03.406309Z","steps":["trace[1699057080] 'agreement among raft nodes before linearized reading' (duration: 1.027862003s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:03.406334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:02.37838Z","time spent":"1.027950286s","remote":"127.0.0.1:53098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-13T23:20:03.406438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.267549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-03-13T23:20:03.406473Z","caller":"traceutil/trace.go:171","msg":"trace[793326610] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:7943; }","duration":"685.338081ms","start":"2026-03-13T23:20:02.721126Z","end":"2026-03-13T23:20:03.406464Z","steps":["trace[793326610] 'agreement among raft nodes before linearized reading' (duration: 685.261569ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:03.406497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:02.721104Z","time spent":"685.388353ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-03-13T23:20:03.405798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.717997989s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-03-13T23:20:03.406644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.447702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lp5sr\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-03-13T23:20:03.406659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.215826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:20:03.406681Z","caller":"traceutil/trace.go:171","msg":"trace[1389055388] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lp5sr; range_end:; response_count:1; response_revision:7943; }","duration":"849.518894ms","start":"2026-03-13T23:20:02.557154Z","end":"2026-03-13T23:20:03.406673Z","steps":["trace[1389055388] 'agreement among raft nodes before linearized reading' (duration: 849.431132ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:03.406711Z","caller":"traceutil/trace.go:171","msg":"trace[651411182] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:7943; }","duration":"837.312371ms","start":"2026-03-13T23:20:02.569384Z","end":"2026-03-13T23:20:03.406696Z","steps":["trace[651411182] 'agreement among raft nodes before linearized reading' (duration: 837.140556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:03.406699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.567616711s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"warn","ts":"2026-03-13T23:20:03.406727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:02.557132Z","time spent":"849.588407ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-lp5sr\" "} {"level":"warn","ts":"2026-03-13T23:20:03.406742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:02.569359Z","time spent":"837.374363ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":72,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-03-13T23:20:03.406754Z","caller":"traceutil/trace.go:171","msg":"trace[2124668600] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:7943; }","duration":"1.567781956s","start":"2026-03-13T23:20:01.838958Z","end":"2026-03-13T23:20:03.40674Z","steps":["trace[2124668600] 'agreement among raft nodes before linearized reading' (duration: 1.567346354s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:03.40683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:01.838935Z","time spent":"1.567877369s","remote":"127.0.0.1:53286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2009,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-03-13T23:20:03.405877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.759245123s","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-03-13T23:20:03.406888Z","caller":"traceutil/trace.go:171","msg":"trace[962826562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7943; }","duration":"1.760314071s","start":"2026-03-13T23:20:01.646564Z","end":"2026-03-13T23:20:03.406878Z","steps":["trace[962826562] 'agreement among raft nodes before linearized reading' (duration: 1.759269284s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:03.406935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:01.646499Z","time spent":"1.760424003s","remote":"127.0.0.1:53332","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-03-13T23:20:03.406775Z","caller":"traceutil/trace.go:171","msg":"trace[1016929985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7943; }","duration":"1.718953273s","start":"2026-03-13T23:20:01.687714Z","end":"2026-03-13T23:20:03.406667Z","steps":["trace[1016929985] 'agreement among raft nodes before linearized reading' (duration: 1.717906787s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:03.407141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:01.687681Z","time spent":"1.719446146s","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T23:20:03.406613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.313976281s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-13T23:20:03.407254Z","caller":"traceutil/trace.go:171","msg":"trace[1350016720] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:7943; }","duration":"1.314624938s","start":"2026-03-13T23:20:02.092598Z","end":"2026-03-13T23:20:03.407223Z","steps":["trace[1350016720] 'agreement among raft nodes before linearized reading' (duration: 1.313785796s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:03.407336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:02.09253Z","time spent":"1.314787832s","remote":"127.0.0.1:53144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"warn","ts":"2026-03-13T23:20:03.407367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.540687228s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-03-13T23:20:03.407418Z","caller":"traceutil/trace.go:171","msg":"trace[1071118371] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:7943; }","duration":"1.540991836s","start":"2026-03-13T23:20:01.86641Z","end":"2026-03-13T23:20:03.407401Z","steps":["trace[1071118371] 'agreement among raft nodes before linearized reading' (duration: 1.540654707s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:03.407507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:01.866384Z","time spent":"1.541070697s","remote":"127.0.0.1:53264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-03-13T23:20:09.5517Z","caller":"traceutil/trace.go:171","msg":"trace[210376356] linearizableReadLoop","detail":"{readStateIndex:8279; appliedIndex:8278; }","duration":"125.431304ms","start":"2026-03-13T23:20:09.426238Z","end":"2026-03-13T23:20:09.551669Z","steps":["trace[210376356] 'read index received' (duration: 35.597458ms)","trace[210376356] 'applied index is now lower than readState.Index' (duration: 89.831496ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:20:09.551835Z","caller":"traceutil/trace.go:171","msg":"trace[2132740354] transaction","detail":"{read_only:false; response_revision:7985; number_of_response:1; }","duration":"130.869335ms","start":"2026-03-13T23:20:09.42095Z","end":"2026-03-13T23:20:09.551819Z","steps":["trace[2132740354] 'process raft request' (duration: 40.875165ms)","trace[2132740354] 'compare' (duration: 89.372124ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:20:09.551949Z","caller":"traceutil/trace.go:171","msg":"trace[1280727387] transaction","detail":"{read_only:false; response_revision:7986; number_of_response:1; }","duration":"122.632652ms","start":"2026-03-13T23:20:09.429308Z","end":"2026-03-13T23:20:09.551941Z","steps":["trace[1280727387] 'process raft request' (duration: 122.260083ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:09.551816Z","caller":"traceutil/trace.go:171","msg":"trace[638997590] transaction","detail":"{read_only:false; response_revision:7991; number_of_response:1; }","duration":"118.770203ms","start":"2026-03-13T23:20:09.433026Z","end":"2026-03-13T23:20:09.551797Z","steps":["trace[638997590] 'process raft request' (duration: 118.706952ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:09.552072Z","caller":"traceutil/trace.go:171","msg":"trace[99281875] transaction","detail":"{read_only:false; response_revision:7992; number_of_response:1; }","duration":"118.69918ms","start":"2026-03-13T23:20:09.433362Z","end":"2026-03-13T23:20:09.552061Z","steps":["trace[99281875] 'process raft request' (duration: 118.404542ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:09.552128Z","caller":"traceutil/trace.go:171","msg":"trace[1905795483] transaction","detail":"{read_only:false; response_revision:7987; number_of_response:1; }","duration":"121.237757ms","start":"2026-03-13T23:20:09.430878Z","end":"2026-03-13T23:20:09.552116Z","steps":["trace[1905795483] 'process raft request' (duration: 120.743164ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:09.552295Z","caller":"traceutil/trace.go:171","msg":"trace[320722104] transaction","detail":"{read_only:false; response_revision:7988; number_of_response:1; }","duration":"119.404039ms","start":"2026-03-13T23:20:09.432879Z","end":"2026-03-13T23:20:09.552283Z","steps":["trace[320722104] 'process raft request' (duration: 118.770763ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:09.552356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.945607ms","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-03-13T23:20:09.552392Z","caller":"traceutil/trace.go:171","msg":"trace[777210098] transaction","detail":"{read_only:false; response_revision:7989; number_of_response:1; }","duration":"119.44593ms","start":"2026-03-13T23:20:09.432938Z","end":"2026-03-13T23:20:09.552383Z","steps":["trace[777210098] 'process raft request' (duration: 118.739492ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:09.552476Z","caller":"traceutil/trace.go:171","msg":"trace[1343711121] transaction","detail":"{read_only:false; response_revision:7990; number_of_response:1; }","duration":"119.489272ms","start":"2026-03-13T23:20:09.432978Z","end":"2026-03-13T23:20:09.552468Z","steps":["trace[1343711121] 'process raft request' (duration: 118.722642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:09.552593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.357617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-03-13T23:20:09.552393Z","caller":"traceutil/trace.go:171","msg":"trace[811819612] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7993; }","duration":"119.013228ms","start":"2026-03-13T23:20:09.43337Z","end":"2026-03-13T23:20:09.552384Z","steps":["trace[811819612] 'agreement among raft nodes before linearized reading' (duration: 118.885495ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:09.552676Z","caller":"traceutil/trace.go:171","msg":"trace[1896566815] transaction","detail":"{read_only:false; response_revision:7993; number_of_response:1; }","duration":"104.928245ms","start":"2026-03-13T23:20:09.447742Z","end":"2026-03-13T23:20:09.552671Z","steps":["trace[1896566815] 'process raft request' (duration: 104.166046ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:09.552648Z","caller":"traceutil/trace.go:171","msg":"trace[911415363] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:7993; }","duration":"126.402858ms","start":"2026-03-13T23:20:09.426215Z","end":"2026-03-13T23:20:09.552617Z","steps":["trace[911415363] 'agreement among raft nodes before linearized reading' (duration: 125.831773ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:09.552953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.477716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-13T23:20:09.55298Z","caller":"traceutil/trace.go:171","msg":"trace[666269194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7993; }","duration":"101.527818ms","start":"2026-03-13T23:20:09.451446Z","end":"2026-03-13T23:20:09.552974Z","steps":["trace[666269194] 'agreement among raft nodes before linearized reading' (duration: 101.460256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:09.553072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.059532ms","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-03-13T23:20:09.55309Z","caller":"traceutil/trace.go:171","msg":"trace[301184771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7993; }","duration":"102.093113ms","start":"2026-03-13T23:20:09.450992Z","end":"2026-03-13T23:20:09.553085Z","steps":["trace[301184771] 'agreement among raft nodes before linearized reading' (duration: 102.036401ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:09.553166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.363079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-13T23:20:09.553184Z","caller":"traceutil/trace.go:171","msg":"trace[468562923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7993; }","duration":"102.39213ms","start":"2026-03-13T23:20:09.450786Z","end":"2026-03-13T23:20:09.553178Z","steps":["trace[468562923] 'agreement among raft nodes before linearized reading' (duration: 102.342469ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:09.553269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.728339ms","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-03-13T23:20:09.5533Z","caller":"traceutil/trace.go:171","msg":"trace[2014391476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7993; }","duration":"102.791071ms","start":"2026-03-13T23:20:09.450498Z","end":"2026-03-13T23:20:09.553289Z","steps":["trace[2014391476] 'agreement among raft nodes before linearized reading' (duration: 102.711489ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:09.553528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.630436ms","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-03-13T23:20:09.553637Z","caller":"traceutil/trace.go:171","msg":"trace[1608650257] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7993; }","duration":"109.77791ms","start":"2026-03-13T23:20:09.443829Z","end":"2026-03-13T23:20:09.553607Z","steps":["trace[1608650257] 'agreement among raft nodes before linearized reading' (duration: 109.452971ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:09.797707Z","caller":"traceutil/trace.go:171","msg":"trace[986472509] linearizableReadLoop","detail":"{readStateIndex:8303; appliedIndex:8302; }","duration":"116.656667ms","start":"2026-03-13T23:20:09.681023Z","end":"2026-03-13T23:20:09.79768Z","steps":["trace[986472509] 'read index received' (duration: 104.958576ms)","trace[986472509] 'applied index is now lower than readState.Index' (duration: 11.696781ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:20:09.797859Z","caller":"traceutil/trace.go:171","msg":"trace[1403377173] transaction","detail":"{read_only:false; response_revision:8008; number_of_response:1; }","duration":"117.49391ms","start":"2026-03-13T23:20:09.680351Z","end":"2026-03-13T23:20:09.797845Z","steps":["trace[1403377173] 'process raft request' (duration: 105.658725ms)","trace[1403377173] 'compare' (duration: 11.491786ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:20:09.79814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.096819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4726"} {"level":"info","ts":"2026-03-13T23:20:09.798182Z","caller":"traceutil/trace.go:171","msg":"trace[1704393216] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:8008; }","duration":"117.166641ms","start":"2026-03-13T23:20:09.681005Z","end":"2026-03-13T23:20:09.798171Z","steps":["trace[1704393216] 'agreement among raft nodes before linearized reading' (duration: 117.011187ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:09.798513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.33663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-03-13T23:20:09.798637Z","caller":"traceutil/trace.go:171","msg":"trace[1948159238] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:8008; }","duration":"116.481734ms","start":"2026-03-13T23:20:09.682134Z","end":"2026-03-13T23:20:09.798616Z","steps":["trace[1948159238] 'agreement among raft nodes before linearized reading' (duration: 116.230107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:09.798782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.701813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3565"} {"level":"info","ts":"2026-03-13T23:20:09.798829Z","caller":"traceutil/trace.go:171","msg":"trace[642880611] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8008; }","duration":"115.758324ms","start":"2026-03-13T23:20:09.68306Z","end":"2026-03-13T23:20:09.798818Z","steps":["trace[642880611] 'agreement among raft nodes before linearized reading' (duration: 115.643701ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:16.033548Z","caller":"traceutil/trace.go:171","msg":"trace[974515654] transaction","detail":"{read_only:false; response_revision:8052; number_of_response:1; }","duration":"318.993125ms","start":"2026-03-13T23:20:15.714524Z","end":"2026-03-13T23:20:16.033517Z","steps":["trace[974515654] 'process raft request' (duration: 318.769019ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:16.033924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:15.714244Z","time spent":"319.489079ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4630,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:20:16.927108Z","caller":"traceutil/trace.go:171","msg":"trace[341191674] transaction","detail":"{read_only:false; response_revision:8054; number_of_response:1; }","duration":"280.930294ms","start":"2026-03-13T23:20:16.64615Z","end":"2026-03-13T23:20:16.92708Z","steps":["trace[341191674] 'process raft request' (duration: 280.737089ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:17.326663Z","caller":"traceutil/trace.go:171","msg":"trace[1613380379] linearizableReadLoop","detail":"{readStateIndex:8351; appliedIndex:8350; }","duration":"392.292865ms","start":"2026-03-13T23:20:16.934347Z","end":"2026-03-13T23:20:17.32664Z","steps":["trace[1613380379] 'read index received' (duration: 392.10144ms)","trace[1613380379] 'applied index is now lower than readState.Index' (duration: 190.395µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:20:17.326814Z","caller":"traceutil/trace.go:171","msg":"trace[778268471] transaction","detail":"{read_only:false; response_revision:8055; number_of_response:1; }","duration":"647.834234ms","start":"2026-03-13T23:20:16.678944Z","end":"2026-03-13T23:20:17.326778Z","steps":["trace[778268471] 'process raft request' (duration: 647.480025ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:17.326902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.542882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-03-13T23:20:17.326947Z","caller":"traceutil/trace.go:171","msg":"trace[2059883402] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8055; }","duration":"392.639054ms","start":"2026-03-13T23:20:16.934296Z","end":"2026-03-13T23:20:17.326935Z","steps":["trace[2059883402] 'agreement among raft nodes before linearized reading' (duration: 392.4599ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:17.326982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:16.934273Z","time spent":"392.701336ms","remote":"127.0.0.1:53216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":649,"request content":"key:\"/registry/configmaps/openstack/rook-config-override\" "} {"level":"warn","ts":"2026-03-13T23:20:17.326988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:16.67891Z","time spent":"647.986278ms","remote":"127.0.0.1:53316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:20:17.355754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.076776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3672"} {"level":"info","ts":"2026-03-13T23:20:17.355897Z","caller":"traceutil/trace.go:171","msg":"trace[1524471276] transaction","detail":"{read_only:false; response_revision:8056; number_of_response:1; }","duration":"343.879537ms","start":"2026-03-13T23:20:17.01197Z","end":"2026-03-13T23:20:17.355849Z","steps":["trace[1524471276] 'process raft request' (duration: 343.310582ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:17.355954Z","caller":"traceutil/trace.go:171","msg":"trace[207066398] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:8056; }","duration":"298.328373ms","start":"2026-03-13T23:20:17.057598Z","end":"2026-03-13T23:20:17.355927Z","steps":["trace[207066398] 'agreement among raft nodes before linearized reading' (duration: 297.909323ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:17.356081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:17.011941Z","time spent":"344.059952ms","remote":"127.0.0.1:53184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T23:20:17.355754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.653846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-w4l5k\" ","response":"range_response_count:1 size:4650"} {"level":"warn","ts":"2026-03-13T23:20:17.356055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.371548ms","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-03-13T23:20:17.356179Z","caller":"traceutil/trace.go:171","msg":"trace[1786649962] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8056; }","duration":"169.557713ms","start":"2026-03-13T23:20:17.186595Z","end":"2026-03-13T23:20:17.356152Z","steps":["trace[1786649962] 'agreement among raft nodes before linearized reading' (duration: 169.335277ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:17.356232Z","caller":"traceutil/trace.go:171","msg":"trace[1258739137] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-w4l5k; range_end:; response_count:1; response_revision:8056; }","duration":"345.092938ms","start":"2026-03-13T23:20:17.011051Z","end":"2026-03-13T23:20:17.356144Z","steps":["trace[1258739137] 'agreement among raft nodes before linearized reading' (duration: 344.599855ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:17.356426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:17.011025Z","time spent":"345.311814ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4673,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-w4l5k\" "} {"level":"info","ts":"2026-03-13T23:20:18.234054Z","caller":"traceutil/trace.go:171","msg":"trace[890472838] transaction","detail":"{read_only:false; response_revision:8068; number_of_response:1; }","duration":"237.574046ms","start":"2026-03-13T23:20:17.996445Z","end":"2026-03-13T23:20:18.234019Z","steps":["trace[890472838] 'process raft request' (duration: 194.837004ms)","trace[890472838] 'compare' (duration: 42.27468ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:20:18.393976Z","caller":"traceutil/trace.go:171","msg":"trace[1195993530] transaction","detail":"{read_only:false; response_revision:8070; number_of_response:1; }","duration":"152.623325ms","start":"2026-03-13T23:20:18.240538Z","end":"2026-03-13T23:20:18.393161Z","steps":["trace[1195993530] 'process raft request' (duration: 94.426225ms)","trace[1195993530] 'compare' (duration: 57.778239ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:20:18.642685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.109009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:20:18.642819Z","caller":"traceutil/trace.go:171","msg":"trace[821110727] transaction","detail":"{read_only:false; response_revision:8072; number_of_response:1; }","duration":"151.692241ms","start":"2026-03-13T23:20:18.491105Z","end":"2026-03-13T23:20:18.642798Z","steps":["trace[821110727] 'process raft request' (duration: 27.376846ms)","trace[821110727] 'compare' (duration: 123.72709ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:21:30.32078Z","caller":"traceutil/trace.go:171","msg":"trace[1144536592] transaction","detail":"{read_only:false; response_revision:8741; number_of_response:1; }","duration":"663.518454ms","start":"2026-03-13T23:21:29.657237Z","end":"2026-03-13T23:21:30.320756Z","steps":["trace[1144536592] 'process raft request' (duration: 663.35801ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:21:30.32079Z","caller":"traceutil/trace.go:171","msg":"trace[879928216] linearizableReadLoop","detail":"{readStateIndex:9058; appliedIndex:9058; }","duration":"459.238815ms","start":"2026-03-13T23:21:29.861519Z","end":"2026-03-13T23:21:30.320757Z","steps":["trace[879928216] 'read index received' (duration: 459.222184ms)","trace[879928216] 'applied index is now lower than readState.Index' (duration: 14.161µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:21:30.320963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:21:29.65722Z","time spent":"663.648077ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:21:30.32112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.576674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-03-13T23:21:30.321217Z","caller":"traceutil/trace.go:171","msg":"trace[655118971] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:8741; }","duration":"459.691496ms","start":"2026-03-13T23:21:29.861505Z","end":"2026-03-13T23:21:30.321196Z","steps":["trace[655118971] 'agreement among raft nodes before linearized reading' (duration: 459.40916ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:21:30.321269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:21:29.861487Z","time spent":"459.772638ms","remote":"127.0.0.1:53264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1656,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-03-13T23:21:30.340207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.765291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-13T23:21:30.340255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.960953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-13T23:21:30.340298Z","caller":"traceutil/trace.go:171","msg":"trace[1554135777] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8743; }","duration":"242.884754ms","start":"2026-03-13T23:21:30.097394Z","end":"2026-03-13T23:21:30.340278Z","steps":["trace[1554135777] 'agreement among raft nodes before linearized reading' (duration: 242.652278ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:21:30.340234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.621072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-db5fd995c-pm4tx.189c89e065fdd83b\" ","response":"range_response_count:1 size:1132"} {"level":"warn","ts":"2026-03-13T23:21:30.340427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.323712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-13T23:21:30.340475Z","caller":"traceutil/trace.go:171","msg":"trace[801248408] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8743; }","duration":"244.396444ms","start":"2026-03-13T23:21:30.096071Z","end":"2026-03-13T23:21:30.340467Z","steps":["trace[801248408] 'agreement among raft nodes before linearized reading' (duration: 244.297092ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:21:30.340526Z","caller":"traceutil/trace.go:171","msg":"trace[1434240952] range","detail":"{range_begin:/registry/events/openstack/barbican-api-db5fd995c-pm4tx.189c89e065fdd83b; range_end:; response_count:1; response_revision:8743; }","duration":"184.910149ms","start":"2026-03-13T23:21:30.155547Z","end":"2026-03-13T23:21:30.340458Z","steps":["trace[1434240952] 'agreement among raft nodes before linearized reading' (duration: 184.492138ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:21:30.340526Z","caller":"traceutil/trace.go:171","msg":"trace[92053058] transaction","detail":"{read_only:false; response_revision:8742; number_of_response:1; }","duration":"420.412814ms","start":"2026-03-13T23:21:29.920079Z","end":"2026-03-13T23:21:30.340492Z","steps":["trace[92053058] 'process raft request' (duration: 419.609203ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:21:30.340678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:21:29.920041Z","time spent":"420.571697ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:21:30.34034Z","caller":"traceutil/trace.go:171","msg":"trace[11656810] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8743; }","duration":"289.089656ms","start":"2026-03-13T23:21:30.051233Z","end":"2026-03-13T23:21:30.340323Z","steps":["trace[11656810] 'agreement among raft nodes before linearized reading' (duration: 288.832009ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:21:30.340225Z","caller":"traceutil/trace.go:171","msg":"trace[1642055593] transaction","detail":"{read_only:false; response_revision:8743; number_of_response:1; }","duration":"419.583672ms","start":"2026-03-13T23:21:29.920619Z","end":"2026-03-13T23:21:30.340202Z","steps":["trace[1642055593] 'process raft request' (duration: 419.278784ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:21:30.340821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:21:29.920592Z","time spent":"420.198338ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:21:30.340549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.634348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lp5sr\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-13T23:21:30.340904Z","caller":"traceutil/trace.go:171","msg":"trace[311525488] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lp5sr; range_end:; response_count:1; response_revision:8743; }","duration":"160.036908ms","start":"2026-03-13T23:21:30.180857Z","end":"2026-03-13T23:21:30.340894Z","steps":["trace[311525488] 'agreement among raft nodes before linearized reading' (duration: 159.595947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:21:30.340939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.999807ms","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-03-13T23:21:30.341012Z","caller":"traceutil/trace.go:171","msg":"trace[2137569214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8743; }","duration":"312.47551ms","start":"2026-03-13T23:21:30.028521Z","end":"2026-03-13T23:21:30.340996Z","steps":["trace[2137569214] 'agreement among raft nodes before linearized reading' (duration: 311.823713ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:21:30.341051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:21:30.028449Z","time spent":"312.592033ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T23:21:30.341308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.245845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-13T23:21:30.34135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.883602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-03-13T23:21:30.341438Z","caller":"traceutil/trace.go:171","msg":"trace[1001333825] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8743; }","duration":"170.638231ms","start":"2026-03-13T23:21:30.170773Z","end":"2026-03-13T23:21:30.341411Z","steps":["trace[1001333825] 'agreement among raft nodes before linearized reading' (duration: 169.783429ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:21:30.341398Z","caller":"traceutil/trace.go:171","msg":"trace[1604011149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8743; }","duration":"288.383298ms","start":"2026-03-13T23:21:30.052997Z","end":"2026-03-13T23:21:30.341381Z","steps":["trace[1604011149] 'agreement among raft nodes before linearized reading' (duration: 287.413104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:21:30.758492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.469433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:21:30.758654Z","caller":"traceutil/trace.go:171","msg":"trace[215651900] transaction","detail":"{read_only:false; response_revision:8744; number_of_response:1; }","duration":"414.155711ms","start":"2026-03-13T23:21:30.344477Z","end":"2026-03-13T23:21:30.758633Z","steps":["trace[215651900] 'process raft request' (duration: 57.457392ms)","trace[215651900] 'compare' (duration: 356.269749ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:21:30.758715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:21:30.344458Z","time spent":"414.227554ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:21:30.814043Z","caller":"traceutil/trace.go:171","msg":"trace[2140980654] transaction","detail":"{read_only:false; response_revision:8745; number_of_response:1; }","duration":"469.451877ms","start":"2026-03-13T23:21:30.344555Z","end":"2026-03-13T23:21:30.814007Z","steps":["trace[2140980654] 'process raft request' (duration: 469.209631ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:21:30.814377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:21:30.344539Z","time spent":"469.734996ms","remote":"127.0.0.1:53184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1117,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:21:30.814417Z","caller":"traceutil/trace.go:171","msg":"trace[185836109] transaction","detail":"{read_only:false; response_revision:8746; number_of_response:1; }","duration":"469.227222ms","start":"2026-03-13T23:21:30.345149Z","end":"2026-03-13T23:21:30.814377Z","steps":["trace[185836109] 'process raft request' (duration: 468.787471ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:21:30.814711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:21:30.345114Z","time spent":"469.495089ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:21:30.814886Z","caller":"traceutil/trace.go:171","msg":"trace[1312230036] transaction","detail":"{read_only:false; response_revision:8747; number_of_response:1; }","duration":"469.664403ms","start":"2026-03-13T23:21:30.34518Z","end":"2026-03-13T23:21:30.814845Z","steps":["trace[1312230036] 'process raft request' (duration: 468.963875ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:21:30.815049Z","caller":"traceutil/trace.go:171","msg":"trace[1591771945] transaction","detail":"{read_only:false; response_revision:8748; number_of_response:1; }","duration":"469.784917ms","start":"2026-03-13T23:21:30.345219Z","end":"2026-03-13T23:21:30.815004Z","steps":["trace[1591771945] 'process raft request' (duration: 469.471908ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:21:30.815135Z","caller":"traceutil/trace.go:171","msg":"trace[522572321] transaction","detail":"{read_only:false; response_revision:8749; number_of_response:1; }","duration":"469.15882ms","start":"2026-03-13T23:21:30.345957Z","end":"2026-03-13T23:21:30.815116Z","steps":["trace[522572321] 'process raft request' (duration: 468.79523ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:21:30.815162Z","caller":"traceutil/trace.go:171","msg":"trace[340063671] transaction","detail":"{read_only:false; response_revision:8750; number_of_response:1; }","duration":"468.887954ms","start":"2026-03-13T23:21:30.346246Z","end":"2026-03-13T23:21:30.815134Z","steps":["trace[340063671] 'process raft request' (duration: 468.73634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:21:30.815263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:21:30.345943Z","time spent":"469.249843ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:21:30.815308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:21:30.346237Z","time spent":"469.000426ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:21:30.815319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:21:30.345172Z","time spent":"469.775196ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:21:30.815068Z","caller":"traceutil/trace.go:171","msg":"trace[1598998736] linearizableReadLoop","detail":"{readStateIndex:9065; appliedIndex:9060; }","duration":"469.538081ms","start":"2026-03-13T23:21:30.345496Z","end":"2026-03-13T23:21:30.815034Z","steps":["trace[1598998736] 'read index received' (duration: 56.345454ms)","trace[1598998736] 'applied index is now lower than readState.Index' (duration: 413.191027ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:21:30.815452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:21:30.345201Z","time spent":"470.093415ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:21:30.815732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.231539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:31 size:291210"} {"level":"info","ts":"2026-03-13T23:21:30.815812Z","caller":"traceutil/trace.go:171","msg":"trace[175360919] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:31; response_revision:8750; }","duration":"470.315341ms","start":"2026-03-13T23:21:30.345479Z","end":"2026-03-13T23:21:30.815795Z","steps":["trace[175360919] 'agreement among raft nodes before linearized reading' (duration: 469.747526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:21:30.81587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:21:30.34546Z","time spent":"470.394662ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":31,"response size":291233,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-03-13T23:21:30.816051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.37776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-03-13T23:21:30.815903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.052602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:21:30.816477Z","caller":"traceutil/trace.go:171","msg":"trace[591432594] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8750; }","duration":"316.854583ms","start":"2026-03-13T23:21:30.499601Z","end":"2026-03-13T23:21:30.816455Z","steps":["trace[591432594] 'agreement among raft nodes before linearized reading' (duration: 316.153705ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:21:30.816501Z","caller":"traceutil/trace.go:171","msg":"trace[182779395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8750; }","duration":"437.753501ms","start":"2026-03-13T23:21:30.378727Z","end":"2026-03-13T23:21:30.81648Z","steps":["trace[182779395] 'agreement among raft nodes before linearized reading' (duration: 437.028442ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:21:30.816537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:21:30.378715Z","time spent":"437.811762ms","remote":"127.0.0.1:53098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-13T23:21:30.816539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:21:30.499576Z","time spent":"316.948725ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-03-13T23:23:43.958176Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-13T23:23:43.969763Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-13T23:23:43.969879Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-13T23:23:45.821106Z","caller":"traceutil/trace.go:171","msg":"trace[2074225419] transaction","detail":"{read_only:false; response_revision:9703; number_of_response:1; }","duration":"209.320602ms","start":"2026-03-13T23:23:45.611766Z","end":"2026-03-13T23:23:45.821087Z","steps":["trace[2074225419] 'process raft request' (duration: 209.170097ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:23:45.822241Z","caller":"traceutil/trace.go:171","msg":"trace[2012440991] linearizableReadLoop","detail":"{readStateIndex:10055; appliedIndex:10053; }","duration":"202.27571ms","start":"2026-03-13T23:23:45.619944Z","end":"2026-03-13T23:23:45.82222Z","steps":["trace[2012440991] 'read index received' (duration: 201.073169ms)","trace[2012440991] 'applied index is now lower than readState.Index' (duration: 1.201861ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:23:45.822376Z","caller":"traceutil/trace.go:171","msg":"trace[303032429] transaction","detail":"{read_only:false; response_revision:9704; number_of_response:1; }","duration":"207.777891ms","start":"2026-03-13T23:23:45.614564Z","end":"2026-03-13T23:23:45.822342Z","steps":["trace[303032429] 'process raft request' (duration: 207.520385ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:23:45.822447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.490146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-03-13T23:23:45.822447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.353931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-13T23:23:45.822478Z","caller":"traceutil/trace.go:171","msg":"trace[604773430] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9704; }","duration":"202.551898ms","start":"2026-03-13T23:23:45.619917Z","end":"2026-03-13T23:23:45.822469Z","steps":["trace[604773430] 'agreement among raft nodes before linearized reading' (duration: 202.406423ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:23:45.822484Z","caller":"traceutil/trace.go:171","msg":"trace[493396974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9704; }","duration":"200.446274ms","start":"2026-03-13T23:23:45.622029Z","end":"2026-03-13T23:23:45.822475Z","steps":["trace[493396974] 'agreement among raft nodes before linearized reading' (duration: 200.33803ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:23:45.822615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.359706ms","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-03-13T23:23:45.822703Z","caller":"traceutil/trace.go:171","msg":"trace[989490153] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9704; }","duration":"182.48309ms","start":"2026-03-13T23:23:45.640195Z","end":"2026-03-13T23:23:45.822678Z","steps":["trace[989490153] 'agreement among raft nodes before linearized reading' (duration: 182.216492ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:24:28.967133Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7729} {"level":"info","ts":"2026-03-13T23:24:29.033738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7729,"took":"64.435683ms","hash":512184977,"current-db-size-bytes":43151360,"current-db-size":"43 MB","current-db-size-in-use-bytes":26300416,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-13T23:24:29.033806Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":512184977,"revision":7729,"compact-revision":5666} {"level":"info","ts":"2026-03-13T23:29:28.97934Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9966} {"level":"info","ts":"2026-03-13T23:29:29.065989Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9966,"took":"84.965522ms","hash":1318787408,"current-db-size-bytes":43151360,"current-db-size":"43 MB","current-db-size-in-use-bytes":17489920,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-03-13T23:29:29.066065Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1318787408,"revision":9966,"compact-revision":7729}