{"level":"warn","ts":"2026-07-22T22:04:46.196141Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-22T22:04:46.196258Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.243:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.243:2380","--initial-cluster=instance=https://199.19.213.243:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.243:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.243:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-22T22:04:46.196325Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-22T22:04:46.196332Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.243:2380"]} {"level":"info","ts":"2026-07-22T22:04:46.196358Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-22T22:04:46.198166Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"]} {"level":"info","ts":"2026-07-22T22:04:46.198352Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.243:2380"],"listen-peer-urls":["https://199.19.213.243:2380"],"advertise-client-urls":["https://199.19.213.243:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.243:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-22T22:04:46.20259Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.970375ms"} {"level":"info","ts":"2026-07-22T22:04:46.211239Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"864e14457b828f9c","cluster-id":"6c0ae60a355554b9"} {"level":"info","ts":"2026-07-22T22:04:46.21138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=()"} {"level":"info","ts":"2026-07-22T22:04:46.211469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became follower at term 0"} {"level":"info","ts":"2026-07-22T22:04:46.211508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 864e14457b828f9c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-22T22:04:46.21156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became follower at term 1"} {"level":"info","ts":"2026-07-22T22:04:46.21164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=(9677694937923227548)"} {"level":"warn","ts":"2026-07-22T22:04:46.219869Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-22T22:04:46.221109Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-22T22:04:46.22357Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-22T22:04:46.226003Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"864e14457b828f9c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-22T22:04:46.226379Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-22T22:04:46.226467Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-22T22:04:46.226479Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-22T22:04:46.226554Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"864e14457b828f9c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-22T22:04:46.227468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=(9677694937923227548)"} {"level":"info","ts":"2026-07-22T22:04:46.228339Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c0ae60a355554b9","local-member-id":"864e14457b828f9c","added-peer-id":"864e14457b828f9c","added-peer-peer-urls":["https://199.19.213.243:2380"]} {"level":"info","ts":"2026-07-22T22:04:46.23013Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-22T22:04:46.230215Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.243:2380"} {"level":"info","ts":"2026-07-22T22:04:46.230275Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.243:2380"} {"level":"info","ts":"2026-07-22T22:04:46.230382Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"864e14457b828f9c","initial-advertise-peer-urls":["https://199.19.213.243:2380"],"listen-peer-urls":["https://199.19.213.243:2380"],"advertise-client-urls":["https://199.19.213.243:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-22T22:04:46.230428Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-22T22:04:46.412856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c is starting a new election at term 1"} {"level":"info","ts":"2026-07-22T22:04:46.412945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became pre-candidate at term 1"} {"level":"info","ts":"2026-07-22T22:04:46.412966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c received MsgPreVoteResp from 864e14457b828f9c at term 1"} {"level":"info","ts":"2026-07-22T22:04:46.413014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became candidate at term 2"} {"level":"info","ts":"2026-07-22T22:04:46.413036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c received MsgVoteResp from 864e14457b828f9c at term 2"} {"level":"info","ts":"2026-07-22T22:04:46.413075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became leader at term 2"} {"level":"info","ts":"2026-07-22T22:04:46.413093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 864e14457b828f9c elected leader 864e14457b828f9c at term 2"} {"level":"info","ts":"2026-07-22T22:04:46.414195Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"864e14457b828f9c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.243:2379]}","request-path":"/0/members/864e14457b828f9c/attributes","cluster-id":"6c0ae60a355554b9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-22T22:04:46.414272Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T22:04:46.414336Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:04:46.414421Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T22:04:46.414668Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-22T22:04:46.414705Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-22T22:04:46.415005Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c0ae60a355554b9","local-member-id":"864e14457b828f9c","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:04:46.415099Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:04:46.41513Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:04:46.416619Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-22T22:04:46.418088Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.243:2379"} {"level":"info","ts":"2026-07-22T22:05:02.59605Z","caller":"traceutil/trace.go:171","msg":"trace[1436239244] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"135.871154ms","start":"2026-07-22T22:05:02.460147Z","end":"2026-07-22T22:05:02.596018Z","steps":["trace[1436239244] 'process raft request' (duration: 135.650234ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:05:27.968837Z","caller":"traceutil/trace.go:171","msg":"trace[253215818] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"251.035168ms","start":"2026-07-22T22:05:27.717723Z","end":"2026-07-22T22:05:27.968758Z","steps":["trace[253215818] 'process raft request' (duration: 241.778246ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:05:28.137847Z","caller":"traceutil/trace.go:171","msg":"trace[1089269306] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"115.35259ms","start":"2026-07-22T22:05:28.022454Z","end":"2026-07-22T22:05:28.137807Z","steps":["trace[1089269306] 'process raft request' (duration: 59.552539ms)","trace[1089269306] 'compare' (duration: 55.654669ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:05:28.896353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.322437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2gqzg\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-22T22:05:28.896466Z","caller":"traceutil/trace.go:171","msg":"trace[1901068962] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2gqzg; range_end:; response_count:1; response_revision:589; }","duration":"565.46143ms","start":"2026-07-22T22:05:28.330985Z","end":"2026-07-22T22:05:28.896446Z","steps":["trace[1901068962] 'range keys from in-memory index tree' (duration: 565.220904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:05:28.896507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:05:28.330965Z","time spent":"565.532626ms","remote":"127.0.0.1:33312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-2gqzg\" "} {"level":"info","ts":"2026-07-22T22:05:28.898083Z","caller":"traceutil/trace.go:171","msg":"trace[1182747235] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"191.942894ms","start":"2026-07-22T22:05:28.706125Z","end":"2026-07-22T22:05:28.898068Z","steps":["trace[1182747235] 'read index received' (duration: 191.46276ms)","trace[1182747235] 'applied index is now lower than readState.Index' (duration: 479.374µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:05:28.898365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.243548ms","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-07-22T22:05:28.898459Z","caller":"traceutil/trace.go:171","msg":"trace[1194932745] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"192.367521ms","start":"2026-07-22T22:05:28.706082Z","end":"2026-07-22T22:05:28.898449Z","steps":["trace[1194932745] 'agreement among raft nodes before linearized reading' (duration: 192.142903ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:05:28.898497Z","caller":"traceutil/trace.go:171","msg":"trace[946678102] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"374.956709ms","start":"2026-07-22T22:05:28.523446Z","end":"2026-07-22T22:05:28.898403Z","steps":["trace[946678102] 'process raft request' (duration: 374.179401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:05:28.899214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:05:28.52341Z","time spent":"375.169188ms","remote":"127.0.0.1:33220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:05:28.982656Z","caller":"traceutil/trace.go:171","msg":"trace[1779195085] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"135.44076ms","start":"2026-07-22T22:05:28.847197Z","end":"2026-07-22T22:05:28.982638Z","steps":["trace[1779195085] 'process raft request' (duration: 135.313317ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:05:29.087401Z","caller":"traceutil/trace.go:171","msg":"trace[1840722806] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"183.433809ms","start":"2026-07-22T22:05:28.903944Z","end":"2026-07-22T22:05:29.087378Z","steps":["trace[1840722806] 'process raft request' (duration: 163.833751ms)","trace[1840722806] 'compare' (duration: 19.401028ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:05:29.087753Z","caller":"traceutil/trace.go:171","msg":"trace[1643401337] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"166.042834ms","start":"2026-07-22T22:05:28.921698Z","end":"2026-07-22T22:05:29.087741Z","steps":["trace[1643401337] 'process raft request' (duration: 165.626965ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:05:29.088572Z","caller":"traceutil/trace.go:171","msg":"trace[316713514] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"128.032159ms","start":"2026-07-22T22:05:28.960519Z","end":"2026-07-22T22:05:29.088551Z","steps":["trace[316713514] 'process raft request' (duration: 127.882926ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:05:40.537719Z","caller":"traceutil/trace.go:171","msg":"trace[2009187926] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"117.94556ms","start":"2026-07-22T22:05:40.41974Z","end":"2026-07-22T22:05:40.537685Z","steps":["trace[2009187926] 'process raft request' (duration: 117.681808ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:06:13.517116Z","caller":"traceutil/trace.go:171","msg":"trace[831457057] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1184; }","duration":"158.431342ms","start":"2026-07-22T22:06:13.358662Z","end":"2026-07-22T22:06:13.517093Z","steps":["trace[831457057] 'read index received' (duration: 158.157328ms)","trace[831457057] 'applied index is now lower than readState.Index' (duration: 272.854µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:06:13.517269Z","caller":"traceutil/trace.go:171","msg":"trace[671424914] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"160.199949ms","start":"2026-07-22T22:06:13.357053Z","end":"2026-07-22T22:06:13.517253Z","steps":["trace[671424914] 'process raft request' (duration: 159.832773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:13.517564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.850448ms","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-07-22T22:06:13.517611Z","caller":"traceutil/trace.go:171","msg":"trace[1158071491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1151; }","duration":"158.98351ms","start":"2026-07-22T22:06:13.358615Z","end":"2026-07-22T22:06:13.517599Z","steps":["trace[1158071491] 'agreement among raft nodes before linearized reading' (duration: 158.773067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:13.517776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.056484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-22T22:06:13.517812Z","caller":"traceutil/trace.go:171","msg":"trace[353643332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1151; }","duration":"154.124346ms","start":"2026-07-22T22:06:13.363672Z","end":"2026-07-22T22:06:13.517796Z","steps":["trace[353643332] 'agreement among raft nodes before linearized reading' (duration: 154.033014ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:06:38.875064Z","caller":"traceutil/trace.go:171","msg":"trace[971906815] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"172.415887ms","start":"2026-07-22T22:06:38.702616Z","end":"2026-07-22T22:06:38.875032Z","steps":["trace[971906815] 'process raft request' (duration: 172.223099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:39.774357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:06:39.464704Z","time spent":"309.648371ms","remote":"127.0.0.1:33150","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-22T22:06:59.00348Z","caller":"traceutil/trace.go:171","msg":"trace[644726323] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"130.842654ms","start":"2026-07-22T22:06:58.872601Z","end":"2026-07-22T22:06:59.003443Z","steps":["trace[644726323] 'process raft request' (duration: 130.667175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:07:04.09651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.168306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:07:04.09675Z","caller":"traceutil/trace.go:171","msg":"trace[1345497718] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1934; }","duration":"106.568269ms","start":"2026-07-22T22:07:03.990121Z","end":"2026-07-22T22:07:04.09669Z","steps":["trace[1345497718] 'read index received' (duration: 55.392µs)","trace[1345497718] 'applied index is now lower than readState.Index' (duration: 106.421653ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:07:04.096898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.77139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-07-22T22:07:04.096957Z","caller":"traceutil/trace.go:171","msg":"trace[1451418112] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1880; }","duration":"106.879796ms","start":"2026-07-22T22:07:03.990066Z","end":"2026-07-22T22:07:04.096946Z","steps":["trace[1451418112] 'agreement among raft nodes before linearized reading' (duration: 106.707987ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:07:04.096697Z","caller":"traceutil/trace.go:171","msg":"trace[1830190959] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"140.159935ms","start":"2026-07-22T22:07:03.956509Z","end":"2026-07-22T22:07:04.096669Z","steps":["trace[1830190959] 'process raft request' (duration: 23.583078ms)","trace[1830190959] 'compare' (duration: 115.997487ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:07:08.619102Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348321466832720420,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T22:07:09.047091Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.316477598s","expected-duration":"1s"} {"level":"info","ts":"2026-07-22T22:07:09.047692Z","caller":"traceutil/trace.go:171","msg":"trace[536713042] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"1.317130683s","start":"2026-07-22T22:07:07.730532Z","end":"2026-07-22T22:07:09.047663Z","steps":["trace[536713042] 'process raft request' (duration: 1.316893801s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:07:09.047886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:07:07.730502Z","time spent":"1.31724058s","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:07:09.106181Z","caller":"traceutil/trace.go:171","msg":"trace[2074031494] linearizableReadLoop","detail":"{readStateIndex:1960; appliedIndex:1957; }","duration":"987.618285ms","start":"2026-07-22T22:07:08.118542Z","end":"2026-07-22T22:07:09.106161Z","steps":["trace[2074031494] 'read index received' (duration: 928.864819ms)","trace[2074031494] 'applied index is now lower than readState.Index' (duration: 58.752906ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:07:09.10627Z","caller":"traceutil/trace.go:171","msg":"trace[1709959297] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"983.368976ms","start":"2026-07-22T22:07:08.122893Z","end":"2026-07-22T22:07:09.106262Z","steps":["trace[1709959297] 'process raft request' (duration: 983.134503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:07:09.106342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:07:08.122882Z","time spent":"983.404108ms","remote":"127.0.0.1:33572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16383,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:07:09.106481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.638454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-07-22T22:07:09.106481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.972628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:07:09.106507Z","caller":"traceutil/trace.go:171","msg":"trace[654079463] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1904; }","duration":"901.669507ms","start":"2026-07-22T22:07:08.204828Z","end":"2026-07-22T22:07:09.106497Z","steps":["trace[654079463] 'agreement among raft nodes before linearized reading' (duration: 901.594733ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:07:09.106508Z","caller":"traceutil/trace.go:171","msg":"trace[1014820858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1904; }","duration":"984.011371ms","start":"2026-07-22T22:07:08.122489Z","end":"2026-07-22T22:07:09.106501Z","steps":["trace[1014820858] 'agreement among raft nodes before linearized reading' (duration: 983.971169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:07:09.106522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:07:08.204823Z","time spent":"901.695828ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4614,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-22T22:07:09.106525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:07:08.122483Z","time spent":"984.038662ms","remote":"127.0.0.1:33112","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-07-22T22:07:09.106461Z","caller":"traceutil/trace.go:171","msg":"trace[136724195] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"494.441729ms","start":"2026-07-22T22:07:08.611982Z","end":"2026-07-22T22:07:09.106424Z","steps":["trace[136724195] 'process raft request' (duration: 494.147273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:07:09.10657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.002416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-07-22T22:07:09.106631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.049901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:523"} {"level":"warn","ts":"2026-07-22T22:07:09.106638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.462073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-07-22T22:07:09.106646Z","caller":"traceutil/trace.go:171","msg":"trace[595666897] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1904; }","duration":"971.079132ms","start":"2026-07-22T22:07:08.135562Z","end":"2026-07-22T22:07:09.106642Z","steps":["trace[595666897] 'agreement among raft nodes before linearized reading' (duration: 971.03382ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:07:09.106634Z","caller":"traceutil/trace.go:171","msg":"trace[1159826040] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1904; }","duration":"988.107572ms","start":"2026-07-22T22:07:08.118509Z","end":"2026-07-22T22:07:09.106616Z","steps":["trace[1159826040] 'agreement among raft nodes before linearized reading' (duration: 987.785315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:07:09.106662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:07:08.135552Z","time spent":"971.107403ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":546,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-07-22T22:07:09.106651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:07:08.611964Z","time spent":"494.602097ms","remote":"127.0.0.1:33400","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-07-22T22:07:09.106692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.125459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-22T22:07:09.106684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:07:08.118493Z","time spent":"988.180035ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-22T22:07:09.106704Z","caller":"traceutil/trace.go:171","msg":"trace[576531171] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1904; }","duration":"279.169601ms","start":"2026-07-22T22:07:08.827532Z","end":"2026-07-22T22:07:09.106701Z","steps":["trace[576531171] 'agreement among raft nodes before linearized reading' (duration: 279.128738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:07:09.106684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.026008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-22T22:07:09.106764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.033318ms","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-07-22T22:07:09.106777Z","caller":"traceutil/trace.go:171","msg":"trace[2027188447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1904; }","duration":"822.053099ms","start":"2026-07-22T22:07:08.28472Z","end":"2026-07-22T22:07:09.106773Z","steps":["trace[2027188447] 'agreement among raft nodes before linearized reading' (duration: 822.016948ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:07:09.106651Z","caller":"traceutil/trace.go:171","msg":"trace[1053439234] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1904; }","duration":"278.492785ms","start":"2026-07-22T22:07:08.828155Z","end":"2026-07-22T22:07:09.106647Z","steps":["trace[1053439234] 'agreement among raft nodes before linearized reading' (duration: 278.449622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:07:09.106789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:07:08.284715Z","time spent":"822.07169ms","remote":"127.0.0.1:33400","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-07-22T22:07:09.106788Z","caller":"traceutil/trace.go:171","msg":"trace[761779783] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1904; }","duration":"988.142013ms","start":"2026-07-22T22:07:08.118626Z","end":"2026-07-22T22:07:09.106768Z","steps":["trace[761779783] 'agreement among raft nodes before linearized reading' (duration: 987.761243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:07:09.106856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.756523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-07-22T22:07:09.106874Z","caller":"traceutil/trace.go:171","msg":"trace[2117053276] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1904; }","duration":"987.783275ms","start":"2026-07-22T22:07:08.119085Z","end":"2026-07-22T22:07:09.106868Z","steps":["trace[2117053276] 'agreement among raft nodes before linearized reading' (duration: 987.739362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:07:09.106889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:07:08.11908Z","time spent":"987.805626ms","remote":"127.0.0.1:33222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-22T22:07:09.106881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:07:08.118619Z","time spent":"988.245859ms","remote":"127.0.0.1:33400","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-07-22T22:07:09.106916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.275261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-22T22:07:09.10693Z","caller":"traceutil/trace.go:171","msg":"trace[1174437727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1904; }","duration":"988.310713ms","start":"2026-07-22T22:07:08.118616Z","end":"2026-07-22T22:07:09.106927Z","steps":["trace[1174437727] 'agreement among raft nodes before linearized reading' (duration: 988.273561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:07:09.106944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:07:08.118606Z","time spent":"988.335344ms","remote":"127.0.0.1:33400","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-07-22T22:08:03.110902Z","caller":"traceutil/trace.go:171","msg":"trace[1497931836] transaction","detail":"{read_only:false; response_revision:2280; number_of_response:1; }","duration":"191.646699ms","start":"2026-07-22T22:08:02.919229Z","end":"2026-07-22T22:08:03.110876Z","steps":["trace[1497931836] 'process raft request' (duration: 191.474527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:08:03.962265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.808161ms","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-07-22T22:08:03.96235Z","caller":"traceutil/trace.go:171","msg":"trace[1955912653] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2297; }","duration":"208.94566ms","start":"2026-07-22T22:08:03.753384Z","end":"2026-07-22T22:08:03.96233Z","steps":["trace[1955912653] 'count revisions from in-memory index tree' (duration: 208.686033ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:00.367855Z","caller":"traceutil/trace.go:171","msg":"trace[1616489749] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"109.124772ms","start":"2026-07-22T22:09:00.258656Z","end":"2026-07-22T22:09:00.36778Z","steps":["trace[1616489749] 'process raft request' (duration: 108.925586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:05.824058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.635292ms","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-07-22T22:09:05.824154Z","caller":"traceutil/trace.go:171","msg":"trace[1527221597] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2816; }","duration":"164.804165ms","start":"2026-07-22T22:09:05.659329Z","end":"2026-07-22T22:09:05.824133Z","steps":["trace[1527221597] 'count revisions from in-memory index tree' (duration: 164.525683ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:31.188528Z","caller":"traceutil/trace.go:171","msg":"trace[1983239908] linearizableReadLoop","detail":"{readStateIndex:3143; appliedIndex:3142; }","duration":"112.264854ms","start":"2026-07-22T22:09:31.076231Z","end":"2026-07-22T22:09:31.188496Z","steps":["trace[1983239908] 'read index received' (duration: 109.747295ms)","trace[1983239908] 'applied index is now lower than readState.Index' (duration: 2.515659ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:09:31.188704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.454809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:09:31.188737Z","caller":"traceutil/trace.go:171","msg":"trace[781856607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3022; }","duration":"112.544976ms","start":"2026-07-22T22:09:31.076181Z","end":"2026-07-22T22:09:31.188726Z","steps":["trace[781856607] 'agreement among raft nodes before linearized reading' (duration: 112.454349ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:31.188899Z","caller":"traceutil/trace.go:171","msg":"trace[1231973876] transaction","detail":"{read_only:false; response_revision:3022; number_of_response:1; }","duration":"378.267154ms","start":"2026-07-22T22:09:30.810598Z","end":"2026-07-22T22:09:31.188865Z","steps":["trace[1231973876] 'process raft request' (duration: 375.344403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:31.189067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:30.810592Z","time spent":"378.386614ms","remote":"127.0.0.1:33222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:09:32.192461Z","caller":"traceutil/trace.go:171","msg":"trace[1558916443] transaction","detail":"{read_only:false; response_revision:3024; number_of_response:1; }","duration":"746.890931ms","start":"2026-07-22T22:09:31.445536Z","end":"2026-07-22T22:09:32.192427Z","steps":["trace[1558916443] 'process raft request' (duration: 746.727628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:32.192626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:31.44552Z","time spent":"747.038032ms","remote":"127.0.0.1:33228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":369,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T22:09:32.199964Z","caller":"traceutil/trace.go:171","msg":"trace[2115341492] transaction","detail":"{read_only:false; response_revision:3025; number_of_response:1; }","duration":"517.747809ms","start":"2026-07-22T22:09:31.682191Z","end":"2026-07-22T22:09:32.199939Z","steps":["trace[2115341492] 'process raft request' (duration: 513.718169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:32.200113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:31.682172Z","time spent":"517.871869ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:09:32.200132Z","caller":"traceutil/trace.go:171","msg":"trace[2094749884] transaction","detail":"{read_only:false; response_revision:3026; number_of_response:1; }","duration":"456.205239ms","start":"2026-07-22T22:09:31.743904Z","end":"2026-07-22T22:09:32.200109Z","steps":["trace[2094749884] 'process raft request' (duration: 455.999463ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:32.200272Z","caller":"traceutil/trace.go:171","msg":"trace[1221089333] linearizableReadLoop","detail":"{readStateIndex:3147; appliedIndex:3144; }","duration":"244.53373ms","start":"2026-07-22T22:09:31.955534Z","end":"2026-07-22T22:09:32.200067Z","steps":["trace[1221089333] 'read index received' (duration: 236.765925ms)","trace[1221089333] 'applied index is now lower than readState.Index' (duration: 7.766925ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:09:32.200302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:31.743886Z","time spent":"456.331489ms","remote":"127.0.0.1:33400","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-07-22T22:09:32.200409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.884407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-22T22:09:32.200436Z","caller":"traceutil/trace.go:171","msg":"trace[436130021] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3026; }","duration":"244.949202ms","start":"2026-07-22T22:09:31.95548Z","end":"2026-07-22T22:09:32.200429Z","steps":["trace[436130021] 'agreement among raft nodes before linearized reading' (duration: 244.853225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:32.200973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.22102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-07-22T22:09:32.201035Z","caller":"traceutil/trace.go:171","msg":"trace[1835876049] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:3027; }","duration":"238.289625ms","start":"2026-07-22T22:09:31.962734Z","end":"2026-07-22T22:09:32.201024Z","steps":["trace[1835876049] 'agreement among raft nodes before linearized reading' (duration: 238.121462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:32.201769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.970512ms","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-07-22T22:09:32.201835Z","caller":"traceutil/trace.go:171","msg":"trace[1223559466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3027; }","duration":"224.0653ms","start":"2026-07-22T22:09:31.977752Z","end":"2026-07-22T22:09:32.201817Z","steps":["trace[1223559466] 'agreement among raft nodes before linearized reading' (duration: 223.900777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:32.202002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.458362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:09:32.202144Z","caller":"traceutil/trace.go:171","msg":"trace[1994880161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3027; }","duration":"125.55664ms","start":"2026-07-22T22:09:32.076494Z","end":"2026-07-22T22:09:32.20205Z","steps":["trace[1994880161] 'agreement among raft nodes before linearized reading' (duration: 125.467353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:32.202272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.088103ms","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-07-22T22:09:32.202316Z","caller":"traceutil/trace.go:171","msg":"trace[1478111308] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3027; }","duration":"234.152537ms","start":"2026-07-22T22:09:31.968154Z","end":"2026-07-22T22:09:32.202306Z","steps":["trace[1478111308] 'agreement among raft nodes before linearized reading' (duration: 234.0561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:32.202018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.422238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-22T22:09:32.202702Z","caller":"traceutil/trace.go:171","msg":"trace[157737360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3027; }","duration":"224.126715ms","start":"2026-07-22T22:09:31.978565Z","end":"2026-07-22T22:09:32.202692Z","steps":["trace[157737360] 'agreement among raft nodes before linearized reading' (duration: 223.397386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:32.202036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.070824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-22T22:09:32.204042Z","caller":"traceutil/trace.go:171","msg":"trace[1961971456] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3027; }","duration":"221.074523ms","start":"2026-07-22T22:09:31.982926Z","end":"2026-07-22T22:09:32.204Z","steps":["trace[1961971456] 'agreement among raft nodes before linearized reading' (duration: 218.943234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:04.055416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.916473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/kube-prometheus-stack-admission\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:04.055523Z","caller":"traceutil/trace.go:171","msg":"trace[1254558859] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/kube-prometheus-stack-admission; range_end:; response_count:0; response_revision:3773; }","duration":"213.047343ms","start":"2026-07-22T22:11:03.842452Z","end":"2026-07-22T22:11:04.0555Z","steps":["trace[1254558859] 'range keys from in-memory index tree' (duration: 212.871893ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:04.620319Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348321466832725488,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-22T22:11:04.966491Z","caller":"traceutil/trace.go:171","msg":"trace[852215464] transaction","detail":"{read_only:false; response_revision:3774; number_of_response:1; }","duration":"851.679696ms","start":"2026-07-22T22:11:04.114781Z","end":"2026-07-22T22:11:04.96646Z","steps":["trace[852215464] 'process raft request' (duration: 851.500966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:04.966675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:04.114766Z","time spent":"851.816777ms","remote":"127.0.0.1:33222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:04.969286Z","caller":"traceutil/trace.go:171","msg":"trace[451841693] transaction","detail":"{read_only:false; number_of_response:0; response_revision:3774; }","duration":"846.397581ms","start":"2026-07-22T22:11:04.122867Z","end":"2026-07-22T22:11:04.969264Z","steps":["trace[451841693] 'process raft request' (duration: 846.20962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:04.969396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:04.122854Z","time spent":"846.494212ms","remote":"127.0.0.1:33228","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T22:11:04.969923Z","caller":"traceutil/trace.go:171","msg":"trace[129474573] transaction","detail":"{read_only:false; response_revision:3775; number_of_response:1; }","duration":"462.20394ms","start":"2026-07-22T22:11:04.507698Z","end":"2026-07-22T22:11:04.969902Z","steps":["trace[129474573] 'process raft request' (duration: 461.445348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:04.970137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:04.507675Z","time spent":"462.306962ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:04.97758Z","caller":"traceutil/trace.go:171","msg":"trace[1815476670] linearizableReadLoop","detail":"{readStateIndex:3947; appliedIndex:3944; }","duration":"857.536172ms","start":"2026-07-22T22:11:04.120023Z","end":"2026-07-22T22:11:04.977559Z","steps":["trace[1815476670] 'read index received' (duration: 846.267551ms)","trace[1815476670] 'applied index is now lower than readState.Index' (duration: 11.267211ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:04.97785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.751968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"warn","ts":"2026-07-22T22:11:04.977839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.793914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:04.97789Z","caller":"traceutil/trace.go:171","msg":"trace[398695049] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:3775; }","duration":"270.820898ms","start":"2026-07-22T22:11:04.707059Z","end":"2026-07-22T22:11:04.97788Z","steps":["trace[398695049] 'agreement among raft nodes before linearized reading' (duration: 270.727358ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:04.977946Z","caller":"traceutil/trace.go:171","msg":"trace[1497915822] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:3775; }","duration":"857.881404ms","start":"2026-07-22T22:11:04.120007Z","end":"2026-07-22T22:11:04.977889Z","steps":["trace[1497915822] 'agreement among raft nodes before linearized reading' (duration: 857.734214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:04.978079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.752151ms","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-07-22T22:11:04.978082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:04.120001Z","time spent":"858.061885ms","remote":"127.0.0.1:45076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"info","ts":"2026-07-22T22:11:04.97814Z","caller":"traceutil/trace.go:171","msg":"trace[1030511679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3775; }","duration":"271.826101ms","start":"2026-07-22T22:11:04.706298Z","end":"2026-07-22T22:11:04.978125Z","steps":["trace[1030511679] 'agreement among raft nodes before linearized reading' (duration: 271.69405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:04.978243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.360456ms","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":"warn","ts":"2026-07-22T22:11:04.977957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.184447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-22T22:11:04.978306Z","caller":"traceutil/trace.go:171","msg":"trace[799011190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3775; }","duration":"266.449086ms","start":"2026-07-22T22:11:04.711845Z","end":"2026-07-22T22:11:04.978294Z","steps":["trace[799011190] 'agreement among raft nodes before linearized reading' (duration: 266.188366ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:04.978317Z","caller":"traceutil/trace.go:171","msg":"trace[447168078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3775; }","duration":"274.570528ms","start":"2026-07-22T22:11:04.703731Z","end":"2026-07-22T22:11:04.978301Z","steps":["trace[447168078] 'agreement among raft nodes before linearized reading' (duration: 274.101577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:04.978446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.610703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-22T22:11:04.978597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.879934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-22T22:11:04.97866Z","caller":"traceutil/trace.go:171","msg":"trace[1206385057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3775; }","duration":"328.971734ms","start":"2026-07-22T22:11:04.649674Z","end":"2026-07-22T22:11:04.978645Z","steps":["trace[1206385057] 'agreement among raft nodes before linearized reading' (duration: 328.108132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:04.978715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:04.649664Z","time spent":"329.037384ms","remote":"127.0.0.1:33400","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":"info","ts":"2026-07-22T22:11:04.978793Z","caller":"traceutil/trace.go:171","msg":"trace[1128938212] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3775; }","duration":"275.809733ms","start":"2026-07-22T22:11:04.702775Z","end":"2026-07-22T22:11:04.978585Z","steps":["trace[1128938212] 'agreement among raft nodes before linearized reading' (duration: 275.210741ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:05.992277Z","caller":"traceutil/trace.go:171","msg":"trace[1728680107] transaction","detail":"{read_only:false; response_revision:3793; number_of_response:1; }","duration":"144.900733ms","start":"2026-07-22T22:11:05.847354Z","end":"2026-07-22T22:11:05.992254Z","steps":["trace[1728680107] 'process raft request' (duration: 144.463412ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:06.640023Z","caller":"traceutil/trace.go:171","msg":"trace[273982558] linearizableReadLoop","detail":"{readStateIndex:3971; appliedIndex:3970; }","duration":"110.719155ms","start":"2026-07-22T22:11:06.529282Z","end":"2026-07-22T22:11:06.640001Z","steps":["trace[273982558] 'read index received' (duration: 109.387009ms)","trace[273982558] 'applied index is now lower than readState.Index' (duration: 1.331116ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:06.640175Z","caller":"traceutil/trace.go:171","msg":"trace[1226867869] transaction","detail":"{read_only:false; response_revision:3798; number_of_response:1; }","duration":"138.581672ms","start":"2026-07-22T22:11:06.501472Z","end":"2026-07-22T22:11:06.640054Z","steps":["trace[1226867869] 'process raft request' (duration: 137.672539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:06.640491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.045096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-07-22T22:11:06.640604Z","caller":"traceutil/trace.go:171","msg":"trace[1758013003] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3798; }","duration":"111.346847ms","start":"2026-07-22T22:11:06.529242Z","end":"2026-07-22T22:11:06.640589Z","steps":["trace[1758013003] 'agreement among raft nodes before linearized reading' (duration: 110.847215ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:16.420085Z","caller":"traceutil/trace.go:171","msg":"trace[200142255] linearizableReadLoop","detail":"{readStateIndex:4461; appliedIndex:4460; }","duration":"137.999723ms","start":"2026-07-22T22:11:16.28206Z","end":"2026-07-22T22:11:16.42006Z","steps":["trace[200142255] 'read index received' (duration: 128.927625ms)","trace[200142255] 'applied index is now lower than readState.Index' (duration: 9.070778ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:16.420156Z","caller":"traceutil/trace.go:171","msg":"trace[511862315] transaction","detail":"{read_only:false; response_revision:4279; number_of_response:1; }","duration":"141.107169ms","start":"2026-07-22T22:11:16.279022Z","end":"2026-07-22T22:11:16.420129Z","steps":["trace[511862315] 'process raft request' (duration: 132.013082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:16.420313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.229415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1346"} {"level":"info","ts":"2026-07-22T22:11:16.420368Z","caller":"traceutil/trace.go:171","msg":"trace[2005609190] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4279; }","duration":"138.293815ms","start":"2026-07-22T22:11:16.282046Z","end":"2026-07-22T22:11:16.42034Z","steps":["trace[2005609190] 'agreement among raft nodes before linearized reading' (duration: 138.112544ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:17.093775Z","caller":"traceutil/trace.go:171","msg":"trace[303636110] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4291; }","duration":"114.2542ms","start":"2026-07-22T22:11:16.979493Z","end":"2026-07-22T22:11:17.093747Z","steps":["trace[303636110] 'process raft request' (duration: 112.756866ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:18.370688Z","caller":"traceutil/trace.go:171","msg":"trace[295088816] transaction","detail":"{read_only:false; response_revision:4312; number_of_response:1; }","duration":"105.020694ms","start":"2026-07-22T22:11:18.265626Z","end":"2026-07-22T22:11:18.370647Z","steps":["trace[295088816] 'process raft request' (duration: 104.841142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:23.721275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.226102ms","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-07-22T22:11:23.721335Z","caller":"traceutil/trace.go:171","msg":"trace[542015940] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:4436; }","duration":"125.334602ms","start":"2026-07-22T22:11:23.595988Z","end":"2026-07-22T22:11:23.721323Z","steps":["trace[542015940] 'count revisions from in-memory index tree' (duration: 125.126421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:23.72188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.12488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2360980"} {"level":"info","ts":"2026-07-22T22:11:23.721936Z","caller":"traceutil/trace.go:171","msg":"trace[852304518] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4436; }","duration":"118.206511ms","start":"2026-07-22T22:11:23.603717Z","end":"2026-07-22T22:11:23.721923Z","steps":["trace[852304518] 'range keys from in-memory index tree' (duration: 117.255878ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:24.326243Z","caller":"traceutil/trace.go:171","msg":"trace[1933798933] linearizableReadLoop","detail":"{readStateIndex:4626; appliedIndex:4625; }","duration":"249.911155ms","start":"2026-07-22T22:11:24.07631Z","end":"2026-07-22T22:11:24.326221Z","steps":["trace[1933798933] 'read index received' (duration: 249.799644ms)","trace[1933798933] 'applied index is now lower than readState.Index' (duration: 110.621µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:24.326361Z","caller":"traceutil/trace.go:171","msg":"trace[273877550] transaction","detail":"{read_only:false; response_revision:4437; number_of_response:1; }","duration":"373.906733ms","start":"2026-07-22T22:11:23.952432Z","end":"2026-07-22T22:11:24.326339Z","steps":["trace[273877550] 'process raft request' (duration: 373.662051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:24.326389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.067437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:24.326435Z","caller":"traceutil/trace.go:171","msg":"trace[1551249057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4437; }","duration":"250.167148ms","start":"2026-07-22T22:11:24.076256Z","end":"2026-07-22T22:11:24.326424Z","steps":["trace[1551249057] 'agreement among raft nodes before linearized reading' (duration: 250.086358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:24.326477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:23.952412Z","time spent":"374.000134ms","remote":"127.0.0.1:33222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:24.545295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.997693ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:24.545424Z","caller":"traceutil/trace.go:171","msg":"trace[392918118] linearizableReadLoop","detail":"{readStateIndex:4627; appliedIndex:4626; }","duration":"219.096233ms","start":"2026-07-22T22:11:24.326307Z","end":"2026-07-22T22:11:24.545403Z","steps":["trace[392918118] 'read index received' (duration: 112.761906ms)","trace[392918118] 'applied index is now lower than readState.Index' (duration: 106.332357ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:24.545464Z","caller":"traceutil/trace.go:171","msg":"trace[1091347563] transaction","detail":"{read_only:false; response_revision:4438; number_of_response:1; }","duration":"232.666466ms","start":"2026-07-22T22:11:24.312766Z","end":"2026-07-22T22:11:24.545433Z","steps":["trace[1091347563] 'process raft request' (duration: 126.42432ms)","trace[1091347563] 'compare' (duration: 105.824561ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:24.545591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.286712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:24.545643Z","caller":"traceutil/trace.go:171","msg":"trace[1016784269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4438; }","duration":"218.370824ms","start":"2026-07-22T22:11:24.327259Z","end":"2026-07-22T22:11:24.54563Z","steps":["trace[1016784269] 'agreement among raft nodes before linearized reading' (duration: 218.260322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:24.545753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.243948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-07-22T22:11:24.54583Z","caller":"traceutil/trace.go:171","msg":"trace[851954538] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4438; }","duration":"216.360179ms","start":"2026-07-22T22:11:24.329451Z","end":"2026-07-22T22:11:24.545812Z","steps":["trace[851954538] 'agreement among raft nodes before linearized reading' (duration: 216.127776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:24.545787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.084901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-r2gvh\" ","response":"range_response_count:1 size:9757"} {"level":"info","ts":"2026-07-22T22:11:24.545939Z","caller":"traceutil/trace.go:171","msg":"trace[380979435] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-r2gvh; range_end:; response_count:1; response_revision:4438; }","duration":"233.275253ms","start":"2026-07-22T22:11:24.312641Z","end":"2026-07-22T22:11:24.545916Z","steps":["trace[380979435] 'agreement among raft nodes before linearized reading' (duration: 232.854028ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:24.792555Z","caller":"traceutil/trace.go:171","msg":"trace[274853721] transaction","detail":"{read_only:false; response_revision:4440; number_of_response:1; }","duration":"238.164763ms","start":"2026-07-22T22:11:24.554364Z","end":"2026-07-22T22:11:24.792529Z","steps":["trace[274853721] 'process raft request' (duration: 238.018431ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:25.439525Z","caller":"traceutil/trace.go:171","msg":"trace[1026087968] transaction","detail":"{read_only:false; response_revision:4444; number_of_response:1; }","duration":"153.423773ms","start":"2026-07-22T22:11:25.286067Z","end":"2026-07-22T22:11:25.439491Z","steps":["trace[1026087968] 'process raft request' (duration: 101.538732ms)","trace[1026087968] 'compare' (duration: 50.611244ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:25.439682Z","caller":"traceutil/trace.go:171","msg":"trace[1999615888] transaction","detail":"{read_only:false; response_revision:4445; number_of_response:1; }","duration":"153.23876ms","start":"2026-07-22T22:11:25.286415Z","end":"2026-07-22T22:11:25.439654Z","steps":["trace[1999615888] 'process raft request' (duration: 152.988427ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:25.4399Z","caller":"traceutil/trace.go:171","msg":"trace[1088123934] linearizableReadLoop","detail":"{readStateIndex:4638; appliedIndex:4632; }","duration":"152.064804ms","start":"2026-07-22T22:11:25.287814Z","end":"2026-07-22T22:11:25.439878Z","steps":["trace[1088123934] 'read index received' (duration: 25.218806ms)","trace[1088123934] 'applied index is now lower than readState.Index' (duration: 126.844988ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:25.439887Z","caller":"traceutil/trace.go:171","msg":"trace[941560778] transaction","detail":"{read_only:false; response_revision:4446; number_of_response:1; }","duration":"153.376091ms","start":"2026-07-22T22:11:25.286502Z","end":"2026-07-22T22:11:25.439878Z","steps":["trace[941560778] 'process raft request' (duration: 153.100918ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:25.44001Z","caller":"traceutil/trace.go:171","msg":"trace[1213054953] transaction","detail":"{read_only:false; response_revision:4447; number_of_response:1; }","duration":"152.823305ms","start":"2026-07-22T22:11:25.287176Z","end":"2026-07-22T22:11:25.439999Z","steps":["trace[1213054953] 'process raft request' (duration: 152.575312ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:25.440041Z","caller":"traceutil/trace.go:171","msg":"trace[986722997] transaction","detail":"{read_only:false; response_revision:4448; number_of_response:1; }","duration":"152.431289ms","start":"2026-07-22T22:11:25.287602Z","end":"2026-07-22T22:11:25.440034Z","steps":["trace[986722997] 'process raft request' (duration: 152.204457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:25.440189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.364709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-07-22T22:11:25.441185Z","caller":"traceutil/trace.go:171","msg":"trace[1024976844] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4448; }","duration":"153.394671ms","start":"2026-07-22T22:11:25.28778Z","end":"2026-07-22T22:11:25.441175Z","steps":["trace[1024976844] 'agreement among raft nodes before linearized reading' (duration: 152.278238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:25.440237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.174254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-r2gvh.18c4bc341a35f0f9\" ","response":"range_response_count:1 size:921"} {"level":"info","ts":"2026-07-22T22:11:25.441242Z","caller":"traceutil/trace.go:171","msg":"trace[1494003887] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-r2gvh.18c4bc341a35f0f9; range_end:; response_count:1; response_revision:4448; }","duration":"124.223006ms","start":"2026-07-22T22:11:25.317014Z","end":"2026-07-22T22:11:25.441237Z","steps":["trace[1494003887] 'agreement among raft nodes before linearized reading' (duration: 123.126223ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:25.744619Z","caller":"traceutil/trace.go:171","msg":"trace[789279782] transaction","detail":"{read_only:false; response_revision:4451; number_of_response:1; }","duration":"204.660575ms","start":"2026-07-22T22:11:25.539932Z","end":"2026-07-22T22:11:25.744592Z","steps":["trace[789279782] 'process raft request' (duration: 109.646725ms)","trace[789279782] 'compare' (duration: 94.783687ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:26.096266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.29106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:26.09636Z","caller":"traceutil/trace.go:171","msg":"trace[1244913556] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4451; }","duration":"122.437692ms","start":"2026-07-22T22:11:25.973898Z","end":"2026-07-22T22:11:26.096335Z","steps":["trace[1244913556] 'count revisions from in-memory index tree' (duration: 122.189629ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:26.289145Z","caller":"traceutil/trace.go:171","msg":"trace[627266092] transaction","detail":"{read_only:false; response_revision:4452; number_of_response:1; }","duration":"174.592851ms","start":"2026-07-22T22:11:26.114527Z","end":"2026-07-22T22:11:26.28912Z","steps":["trace[627266092] 'process raft request' (duration: 174.400859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:26.407168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.108237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1516"} {"level":"info","ts":"2026-07-22T22:11:26.407243Z","caller":"traceutil/trace.go:171","msg":"trace[1899780103] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4452; }","duration":"107.220069ms","start":"2026-07-22T22:11:26.300004Z","end":"2026-07-22T22:11:26.407224Z","steps":["trace[1899780103] 'range keys from in-memory index tree' (duration: 107.008226ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:26.566179Z","caller":"traceutil/trace.go:171","msg":"trace[890125933] transaction","detail":"{read_only:false; response_revision:4453; number_of_response:1; }","duration":"149.841781ms","start":"2026-07-22T22:11:26.41631Z","end":"2026-07-22T22:11:26.566152Z","steps":["trace[890125933] 'process raft request' (duration: 149.694549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.178565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.346476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:27.178656Z","caller":"traceutil/trace.go:171","msg":"trace[1100664574] transaction","detail":"{read_only:false; response_revision:4454; number_of_response:1; }","duration":"584.993882ms","start":"2026-07-22T22:11:26.593647Z","end":"2026-07-22T22:11:27.178641Z","steps":["trace[1100664574] 'process raft request' (duration: 138.505384ms)","trace[1100664574] 'compare' (duration: 446.246965ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:27.178709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:26.593637Z","time spent":"585.042983ms","remote":"127.0.0.1:33222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:27.401239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.489141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:27.401941Z","caller":"traceutil/trace.go:171","msg":"trace[1310858816] transaction","detail":"{read_only:false; response_revision:4455; number_of_response:1; }","duration":"727.006624ms","start":"2026-07-22T22:11:26.674808Z","end":"2026-07-22T22:11:27.401815Z","steps":["trace[1310858816] 'process raft request' (duration: 605.841854ms)","trace[1310858816] 'compare' (duration: 120.225878ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:27.40244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:26.674789Z","time spent":"727.349918ms","remote":"127.0.0.1:44962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2661,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:27.402821Z","caller":"traceutil/trace.go:171","msg":"trace[620216919] transaction","detail":"{read_only:false; response_revision:4456; number_of_response:1; }","duration":"458.590349ms","start":"2026-07-22T22:11:26.944205Z","end":"2026-07-22T22:11:27.402795Z","steps":["trace[620216919] 'process raft request' (duration: 457.146259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.402927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:26.94418Z","time spent":"458.69634ms","remote":"127.0.0.1:33400","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-07-22T22:11:27.403026Z","caller":"traceutil/trace.go:171","msg":"trace[1103033934] transaction","detail":"{read_only:false; response_revision:4457; number_of_response:1; }","duration":"239.058308ms","start":"2026-07-22T22:11:27.163937Z","end":"2026-07-22T22:11:27.402995Z","steps":["trace[1103033934] 'process raft request' (duration: 238.803774ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:27.402969Z","caller":"traceutil/trace.go:171","msg":"trace[873137137] linearizableReadLoop","detail":"{readStateIndex:4646; appliedIndex:4644; }","duration":"420.489325ms","start":"2026-07-22T22:11:26.982456Z","end":"2026-07-22T22:11:27.402945Z","steps":["trace[873137137] 'read index received' (duration: 298.207849ms)","trace[873137137] 'applied index is now lower than readState.Index' (duration: 122.279796ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:27.403095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.615566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:27.403126Z","caller":"traceutil/trace.go:171","msg":"trace[1055527358] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:4457; }","duration":"420.707477ms","start":"2026-07-22T22:11:26.982411Z","end":"2026-07-22T22:11:27.403119Z","steps":["trace[1055527358] 'agreement among raft nodes before linearized reading' (duration: 420.600186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.403157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:26.982399Z","time spent":"420.749978ms","remote":"127.0.0.1:59306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:11:27.403198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.155396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:27.403238Z","caller":"traceutil/trace.go:171","msg":"trace[581229398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4457; }","duration":"327.218837ms","start":"2026-07-22T22:11:27.076007Z","end":"2026-07-22T22:11:27.403225Z","steps":["trace[581229398] 'agreement among raft nodes before linearized reading' (duration: 327.153866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.403279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:27.075996Z","time spent":"327.276197ms","remote":"127.0.0.1:33112","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-07-22T22:11:27.403369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.055433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-07-22T22:11:27.403401Z","caller":"traceutil/trace.go:171","msg":"trace[1531967708] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4457; }","duration":"122.133844ms","start":"2026-07-22T22:11:27.281259Z","end":"2026-07-22T22:11:27.403393Z","steps":["trace[1531967708] 'agreement among raft nodes before linearized reading' (duration: 121.989852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.40351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.063175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:27.403537Z","caller":"traceutil/trace.go:171","msg":"trace[148976165] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:4457; }","duration":"321.129156ms","start":"2026-07-22T22:11:27.0824Z","end":"2026-07-22T22:11:27.40353Z","steps":["trace[148976165] 'agreement among raft nodes before linearized reading' (duration: 321.068785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.403535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.940228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-07-22T22:11:27.403588Z","caller":"traceutil/trace.go:171","msg":"trace[1274863303] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4457; }","duration":"221.994309ms","start":"2026-07-22T22:11:27.181579Z","end":"2026-07-22T22:11:27.403573Z","steps":["trace[1274863303] 'agreement among raft nodes before linearized reading' (duration: 221.831837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.403554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:27.08239Z","time spent":"321.158926ms","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"info","ts":"2026-07-22T22:11:27.892064Z","caller":"traceutil/trace.go:171","msg":"trace[1138485142] linearizableReadLoop","detail":"{readStateIndex:4649; appliedIndex:4648; }","duration":"459.605306ms","start":"2026-07-22T22:11:27.432431Z","end":"2026-07-22T22:11:27.892037Z","steps":["trace[1138485142] 'read index received' (duration: 459.273452ms)","trace[1138485142] 'applied index is now lower than readState.Index' (duration: 330.404µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:27.892098Z","caller":"traceutil/trace.go:171","msg":"trace[1247549531] transaction","detail":"{read_only:false; response_revision:4459; number_of_response:1; }","duration":"481.908015ms","start":"2026-07-22T22:11:27.41017Z","end":"2026-07-22T22:11:27.892078Z","steps":["trace[1247549531] 'process raft request' (duration: 481.587911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.892219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.853954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:27.892272Z","caller":"traceutil/trace.go:171","msg":"trace[725839290] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:4459; }","duration":"481.928835ms","start":"2026-07-22T22:11:27.410332Z","end":"2026-07-22T22:11:27.892261Z","steps":["trace[725839290] 'agreement among raft nodes before linearized reading' (duration: 481.814444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.892324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:27.410312Z","time spent":"482.002276ms","remote":"127.0.0.1:33208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" "} {"level":"warn","ts":"2026-07-22T22:11:27.892444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.438047ms","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-07-22T22:11:27.892499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.761901ms","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-07-22T22:11:27.892516Z","caller":"traceutil/trace.go:171","msg":"trace[1362630097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4459; }","duration":"445.532028ms","start":"2026-07-22T22:11:27.446966Z","end":"2026-07-22T22:11:27.892498Z","steps":["trace[1362630097] 'agreement among raft nodes before linearized reading' (duration: 445.338346ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:27.892549Z","caller":"traceutil/trace.go:171","msg":"trace[158305684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4459; }","duration":"445.826123ms","start":"2026-07-22T22:11:27.446709Z","end":"2026-07-22T22:11:27.892535Z","steps":["trace[158305684] 'agreement among raft nodes before linearized reading' (duration: 445.705601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.892559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:27.446961Z","time spent":"445.589779ms","remote":"127.0.0.1:33400","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-07-22T22:11:27.89259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:27.446703Z","time spent":"445.876923ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-22T22:11:27.892685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.55541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-07-22T22:11:27.892718Z","caller":"traceutil/trace.go:171","msg":"trace[214619306] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4459; }","duration":"143.629832ms","start":"2026-07-22T22:11:27.749078Z","end":"2026-07-22T22:11:27.892708Z","steps":["trace[214619306] 'agreement among raft nodes before linearized reading' (duration: 143.52739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.892756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.097917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-22T22:11:27.892713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.426021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-22T22:11:27.892793Z","caller":"traceutil/trace.go:171","msg":"trace[851600139] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4459; }","duration":"449.156508ms","start":"2026-07-22T22:11:27.443625Z","end":"2026-07-22T22:11:27.892781Z","steps":["trace[851600139] 'agreement among raft nodes before linearized reading' (duration: 449.051706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.892817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.09258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-r2gvh-tls\" ","response":"range_response_count:1 size:1967"} {"level":"warn","ts":"2026-07-22T22:11:27.892829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:27.443613Z","time spent":"449.207828ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-22T22:11:27.892857Z","caller":"traceutil/trace.go:171","msg":"trace[205084377] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-r2gvh-tls; range_end:; response_count:1; response_revision:4459; }","duration":"198.173231ms","start":"2026-07-22T22:11:27.694672Z","end":"2026-07-22T22:11:27.892846Z","steps":["trace[205084377] 'agreement among raft nodes before linearized reading' (duration: 198.071449ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:27.892846Z","caller":"traceutil/trace.go:171","msg":"trace[221266852] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4459; }","duration":"446.609653ms","start":"2026-07-22T22:11:27.446206Z","end":"2026-07-22T22:11:27.892815Z","steps":["trace[221266852] 'agreement among raft nodes before linearized reading' (duration: 446.180167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.892919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:27.446196Z","time spent":"446.708824ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-22T22:11:27.89301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.69278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:11:27.893063Z","caller":"traceutil/trace.go:171","msg":"trace[1117492023] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:4459; }","duration":"341.797591ms","start":"2026-07-22T22:11:27.551256Z","end":"2026-07-22T22:11:27.893054Z","steps":["trace[1117492023] 'agreement among raft nodes before linearized reading' (duration: 341.72016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.893094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:27.551237Z","time spent":"341.848752ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:11:27.893339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.08165ms","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-07-22T22:11:27.893392Z","caller":"traceutil/trace.go:171","msg":"trace[1335106102] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4459; }","duration":"450.170571ms","start":"2026-07-22T22:11:27.443209Z","end":"2026-07-22T22:11:27.89338Z","steps":["trace[1335106102] 'agreement among raft nodes before linearized reading' (duration: 450.044829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.893438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:27.44319Z","time spent":"450.238192ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-22T22:11:27.893995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.42219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9482"} {"level":"info","ts":"2026-07-22T22:11:27.894259Z","caller":"traceutil/trace.go:171","msg":"trace[1339862818] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4459; }","duration":"460.246765ms","start":"2026-07-22T22:11:27.433898Z","end":"2026-07-22T22:11:27.894144Z","steps":["trace[1339862818] 'agreement among raft nodes before linearized reading' (duration: 458.310078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.894451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:27.43388Z","time spent":"460.444538ms","remote":"127.0.0.1:33296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":9505,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-22T22:11:27.894576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:27.410157Z","time spent":"482.075037ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:28.781567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.954764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:28.782218Z","caller":"traceutil/trace.go:171","msg":"trace[1757137312] linearizableReadLoop","detail":"{readStateIndex:4681; appliedIndex:4680; }","duration":"615.108653ms","start":"2026-07-22T22:11:28.167086Z","end":"2026-07-22T22:11:28.782195Z","steps":["trace[1757137312] 'read index received' (duration: 196.391729ms)","trace[1757137312] 'applied index is now lower than readState.Index' (duration: 418.714953ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:28.782299Z","caller":"traceutil/trace.go:171","msg":"trace[75864332] transaction","detail":"{read_only:false; response_revision:4489; number_of_response:1; }","duration":"615.228035ms","start":"2026-07-22T22:11:28.166971Z","end":"2026-07-22T22:11:28.782199Z","steps":["trace[75864332] 'process raft request' (duration: 196.45766ms)","trace[75864332] 'compare' (duration: 417.455217ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:28.78245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.509243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:28.782716Z","caller":"traceutil/trace.go:171","msg":"trace[916162284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4490; }","duration":"123.827017ms","start":"2026-07-22T22:11:28.658871Z","end":"2026-07-22T22:11:28.782698Z","steps":["trace[916162284] 'agreement among raft nodes before linearized reading' (duration: 123.515833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:28.782739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.418916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-r2gvh\" ","response":"range_response_count:1 size:9769"} {"level":"info","ts":"2026-07-22T22:11:28.782487Z","caller":"traceutil/trace.go:171","msg":"trace[560972029] transaction","detail":"{read_only:false; response_revision:4490; number_of_response:1; }","duration":"445.081508ms","start":"2026-07-22T22:11:28.337384Z","end":"2026-07-22T22:11:28.782465Z","steps":["trace[560972029] 'process raft request' (duration: 444.588612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:28.782826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.731071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:72 size:59901"} {"level":"info","ts":"2026-07-22T22:11:28.782876Z","caller":"traceutil/trace.go:171","msg":"trace[1253708557] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-r2gvh; range_end:; response_count:1; response_revision:4490; }","duration":"446.608588ms","start":"2026-07-22T22:11:28.33623Z","end":"2026-07-22T22:11:28.782839Z","steps":["trace[1253708557] 'agreement among raft nodes before linearized reading' (duration: 446.198623ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:28.782888Z","caller":"traceutil/trace.go:171","msg":"trace[847138601] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:72; response_revision:4490; }","duration":"615.828162ms","start":"2026-07-22T22:11:28.167046Z","end":"2026-07-22T22:11:28.782874Z","steps":["trace[847138601] 'agreement among raft nodes before linearized reading' (duration: 615.253444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:28.782578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:28.166935Z","time spent":"615.470197ms","remote":"127.0.0.1:33200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":685,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T22:11:28.782907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:28.337373Z","time spent":"445.479973ms","remote":"127.0.0.1:33200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T22:11:28.782944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:28.16703Z","time spent":"615.899593ms","remote":"127.0.0.1:33486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":72,"response size":59924,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-07-22T22:11:28.782949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:28.336216Z","time spent":"446.72149ms","remote":"127.0.0.1:33312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":9792,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-r2gvh\" "} {"level":"warn","ts":"2026-07-22T22:11:28.782634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.429603ms","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-07-22T22:11:28.783054Z","caller":"traceutil/trace.go:171","msg":"trace[101131865] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4490; }","duration":"609.89374ms","start":"2026-07-22T22:11:28.173145Z","end":"2026-07-22T22:11:28.783039Z","steps":["trace[101131865] 'agreement among raft nodes before linearized reading' (duration: 609.404133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:28.783327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:28.173127Z","time spent":"610.172203ms","remote":"127.0.0.1:33326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1308,"request content":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" "} {"level":"warn","ts":"2026-07-22T22:11:28.783243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.195474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2360980"} {"level":"info","ts":"2026-07-22T22:11:28.78352Z","caller":"traceutil/trace.go:171","msg":"trace[1538577177] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4490; }","duration":"486.512739ms","start":"2026-07-22T22:11:28.296994Z","end":"2026-07-22T22:11:28.783506Z","steps":["trace[1538577177] 'agreement among raft nodes before linearized reading' (duration: 485.468284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:28.783566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:28.296978Z","time spent":"486.571151ms","remote":"127.0.0.1:33222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2361003,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-07-22T22:11:29.086164Z","caller":"traceutil/trace.go:171","msg":"trace[1575343334] linearizableReadLoop","detail":"{readStateIndex:4709; appliedIndex:4708; }","duration":"114.815038ms","start":"2026-07-22T22:11:28.97133Z","end":"2026-07-22T22:11:29.086145Z","steps":["trace[1575343334] 'read index received' (duration: 24.566788ms)","trace[1575343334] 'applied index is now lower than readState.Index' (duration: 90.24735ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:29.086235Z","caller":"traceutil/trace.go:171","msg":"trace[673867122] transaction","detail":"{read_only:false; response_revision:4517; number_of_response:1; }","duration":"115.054641ms","start":"2026-07-22T22:11:28.971154Z","end":"2026-07-22T22:11:29.086208Z","steps":["trace[673867122] 'process raft request' (duration: 24.855962ms)","trace[673867122] 'compare' (duration: 90.054597ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:29.086354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.00723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9761"} {"level":"info","ts":"2026-07-22T22:11:29.086377Z","caller":"traceutil/trace.go:171","msg":"trace[405261769] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4517; }","duration":"115.055401ms","start":"2026-07-22T22:11:28.971316Z","end":"2026-07-22T22:11:29.086371Z","steps":["trace[405261769] 'agreement among raft nodes before linearized reading' (duration: 114.927579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:30.274991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.655655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:30.275188Z","caller":"traceutil/trace.go:171","msg":"trace[163325008] linearizableReadLoop","detail":"{readStateIndex:4722; appliedIndex:4721; }","duration":"342.8597ms","start":"2026-07-22T22:11:29.932305Z","end":"2026-07-22T22:11:30.275165Z","steps":["trace[163325008] 'read index received' (duration: 162.951382ms)","trace[163325008] 'applied index is now lower than readState.Index' (duration: 179.906578ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:30.275504Z","caller":"traceutil/trace.go:171","msg":"trace[223780321] transaction","detail":"{read_only:false; response_revision:4529; number_of_response:1; }","duration":"355.963177ms","start":"2026-07-22T22:11:29.919526Z","end":"2026-07-22T22:11:30.275489Z","steps":["trace[223780321] 'process raft request' (duration: 175.662063ms)","trace[223780321] 'compare' (duration: 179.123237ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:30.275586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:29.919518Z","time spent":"356.029218ms","remote":"127.0.0.1:33222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:30.275925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.423651ms","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-07-22T22:11:30.276Z","caller":"traceutil/trace.go:171","msg":"trace[820807070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4529; }","duration":"296.554803ms","start":"2026-07-22T22:11:29.979429Z","end":"2026-07-22T22:11:30.275984Z","steps":["trace[820807070] 'agreement among raft nodes before linearized reading' (duration: 296.223108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:30.276146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.404758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:30.276197Z","caller":"traceutil/trace.go:171","msg":"trace[1104007033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4529; }","duration":"200.494729ms","start":"2026-07-22T22:11:30.07569Z","end":"2026-07-22T22:11:30.276185Z","steps":["trace[1104007033] 'agreement among raft nodes before linearized reading' (duration: 200.396497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:30.276284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.435811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-22T22:11:30.27631Z","caller":"traceutil/trace.go:171","msg":"trace[1570175890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4529; }","duration":"296.488882ms","start":"2026-07-22T22:11:29.979815Z","end":"2026-07-22T22:11:30.276304Z","steps":["trace[1570175890] 'agreement among raft nodes before linearized reading' (duration: 296.420701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:30.276319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.701164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-07-22T22:11:30.276373Z","caller":"traceutil/trace.go:171","msg":"trace[1007833173] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4529; }","duration":"272.814295ms","start":"2026-07-22T22:11:30.003541Z","end":"2026-07-22T22:11:30.276355Z","steps":["trace[1007833173] 'agreement among raft nodes before linearized reading' (duration: 272.682033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:30.276175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.592009ms","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":"warn","ts":"2026-07-22T22:11:30.276526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.2158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-80b09727-1543-4bc2-a8d9-7b1e1140c819\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-22T22:11:30.27656Z","caller":"traceutil/trace.go:171","msg":"trace[1141617567] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4529; }","duration":"296.025975ms","start":"2026-07-22T22:11:29.980526Z","end":"2026-07-22T22:11:30.276552Z","steps":["trace[1141617567] 'agreement among raft nodes before linearized reading' (duration: 295.467187ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:30.276573Z","caller":"traceutil/trace.go:171","msg":"trace[446387668] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-80b09727-1543-4bc2-a8d9-7b1e1140c819; range_end:; response_count:1; response_revision:4529; }","duration":"344.27281ms","start":"2026-07-22T22:11:29.932282Z","end":"2026-07-22T22:11:30.276555Z","steps":["trace[446387668] 'agreement among raft nodes before linearized reading' (duration: 344.084377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:30.276618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:29.932271Z","time spent":"344.336212ms","remote":"127.0.0.1:33312","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-80b09727-1543-4bc2-a8d9-7b1e1140c819\" "} {"level":"warn","ts":"2026-07-22T22:11:30.27694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.932429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-22T22:11:30.276987Z","caller":"traceutil/trace.go:171","msg":"trace[35379726] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4529; }","duration":"297.01642ms","start":"2026-07-22T22:11:29.97996Z","end":"2026-07-22T22:11:30.276977Z","steps":["trace[35379726] 'agreement among raft nodes before linearized reading' (duration: 296.908669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:30.276964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.049361ms","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-07-22T22:11:30.278008Z","caller":"traceutil/trace.go:171","msg":"trace[1325030126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4529; }","duration":"298.099495ms","start":"2026-07-22T22:11:29.979878Z","end":"2026-07-22T22:11:30.277977Z","steps":["trace[1325030126] 'agreement among raft nodes before linearized reading' (duration: 296.225308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:30.782884Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348321466832726911,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-22T22:11:31.073975Z","caller":"traceutil/trace.go:171","msg":"trace[1477279189] transaction","detail":"{read_only:false; response_revision:4530; number_of_response:1; }","duration":"792.131284ms","start":"2026-07-22T22:11:30.281819Z","end":"2026-07-22T22:11:31.07395Z","steps":["trace[1477279189] 'process raft request' (duration: 792.007022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:31.074083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:30.281799Z","time spent":"792.230595ms","remote":"127.0.0.1:33400","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-07-22T22:11:31.074621Z","caller":"traceutil/trace.go:171","msg":"trace[2007940265] transaction","detail":"{read_only:false; response_revision:4532; number_of_response:1; }","duration":"792.656772ms","start":"2026-07-22T22:11:30.281942Z","end":"2026-07-22T22:11:31.074599Z","steps":["trace[2007940265] 'process raft request' (duration: 792.405008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:31.074798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:30.281937Z","time spent":"792.763373ms","remote":"127.0.0.1:33400","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-07-22T22:11:31.075936Z","caller":"traceutil/trace.go:171","msg":"trace[789039070] transaction","detail":"{read_only:false; response_revision:4531; number_of_response:1; }","duration":"794.033893ms","start":"2026-07-22T22:11:30.281887Z","end":"2026-07-22T22:11:31.075921Z","steps":["trace[789039070] 'process raft request' (duration: 792.338607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:31.075996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:30.281871Z","time spent":"794.091774ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:31.074529Z","caller":"traceutil/trace.go:171","msg":"trace[1102441288] linearizableReadLoop","detail":"{readStateIndex:4727; appliedIndex:4722; }","duration":"792.507929ms","start":"2026-07-22T22:11:30.281996Z","end":"2026-07-22T22:11:31.074504Z","steps":["trace[1102441288] 'read index received' (duration: 791.826219ms)","trace[1102441288] 'applied index is now lower than readState.Index' (duration: 680.7µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:31.076184Z","caller":"traceutil/trace.go:171","msg":"trace[1515456353] transaction","detail":"{read_only:false; response_revision:4533; number_of_response:1; }","duration":"793.847299ms","start":"2026-07-22T22:11:30.282325Z","end":"2026-07-22T22:11:31.076173Z","steps":["trace[1515456353] 'process raft request' (duration: 792.080522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:31.076255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:30.282318Z","time spent":"793.904369ms","remote":"127.0.0.1:33400","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-07-22T22:11:31.076334Z","caller":"traceutil/trace.go:171","msg":"trace[1360941066] transaction","detail":"{read_only:false; response_revision:4534; number_of_response:1; }","duration":"792.416009ms","start":"2026-07-22T22:11:30.283908Z","end":"2026-07-22T22:11:31.076324Z","steps":["trace[1360941066] 'process raft request' (duration: 790.54618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:31.076382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:30.283894Z","time spent":"792.460989ms","remote":"127.0.0.1:33400","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-07-22T22:11:31.076569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.56609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-07-22T22:11:31.076608Z","caller":"traceutil/trace.go:171","msg":"trace[1252640481] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4534; }","duration":"794.627531ms","start":"2026-07-22T22:11:30.281967Z","end":"2026-07-22T22:11:31.076594Z","steps":["trace[1252640481] 'agreement among raft nodes before linearized reading' (duration: 794.499709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:31.076661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:30.281962Z","time spent":"794.669592ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":591,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-22T22:11:31.076686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.286915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-07-22T22:11:31.076731Z","caller":"traceutil/trace.go:171","msg":"trace[766482490] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4534; }","duration":"794.352707ms","start":"2026-07-22T22:11:30.282369Z","end":"2026-07-22T22:11:31.076722Z","steps":["trace[766482490] 'agreement among raft nodes before linearized reading' (duration: 794.254575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:31.076777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:30.282343Z","time spent":"794.426817ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-22T22:11:31.076858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.52052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3198"} {"level":"info","ts":"2026-07-22T22:11:31.076894Z","caller":"traceutil/trace.go:171","msg":"trace[828944969] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4534; }","duration":"174.59338ms","start":"2026-07-22T22:11:30.902292Z","end":"2026-07-22T22:11:31.076885Z","steps":["trace[828944969] 'agreement among raft nodes before linearized reading' (duration: 174.502699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:31.076927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.255722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-80b09727-1543-4bc2-a8d9-7b1e1140c819\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-22T22:11:31.076977Z","caller":"traceutil/trace.go:171","msg":"trace[1751577742] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-80b09727-1543-4bc2-a8d9-7b1e1140c819; range_end:; response_count:1; response_revision:4534; }","duration":"728.341212ms","start":"2026-07-22T22:11:30.348628Z","end":"2026-07-22T22:11:31.076969Z","steps":["trace[1751577742] 'agreement among raft nodes before linearized reading' (duration: 728.230541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:31.07701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:30.348609Z","time spent":"728.392893ms","remote":"127.0.0.1:33312","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-80b09727-1543-4bc2-a8d9-7b1e1140c819\" "} {"level":"warn","ts":"2026-07-22T22:11:33.417195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.629161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:33.417379Z","caller":"traceutil/trace.go:171","msg":"trace[2140447372] transaction","detail":"{read_only:false; response_revision:4550; number_of_response:1; }","duration":"316.992235ms","start":"2026-07-22T22:11:33.100372Z","end":"2026-07-22T22:11:33.417364Z","steps":["trace[2140447372] 'process raft request' (duration: 316.913474ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:33.417403Z","caller":"traceutil/trace.go:171","msg":"trace[1214693862] transaction","detail":"{read_only:false; response_revision:4549; number_of_response:1; }","duration":"317.992041ms","start":"2026-07-22T22:11:33.099398Z","end":"2026-07-22T22:11:33.41739Z","steps":["trace[1214693862] 'process raft request' (duration: 30.096276ms)","trace[1214693862] 'compare' (duration: 287.54008ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:33.417449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:33.099369Z","time spent":"318.055963ms","remote":"127.0.0.1:33400","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-07-22T22:11:33.417442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:33.100365Z","time spent":"317.046546ms","remote":"127.0.0.1:33400","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-07-22T22:11:33.417605Z","caller":"traceutil/trace.go:171","msg":"trace[77742410] linearizableReadLoop","detail":"{readStateIndex:4744; appliedIndex:4741; }","duration":"310.451302ms","start":"2026-07-22T22:11:33.107115Z","end":"2026-07-22T22:11:33.417566Z","steps":["trace[77742410] 'read index received' (duration: 22.328823ms)","trace[77742410] 'applied index is now lower than readState.Index' (duration: 288.120739ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:33.417627Z","caller":"traceutil/trace.go:171","msg":"trace[107617439] transaction","detail":"{read_only:false; response_revision:4551; number_of_response:1; }","duration":"316.400356ms","start":"2026-07-22T22:11:33.101183Z","end":"2026-07-22T22:11:33.417584Z","steps":["trace[107617439] 'process raft request' (duration: 316.150313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:33.417784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:33.101177Z","time spent":"316.516598ms","remote":"127.0.0.1:33400","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-07-22T22:11:33.417901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.110595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:566"} {"level":"warn","ts":"2026-07-22T22:11:33.417885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.664766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-80b09727-1543-4bc2-a8d9-7b1e1140c819\" ","response":"range_response_count:1 size:3952"} {"level":"warn","ts":"2026-07-22T22:11:33.417931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.817523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-07-22T22:11:33.417967Z","caller":"traceutil/trace.go:171","msg":"trace[1475068999] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-80b09727-1543-4bc2-a8d9-7b1e1140c819; range_end:; response_count:1; response_revision:4551; }","duration":"128.782188ms","start":"2026-07-22T22:11:33.289164Z","end":"2026-07-22T22:11:33.417946Z","steps":["trace[1475068999] 'agreement among raft nodes before linearized reading' (duration: 128.582105ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:33.418004Z","caller":"traceutil/trace.go:171","msg":"trace[1243359617] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4551; }","duration":"278.918894ms","start":"2026-07-22T22:11:33.139066Z","end":"2026-07-22T22:11:33.417984Z","steps":["trace[1243359617] 'agreement among raft nodes before linearized reading' (duration: 278.747751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:33.418025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.83156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-07-22T22:11:33.417949Z","caller":"traceutil/trace.go:171","msg":"trace[940740171] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4551; }","duration":"308.201617ms","start":"2026-07-22T22:11:33.109733Z","end":"2026-07-22T22:11:33.417934Z","steps":["trace[940740171] 'agreement among raft nodes before linearized reading' (duration: 307.999654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:33.41817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:33.109722Z","time spent":"308.43208ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":589,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-07-22T22:11:33.418187Z","caller":"traceutil/trace.go:171","msg":"trace[378594059] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4551; }","duration":"318.066113ms","start":"2026-07-22T22:11:33.100064Z","end":"2026-07-22T22:11:33.41813Z","steps":["trace[378594059] 'agreement among raft nodes before linearized reading' (duration: 317.586115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:33.418307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:33.10005Z","time spent":"318.238535ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-22T22:11:33.649628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.380921ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:33.649847Z","caller":"traceutil/trace.go:171","msg":"trace[2121900088] linearizableReadLoop","detail":"{readStateIndex:4746; appliedIndex:4744; }","duration":"224.057416ms","start":"2026-07-22T22:11:33.425772Z","end":"2026-07-22T22:11:33.649829Z","steps":["trace[2121900088] 'read index received' (duration: 95.356819ms)","trace[2121900088] 'applied index is now lower than readState.Index' (duration: 128.699557ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:33.649912Z","caller":"traceutil/trace.go:171","msg":"trace[928939593] transaction","detail":"{read_only:false; response_revision:4553; number_of_response:1; }","duration":"224.284019ms","start":"2026-07-22T22:11:33.425616Z","end":"2026-07-22T22:11:33.6499Z","steps":["trace[928939593] 'process raft request' (duration: 224.118466ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:33.64998Z","caller":"traceutil/trace.go:171","msg":"trace[1652522348] transaction","detail":"{read_only:false; response_revision:4554; number_of_response:1; }","duration":"192.590077ms","start":"2026-07-22T22:11:33.45736Z","end":"2026-07-22T22:11:33.64995Z","steps":["trace[1652522348] 'process raft request' (duration: 192.421975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:33.650075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.622572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-07-22T22:11:33.650077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.290629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:10617"} {"level":"warn","ts":"2026-07-22T22:11:33.650074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.593193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:33.650107Z","caller":"traceutil/trace.go:171","msg":"trace[1978154942] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4554; }","duration":"172.671233ms","start":"2026-07-22T22:11:33.477427Z","end":"2026-07-22T22:11:33.650098Z","steps":["trace[1978154942] 'agreement among raft nodes before linearized reading' (duration: 172.527701ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:33.650127Z","caller":"traceutil/trace.go:171","msg":"trace[827010904] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4554; }","duration":"224.36033ms","start":"2026-07-22T22:11:33.425751Z","end":"2026-07-22T22:11:33.650111Z","steps":["trace[827010904] 'agreement among raft nodes before linearized reading' (duration: 224.181317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:33.650114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.683643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:11:33.650152Z","caller":"traceutil/trace.go:171","msg":"trace[1077974331] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:4554; }","duration":"155.720954ms","start":"2026-07-22T22:11:33.494412Z","end":"2026-07-22T22:11:33.650133Z","steps":["trace[1077974331] 'agreement among raft nodes before linearized reading' (duration: 155.564652ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:33.650197Z","caller":"traceutil/trace.go:171","msg":"trace[960944856] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:4554; }","duration":"155.800706ms","start":"2026-07-22T22:11:33.494382Z","end":"2026-07-22T22:11:33.650183Z","steps":["trace[960944856] 'agreement among raft nodes before linearized reading' (duration: 155.633423ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:33.649862Z","caller":"traceutil/trace.go:171","msg":"trace[676078694] transaction","detail":"{read_only:false; response_revision:4552; number_of_response:1; }","duration":"224.706477ms","start":"2026-07-22T22:11:33.425121Z","end":"2026-07-22T22:11:33.649828Z","steps":["trace[676078694] 'process raft request' (duration: 96.045651ms)","trace[676078694] 'compare' (duration: 128.2678ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:35.230387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.806139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:35.2305Z","caller":"traceutil/trace.go:171","msg":"trace[1950167132] transaction","detail":"{read_only:false; response_revision:4579; number_of_response:1; }","duration":"332.724336ms","start":"2026-07-22T22:11:34.897757Z","end":"2026-07-22T22:11:35.230481Z","steps":["trace[1950167132] 'process raft request' (duration: 170.725494ms)","trace[1950167132] 'compare' (duration: 161.703827ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:35.230558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:34.897725Z","time spent":"332.806398ms","remote":"127.0.0.1:33312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9332,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:35.239749Z","caller":"traceutil/trace.go:171","msg":"trace[1253752438] linearizableReadLoop","detail":"{readStateIndex:4774; appliedIndex:4773; }","duration":"141.793816ms","start":"2026-07-22T22:11:35.097935Z","end":"2026-07-22T22:11:35.239729Z","steps":["trace[1253752438] 'read index received' (duration: 133.098921ms)","trace[1253752438] 'applied index is now lower than readState.Index' (duration: 8.692544ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:35.239944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.997849ms","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-07-22T22:11:35.240027Z","caller":"traceutil/trace.go:171","msg":"trace[1763019333] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4580; }","duration":"142.122481ms","start":"2026-07-22T22:11:35.097896Z","end":"2026-07-22T22:11:35.240019Z","steps":["trace[1763019333] 'agreement among raft nodes before linearized reading' (duration: 141.916158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:35.240073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.883762ms","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-07-22T22:11:35.240145Z","caller":"traceutil/trace.go:171","msg":"trace[963905360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4580; }","duration":"128.994913ms","start":"2026-07-22T22:11:35.111133Z","end":"2026-07-22T22:11:35.240128Z","steps":["trace[963905360] 'agreement among raft nodes before linearized reading' (duration: 128.78951ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:37.527285Z","caller":"traceutil/trace.go:171","msg":"trace[594759915] transaction","detail":"{read_only:false; response_revision:4617; number_of_response:1; }","duration":"105.564487ms","start":"2026-07-22T22:11:37.421687Z","end":"2026-07-22T22:11:37.527251Z","steps":["trace[594759915] 'process raft request' (duration: 105.372174ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:42.680194Z","caller":"traceutil/trace.go:171","msg":"trace[833780341] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"208.274197ms","start":"2026-07-22T22:11:42.471906Z","end":"2026-07-22T22:11:42.68018Z","steps":["trace[833780341] 'process raft request' (duration: 208.061323ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:42.680113Z","caller":"traceutil/trace.go:171","msg":"trace[1290967143] linearizableReadLoop","detail":"{readStateIndex:4867; appliedIndex:4866; }","duration":"197.53233ms","start":"2026-07-22T22:11:42.482559Z","end":"2026-07-22T22:11:42.680091Z","steps":["trace[1290967143] 'read index received' (duration: 197.336926ms)","trace[1290967143] 'applied index is now lower than readState.Index' (duration: 194.524µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:42.680489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.912227ms","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":"info","ts":"2026-07-22T22:11:42.680552Z","caller":"traceutil/trace.go:171","msg":"trace[1469394365] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4670; }","duration":"198.007719ms","start":"2026-07-22T22:11:42.482533Z","end":"2026-07-22T22:11:42.68054Z","steps":["trace[1469394365] 'agreement among raft nodes before linearized reading' (duration: 197.818326ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:42.864824Z","caller":"traceutil/trace.go:171","msg":"trace[1702101165] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"178.014263ms","start":"2026-07-22T22:11:42.686778Z","end":"2026-07-22T22:11:42.864792Z","steps":["trace[1702101165] 'process raft request' (duration: 128.986778ms)","trace[1702101165] 'compare' (duration: 48.844962ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:42.865051Z","caller":"traceutil/trace.go:171","msg":"trace[1199029203] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"177.966212ms","start":"2026-07-22T22:11:42.687075Z","end":"2026-07-22T22:11:42.865041Z","steps":["trace[1199029203] 'process raft request' (duration: 177.649136ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:42.865327Z","caller":"traceutil/trace.go:171","msg":"trace[587982933] transaction","detail":"{read_only:false; response_revision:4674; number_of_response:1; }","duration":"177.964162ms","start":"2026-07-22T22:11:42.687357Z","end":"2026-07-22T22:11:42.865321Z","steps":["trace[587982933] 'process raft request' (duration: 177.585385ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:42.865307Z","caller":"traceutil/trace.go:171","msg":"trace[554920624] linearizableReadLoop","detail":"{readStateIndex:4871; appliedIndex:4867; }","duration":"130.534448ms","start":"2026-07-22T22:11:42.734749Z","end":"2026-07-22T22:11:42.865283Z","steps":["trace[554920624] 'read index received' (duration: 6.904193ms)","trace[554920624] 'applied index is now lower than readState.Index' (duration: 123.629124ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:42.866634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.875314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:11:42.866689Z","caller":"traceutil/trace.go:171","msg":"trace[599121077] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4674; }","duration":"132.012846ms","start":"2026-07-22T22:11:42.734664Z","end":"2026-07-22T22:11:42.866677Z","steps":["trace[599121077] 'agreement among raft nodes before linearized reading' (duration: 131.898414ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:43.231698Z","caller":"traceutil/trace.go:171","msg":"trace[1603551730] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"263.496437ms","start":"2026-07-22T22:11:42.968176Z","end":"2026-07-22T22:11:43.231672Z","steps":["trace[1603551730] 'process raft request' (duration: 253.069742ms)","trace[1603551730] 'compare' (duration: 10.308413ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:12:05.793424Z","caller":"traceutil/trace.go:171","msg":"trace[2145594402] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"387.001571ms","start":"2026-07-22T22:12:05.406397Z","end":"2026-07-22T22:12:05.793399Z","steps":["trace[2145594402] 'process raft request' (duration: 386.856307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:05.793675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:05.406382Z","time spent":"387.123775ms","remote":"127.0.0.1:33400","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-07-22T22:12:06.301266Z","caller":"traceutil/trace.go:171","msg":"trace[26269518] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"892.898739ms","start":"2026-07-22T22:12:05.40835Z","end":"2026-07-22T22:12:06.301249Z","steps":["trace[26269518] 'process raft request' (duration: 892.761095ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:06.301363Z","caller":"traceutil/trace.go:171","msg":"trace[1390922775] linearizableReadLoop","detail":"{readStateIndex:5218; appliedIndex:5216; }","duration":"686.263237ms","start":"2026-07-22T22:12:05.615089Z","end":"2026-07-22T22:12:06.301352Z","steps":["trace[1390922775] 'read index received' (duration: 178.176751ms)","trace[1390922775] 'applied index is now lower than readState.Index' (duration: 508.085656ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:12:06.301371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:05.40834Z","time spent":"892.974841ms","remote":"127.0.0.1:33400","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-07-22T22:12:06.301418Z","caller":"traceutil/trace.go:171","msg":"trace[971790741] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"531.308205ms","start":"2026-07-22T22:12:05.770104Z","end":"2026-07-22T22:12:06.301412Z","steps":["trace[971790741] 'process raft request' (duration: 531.11577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:06.301462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:05.770082Z","time spent":"531.350287ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:12:06.301564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.643026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-07-22T22:12:06.301584Z","caller":"traceutil/trace.go:171","msg":"trace[423246116] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5009; }","duration":"309.662507ms","start":"2026-07-22T22:12:05.991914Z","end":"2026-07-22T22:12:06.301576Z","steps":["trace[423246116] 'agreement among raft nodes before linearized reading' (duration: 309.601585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:06.301578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.767675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-22T22:12:06.301654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.578435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-credential-setup-7wwdh\" ","response":"range_response_count:1 size:7551"} {"level":"info","ts":"2026-07-22T22:12:06.301643Z","caller":"traceutil/trace.go:171","msg":"trace[160820952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5009; }","duration":"622.848117ms","start":"2026-07-22T22:12:05.678775Z","end":"2026-07-22T22:12:06.301623Z","steps":["trace[160820952] 'agreement among raft nodes before linearized reading' (duration: 622.688893ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:06.301671Z","caller":"traceutil/trace.go:171","msg":"trace[1822693547] range","detail":"{range_begin:/registry/pods/openstack/keystone-credential-setup-7wwdh; range_end:; response_count:1; response_revision:5009; }","duration":"686.638217ms","start":"2026-07-22T22:12:05.615028Z","end":"2026-07-22T22:12:06.301666Z","steps":["trace[1822693547] 'agreement among raft nodes before linearized reading' (duration: 686.587646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:06.301687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:05.615007Z","time spent":"686.675887ms","remote":"127.0.0.1:33312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":7574,"request content":"key:\"/registry/pods/openstack/keystone-credential-setup-7wwdh\" "} {"level":"warn","ts":"2026-07-22T22:12:06.301691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:05.678762Z","time spent":"622.918439ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-22T22:12:06.301716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.161118ms","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-07-22T22:12:06.301737Z","caller":"traceutil/trace.go:171","msg":"trace[1537490356] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5009; }","duration":"107.20435ms","start":"2026-07-22T22:12:06.194526Z","end":"2026-07-22T22:12:06.30173Z","steps":["trace[1537490356] 'agreement among raft nodes before linearized reading' (duration: 107.142888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:06.301827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.256696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:12:06.301846Z","caller":"traceutil/trace.go:171","msg":"trace[795865627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5009; }","duration":"225.325468ms","start":"2026-07-22T22:12:06.076515Z","end":"2026-07-22T22:12:06.301841Z","steps":["trace[795865627] 'agreement among raft nodes before linearized reading' (duration: 225.294677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:06.301853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.808916ms","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-07-22T22:12:06.301874Z","caller":"traceutil/trace.go:171","msg":"trace[289350920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5009; }","duration":"605.857576ms","start":"2026-07-22T22:12:05.69601Z","end":"2026-07-22T22:12:06.301868Z","steps":["trace[289350920] 'agreement among raft nodes before linearized reading' (duration: 605.797355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:06.301894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:05.695995Z","time spent":"605.893868ms","remote":"127.0.0.1:33400","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-07-22T22:12:06.302013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.051753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-07-22T22:12:06.30201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.313569ms","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-07-22T22:12:06.302033Z","caller":"traceutil/trace.go:171","msg":"trace[1058351387] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5009; }","duration":"315.108355ms","start":"2026-07-22T22:12:05.986919Z","end":"2026-07-22T22:12:06.302027Z","steps":["trace[1058351387] 'agreement among raft nodes before linearized reading' (duration: 315.055033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:06.30205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:05.986899Z","time spent":"315.146135ms","remote":"127.0.0.1:33222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-07-22T22:12:06.302056Z","caller":"traceutil/trace.go:171","msg":"trace[592494863] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5009; }","duration":"606.378501ms","start":"2026-07-22T22:12:05.695663Z","end":"2026-07-22T22:12:06.302042Z","steps":["trace[592494863] 'agreement among raft nodes before linearized reading' (duration: 606.253128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:06.302094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:05.695648Z","time spent":"606.436422ms","remote":"127.0.0.1:33400","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-07-22T22:12:06.302105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.28783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-22T22:12:06.302125Z","caller":"traceutil/trace.go:171","msg":"trace[34095926] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5009; }","duration":"505.344941ms","start":"2026-07-22T22:12:05.796774Z","end":"2026-07-22T22:12:06.302119Z","steps":["trace[34095926] 'agreement among raft nodes before linearized reading' (duration: 505.29108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:06.302141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:05.796751Z","time spent":"505.386423ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-07-22T22:12:06.301601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:05.991895Z","time spent":"309.702057ms","remote":"127.0.0.1:33222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-22T22:12:06.301647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.142404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:12:06.302256Z","caller":"traceutil/trace.go:171","msg":"trace[1393712582] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:5009; }","duration":"124.770052ms","start":"2026-07-22T22:12:06.177471Z","end":"2026-07-22T22:12:06.302241Z","steps":["trace[1393712582] 'agreement among raft nodes before linearized reading' (duration: 124.150244ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:16.501151Z","caller":"traceutil/trace.go:171","msg":"trace[393910222] linearizableReadLoop","detail":"{readStateIndex:5299; appliedIndex:5298; }","duration":"164.778346ms","start":"2026-07-22T22:12:16.336347Z","end":"2026-07-22T22:12:16.501125Z","steps":["trace[393910222] 'read index received' (duration: 164.5479ms)","trace[393910222] 'applied index is now lower than readState.Index' (duration: 229.566µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:12:16.501455Z","caller":"traceutil/trace.go:171","msg":"trace[506520409] transaction","detail":"{read_only:false; response_revision:5085; number_of_response:1; }","duration":"170.472539ms","start":"2026-07-22T22:12:16.33096Z","end":"2026-07-22T22:12:16.501432Z","steps":["trace[506520409] 'process raft request' (duration: 170.002385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:16.501856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.634749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-22T22:12:16.50287Z","caller":"traceutil/trace.go:171","msg":"trace[1937038598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5085; }","duration":"159.62439ms","start":"2026-07-22T22:12:16.343181Z","end":"2026-07-22T22:12:16.502805Z","steps":["trace[1937038598] 'agreement among raft nodes before linearized reading' (duration: 158.350021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:16.50195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.609981ms","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-07-22T22:12:16.502166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.238983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-22T22:12:16.503897Z","caller":"traceutil/trace.go:171","msg":"trace[1626179118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5085; }","duration":"166.972943ms","start":"2026-07-22T22:12:16.336297Z","end":"2026-07-22T22:12:16.50327Z","steps":["trace[1626179118] 'agreement among raft nodes before linearized reading' (duration: 165.59466ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:16.504003Z","caller":"traceutil/trace.go:171","msg":"trace[555218958] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5085; }","duration":"149.036888ms","start":"2026-07-22T22:12:16.354903Z","end":"2026-07-22T22:12:16.50394Z","steps":["trace[555218958] 'agreement among raft nodes before linearized reading' (duration: 147.226393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:16.50221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.749759ms","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-07-22T22:12:16.502147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.394847ms","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-07-22T22:12:16.504318Z","caller":"traceutil/trace.go:171","msg":"trace[42360250] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5085; }","duration":"145.587883ms","start":"2026-07-22T22:12:16.358717Z","end":"2026-07-22T22:12:16.504305Z","steps":["trace[42360250] 'agreement among raft nodes before linearized reading' (duration: 143.330755ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:16.504644Z","caller":"traceutil/trace.go:171","msg":"trace[1133086681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5085; }","duration":"149.776409ms","start":"2026-07-22T22:12:16.35441Z","end":"2026-07-22T22:12:16.504187Z","steps":["trace[1133086681] 'agreement among raft nodes before linearized reading' (duration: 147.769369ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:29.912842Z","caller":"traceutil/trace.go:171","msg":"trace[63932065] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"108.607561ms","start":"2026-07-22T22:12:29.80421Z","end":"2026-07-22T22:12:29.912817Z","steps":["trace[63932065] 'process raft request' (duration: 108.462267ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:30.170867Z","caller":"traceutil/trace.go:171","msg":"trace[925463893] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"116.879412ms","start":"2026-07-22T22:12:30.053952Z","end":"2026-07-22T22:12:30.170831Z","steps":["trace[925463893] 'process raft request' (duration: 63.047323ms)","trace[925463893] 'compare' (duration: 53.048031ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:12:30.389335Z","caller":"traceutil/trace.go:171","msg":"trace[1347252410] transaction","detail":"{read_only:false; response_revision:5205; number_of_response:1; }","duration":"148.030615ms","start":"2026-07-22T22:12:30.241274Z","end":"2026-07-22T22:12:30.389305Z","steps":["trace[1347252410] 'process raft request' (duration: 48.773495ms)","trace[1347252410] 'compare' (duration: 99.068074ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:12:40.775967Z","caller":"traceutil/trace.go:171","msg":"trace[908586146] transaction","detail":"{read_only:false; response_revision:5303; number_of_response:1; }","duration":"192.128343ms","start":"2026-07-22T22:12:40.583813Z","end":"2026-07-22T22:12:40.775941Z","steps":["trace[908586146] 'process raft request' (duration: 191.955987ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:40.777019Z","caller":"traceutil/trace.go:171","msg":"trace[1309658299] linearizableReadLoop","detail":"{readStateIndex:5528; appliedIndex:5526; }","duration":"151.60472ms","start":"2026-07-22T22:12:40.62539Z","end":"2026-07-22T22:12:40.776995Z","steps":["trace[1309658299] 'read index received' (duration: 150.321033ms)","trace[1309658299] 'applied index is now lower than readState.Index' (duration: 1.282527ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:12:40.777095Z","caller":"traceutil/trace.go:171","msg":"trace[1862939886] transaction","detail":"{read_only:false; response_revision:5304; number_of_response:1; }","duration":"174.407065ms","start":"2026-07-22T22:12:40.602659Z","end":"2026-07-22T22:12:40.777066Z","steps":["trace[1862939886] 'process raft request' (duration: 174.227058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:40.777281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.862969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-22T22:12:40.777342Z","caller":"traceutil/trace.go:171","msg":"trace[380516481] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5304; }","duration":"151.962393ms","start":"2026-07-22T22:12:40.625365Z","end":"2026-07-22T22:12:40.777327Z","steps":["trace[380516481] 'agreement among raft nodes before linearized reading' (duration: 151.767956ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:13:17.545292Z","caller":"traceutil/trace.go:171","msg":"trace[518210211] transaction","detail":"{read_only:false; response_revision:5622; number_of_response:1; }","duration":"146.814737ms","start":"2026-07-22T22:13:17.398456Z","end":"2026-07-22T22:13:17.545271Z","steps":["trace[518210211] 'process raft request' (duration: 146.694672ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:13:18.363041Z","caller":"traceutil/trace.go:171","msg":"trace[1280074694] transaction","detail":"{read_only:false; response_revision:5623; number_of_response:1; }","duration":"108.925302ms","start":"2026-07-22T22:13:18.254082Z","end":"2026-07-22T22:13:18.363008Z","steps":["trace[1280074694] 'process raft request' (duration: 108.66959ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:14:46.575686Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3185} {"level":"info","ts":"2026-07-22T22:14:46.684665Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3185,"took":"107.591779ms","hash":2335737286,"current-db-size-bytes":59076608,"current-db-size":"59 MB","current-db-size-in-use-bytes":31387648,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-22T22:14:46.68474Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2335737286,"revision":3185,"compact-revision":-1} {"level":"info","ts":"2026-07-22T22:15:34.710431Z","caller":"traceutil/trace.go:171","msg":"trace[917697685] linearizableReadLoop","detail":"{readStateIndex:7057; appliedIndex:7056; }","duration":"141.354009ms","start":"2026-07-22T22:15:34.569054Z","end":"2026-07-22T22:15:34.710408Z","steps":["trace[917697685] 'read index received' (duration: 141.165618ms)","trace[917697685] 'applied index is now lower than readState.Index' (duration: 187.471µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:15:34.712434Z","caller":"traceutil/trace.go:171","msg":"trace[1657414959] transaction","detail":"{read_only:false; response_revision:6754; number_of_response:1; }","duration":"149.568533ms","start":"2026-07-22T22:15:34.562854Z","end":"2026-07-22T22:15:34.712422Z","steps":["trace[1657414959] 'process raft request' (duration: 147.426317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:15:34.714622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.553306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-07-22T22:15:34.714827Z","caller":"traceutil/trace.go:171","msg":"trace[1715827667] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6754; }","duration":"145.806291ms","start":"2026-07-22T22:15:34.569008Z","end":"2026-07-22T22:15:34.714814Z","steps":["trace[1715827667] 'agreement among raft nodes before linearized reading' (duration: 145.545646ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:15:34.984447Z","caller":"traceutil/trace.go:171","msg":"trace[703129047] transaction","detail":"{read_only:false; response_revision:6758; number_of_response:1; }","duration":"222.471754ms","start":"2026-07-22T22:15:34.761956Z","end":"2026-07-22T22:15:34.984428Z","steps":["trace[703129047] 'process raft request' (duration: 222.337786ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:15:35.142239Z","caller":"traceutil/trace.go:171","msg":"trace[1059297494] transaction","detail":"{read_only:false; number_of_response:0; response_revision:6758; }","duration":"146.698753ms","start":"2026-07-22T22:15:34.995525Z","end":"2026-07-22T22:15:35.142224Z","steps":["trace[1059297494] 'process raft request' (duration: 146.424947ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:15:35.259937Z","caller":"traceutil/trace.go:171","msg":"trace[1867114894] transaction","detail":"{read_only:false; response_revision:6760; number_of_response:1; }","duration":"106.528521ms","start":"2026-07-22T22:15:35.153385Z","end":"2026-07-22T22:15:35.259914Z","steps":["trace[1867114894] 'process raft request' (duration: 106.362681ms)"],"step_count":1}