{"level":"warn","ts":"2026-04-08T00:28:23.952153Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T00:28:23.952232Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246: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-04-08T00:28:23.9523Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T00:28:23.952307Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-08T00:28:23.952322Z","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-04-08T00:28:23.952926Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-04-08T00:28:23.953009Z","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.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246: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-04-08T00:28:23.956756Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.529294ms"} {"level":"info","ts":"2026-04-08T00:28:23.966036Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-04-08T00:28:23.96626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T00:28:23.966289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-04-08T00:28:23.966304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T00:28:23.966338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-04-08T00:28:23.966383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-04-08T00:28:23.97416Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T00:28:23.977252Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T00:28:23.978583Z","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-04-08T00:28:23.981523Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T00:28:23.986592Z","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-04-08T00:28:23.987261Z","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-04-08T00:28:23.987417Z","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-04-08T00:28:23.98743Z","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-04-08T00:28:23.9882Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T00:28:23.988413Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T00:28:23.988478Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-08T00:28:23.988514Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T00:28:23.988514Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-08T00:28:23.988697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-04-08T00:28:23.988772Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-08T00:28:24.766736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T00:28:24.766803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T00:28:24.766828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-04-08T00:28:24.766849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-04-08T00:28:24.766862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-08T00:28:24.766895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-04-08T00:28:24.766917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-08T00:28:24.768301Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T00:28:24.768352Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T00:28:24.768641Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T00:28:24.768869Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T00:28:24.768876Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:28:24.768916Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T00:28:24.770865Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-04-08T00:28:24.771032Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:28:24.771127Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:28:24.771161Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:28:24.771829Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T00:28:27.335353Z","caller":"traceutil/trace.go:171","msg":"trace[1168173165] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"203.177437ms","start":"2026-04-08T00:28:27.13214Z","end":"2026-04-08T00:28:27.335317Z","steps":["trace[1168173165] 'process raft request' (duration: 202.988363ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:27.336763Z","caller":"traceutil/trace.go:171","msg":"trace[1266685394] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:75; }","duration":"157.297505ms","start":"2026-04-08T00:28:27.17944Z","end":"2026-04-08T00:28:27.336737Z","steps":["trace[1266685394] 'read index received' (duration: 155.658306ms)","trace[1266685394] 'applied index is now lower than readState.Index' (duration: 1.637819ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:28:27.336828Z","caller":"traceutil/trace.go:171","msg":"trace[1244811810] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"202.85941ms","start":"2026-04-08T00:28:27.133924Z","end":"2026-04-08T00:28:27.336784Z","steps":["trace[1244811810] 'process raft request' (duration: 202.686516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:27.33705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.570442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T00:28:27.337115Z","caller":"traceutil/trace.go:171","msg":"trace[849054867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"157.725416ms","start":"2026-04-08T00:28:27.179377Z","end":"2026-04-08T00:28:27.337103Z","steps":["trace[849054867] 'agreement among raft nodes before linearized reading' (duration: 157.511441ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:55.381156Z","caller":"traceutil/trace.go:171","msg":"trace[990024870] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"109.766061ms","start":"2026-04-08T00:28:55.271364Z","end":"2026-04-08T00:28:55.38113Z","steps":["trace[990024870] 'process raft request' (duration: 109.611368ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:55.386477Z","caller":"traceutil/trace.go:171","msg":"trace[433013739] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"112.739533ms","start":"2026-04-08T00:28:55.273694Z","end":"2026-04-08T00:28:55.386434Z","steps":["trace[433013739] 'process raft request' (duration: 112.362063ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:29:01.92874Z","caller":"traceutil/trace.go:171","msg":"trace[1966765168] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"346.090608ms","start":"2026-04-08T00:29:01.582575Z","end":"2026-04-08T00:29:01.928666Z","steps":["trace[1966765168] 'read index received' (duration: 344.858918ms)","trace[1966765168] 'applied index is now lower than readState.Index' (duration: 1.22722ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:29:01.928962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.369395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:29:01.928994Z","caller":"traceutil/trace.go:171","msg":"trace[53148508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"346.471857ms","start":"2026-04-08T00:29:01.582512Z","end":"2026-04-08T00:29:01.928984Z","steps":["trace[53148508] 'agreement among raft nodes before linearized reading' (duration: 346.377255ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:29:01.929536Z","caller":"traceutil/trace.go:171","msg":"trace[808553410] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"409.246843ms","start":"2026-04-08T00:29:01.520242Z","end":"2026-04-08T00:29:01.929488Z","steps":["trace[808553410] 'process raft request' (duration: 407.604112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:29:01.929031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:29:01.582477Z","time spent":"346.546239ms","remote":"127.0.0.1:34468","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-04-08T00:29:01.930632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:29:01.520222Z","time spent":"409.447937ms","remote":"127.0.0.1:34566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:29:02.256903Z","caller":"traceutil/trace.go:171","msg":"trace[1282809392] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"264.354373ms","start":"2026-04-08T00:29:01.992517Z","end":"2026-04-08T00:29:02.256871Z","steps":["trace[1282809392] 'process raft request' (duration: 206.112438ms)","trace[1282809392] 'compare' (duration: 57.724413ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:29:02.833884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.392736ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:29:02.834073Z","caller":"traceutil/trace.go:171","msg":"trace[2061218144] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:577; }","duration":"434.609462ms","start":"2026-04-08T00:29:02.399433Z","end":"2026-04-08T00:29:02.834043Z","steps":["trace[2061218144] 'range keys from in-memory index tree' (duration: 434.363006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:29:02.834408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.630908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:29:02.834566Z","caller":"traceutil/trace.go:171","msg":"trace[86293951] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"359.077645ms","start":"2026-04-08T00:29:02.475432Z","end":"2026-04-08T00:29:02.83451Z","steps":["trace[86293951] 'read index received' (duration: 139.024707ms)","trace[86293951] 'applied index is now lower than readState.Index' (duration: 220.051508ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:29:02.834651Z","caller":"traceutil/trace.go:171","msg":"trace[541464929] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"556.423512ms","start":"2026-04-08T00:29:02.278213Z","end":"2026-04-08T00:29:02.834637Z","steps":["trace[541464929] 'process raft request' (duration: 336.297241ms)","trace[541464929] 'compare' (duration: 219.421904ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:29:02.834728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:29:02.27816Z","time spent":"556.535115ms","remote":"127.0.0.1:34670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:29:02.834948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.486486ms","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-04-08T00:29:02.835058Z","caller":"traceutil/trace.go:171","msg":"trace[533843074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"359.610059ms","start":"2026-04-08T00:29:02.475425Z","end":"2026-04-08T00:29:02.835035Z","steps":["trace[533843074] 'agreement among raft nodes before linearized reading' (duration: 359.291022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:29:02.835135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:29:02.475364Z","time spent":"359.758303ms","remote":"127.0.0.1:34768","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-04-08T00:29:02.836402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.417973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:29:02.837063Z","caller":"traceutil/trace.go:171","msg":"trace[184876322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"255.09771ms","start":"2026-04-08T00:29:02.581933Z","end":"2026-04-08T00:29:02.837031Z","steps":["trace[184876322] 'agreement among raft nodes before linearized reading' (duration: 254.389722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:29:02.837153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.361641ms","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-04-08T00:29:02.837203Z","caller":"traceutil/trace.go:171","msg":"trace[1577974605] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"240.440983ms","start":"2026-04-08T00:29:02.596747Z","end":"2026-04-08T00:29:02.837188Z","steps":["trace[1577974605] 'agreement among raft nodes before linearized reading' (duration: 240.280209ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:29:02.836973Z","caller":"traceutil/trace.go:171","msg":"trace[36047960] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"163.861702ms","start":"2026-04-08T00:29:02.673084Z","end":"2026-04-08T00:29:02.836946Z","steps":["trace[36047960] 'process raft request' (duration: 162.656042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:29:17.625502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.626708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-8kcwk\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-08T00:29:17.625608Z","caller":"traceutil/trace.go:171","msg":"trace[1904966012] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-8kcwk; range_end:; response_count:1; response_revision:739; }","duration":"308.776802ms","start":"2026-04-08T00:29:17.316809Z","end":"2026-04-08T00:29:17.625586Z","steps":["trace[1904966012] 'range keys from in-memory index tree' (duration: 308.438093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:29:17.625649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:29:17.316786Z","time spent":"308.854044ms","remote":"127.0.0.1:34670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-8kcwk\" "} {"level":"warn","ts":"2026-04-08T00:30:45.271859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.464355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:30:45.271982Z","caller":"traceutil/trace.go:171","msg":"trace[1808121651] linearizableReadLoop","detail":"{readStateIndex:1911; appliedIndex:1910; }","duration":"501.136373ms","start":"2026-04-08T00:30:44.770828Z","end":"2026-04-08T00:30:45.271964Z","steps":["trace[1808121651] 'read index received' (duration: 31.426673ms)","trace[1808121651] 'applied index is now lower than readState.Index' (duration: 469.70857ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:30:45.272253Z","caller":"traceutil/trace.go:171","msg":"trace[2087101848] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"615.491162ms","start":"2026-04-08T00:30:44.656745Z","end":"2026-04-08T00:30:45.272236Z","steps":["trace[2087101848] 'process raft request' (duration: 145.551887ms)","trace[2087101848] 'compare' (duration: 469.195817ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:30:45.272325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:30:44.656731Z","time spent":"615.559565ms","remote":"127.0.0.1:34768","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-04-08T00:30:45.273195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.332893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e5dfffc4-4c14-4cb7-af96-b1e62ed1e18c\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-08T00:30:45.273522Z","caller":"traceutil/trace.go:171","msg":"trace[157083235] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e5dfffc4-4c14-4cb7-af96-b1e62ed1e18c; range_end:; response_count:1; response_revision:1838; }","duration":"502.726124ms","start":"2026-04-08T00:30:44.770772Z","end":"2026-04-08T00:30:45.273498Z","steps":["trace[157083235] 'agreement among raft nodes before linearized reading' (duration: 501.76159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:30:45.273679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:30:44.770747Z","time spent":"502.912808ms","remote":"127.0.0.1:34670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e5dfffc4-4c14-4cb7-af96-b1e62ed1e18c\" "} {"level":"warn","ts":"2026-04-08T00:30:45.623512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.650846ms","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-04-08T00:30:45.623598Z","caller":"traceutil/trace.go:171","msg":"trace[2146057171] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1839; }","duration":"181.8024ms","start":"2026-04-08T00:30:45.441775Z","end":"2026-04-08T00:30:45.623578Z","steps":["trace[2146057171] 'range keys from in-memory index tree' (duration: 181.41459ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:30:45.623664Z","caller":"traceutil/trace.go:171","msg":"trace[1070334700] linearizableReadLoop","detail":"{readStateIndex:1913; appliedIndex:1912; }","duration":"121.742737ms","start":"2026-04-08T00:30:45.501887Z","end":"2026-04-08T00:30:45.62363Z","steps":["trace[1070334700] 'read index received' (duration: 80.897315ms)","trace[1070334700] 'applied index is now lower than readState.Index' (duration: 40.844042ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:30:45.624129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.665823ms","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-04-08T00:30:45.624241Z","caller":"traceutil/trace.go:171","msg":"trace[1112912902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1839; }","duration":"155.889689ms","start":"2026-04-08T00:30:45.468324Z","end":"2026-04-08T00:30:45.624214Z","steps":["trace[1112912902] 'range keys from in-memory index tree' (duration: 154.843812ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:30:45.624522Z","caller":"traceutil/trace.go:171","msg":"trace[1497957005] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"129.572688ms","start":"2026-04-08T00:30:45.494209Z","end":"2026-04-08T00:30:45.623782Z","steps":["trace[1497957005] 'process raft request' (duration: 88.018437ms)","trace[1497957005] 'compare' (duration: 41.224992ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:30:45.624642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.735634ms","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-04-08T00:30:45.624705Z","caller":"traceutil/trace.go:171","msg":"trace[1817633070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1840; }","duration":"122.864797ms","start":"2026-04-08T00:30:45.501823Z","end":"2026-04-08T00:30:45.624688Z","steps":["trace[1817633070] 'agreement among raft nodes before linearized reading' (duration: 121.973554ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:30:45.901777Z","caller":"traceutil/trace.go:171","msg":"trace[289724669] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"198.388004ms","start":"2026-04-08T00:30:45.703341Z","end":"2026-04-08T00:30:45.901729Z","steps":["trace[289724669] 'process raft request' (duration: 171.951129ms)","trace[289724669] 'compare' (duration: 26.29072ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:30:58.762337Z","caller":"traceutil/trace.go:171","msg":"trace[783414158] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"151.572979ms","start":"2026-04-08T00:30:58.610737Z","end":"2026-04-08T00:30:58.76231Z","steps":["trace[783414158] 'process raft request' (duration: 150.017239ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:20.111003Z","caller":"traceutil/trace.go:171","msg":"trace[1856487165] linearizableReadLoop","detail":"{readStateIndex:2163; appliedIndex:2162; }","duration":"232.637058ms","start":"2026-04-08T00:31:19.878324Z","end":"2026-04-08T00:31:20.110961Z","steps":["trace[1856487165] 'read index received' (duration: 227.664643ms)","trace[1856487165] 'applied index is now lower than readState.Index' (duration: 4.970525ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:31:20.111428Z","caller":"traceutil/trace.go:171","msg":"trace[1756199356] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"292.793896ms","start":"2026-04-08T00:31:19.818611Z","end":"2026-04-08T00:31:20.111405Z","steps":["trace[1756199356] 'process raft request' (duration: 287.276547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:20.112102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.185592ms","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-04-08T00:31:20.112383Z","caller":"traceutil/trace.go:171","msg":"trace[951469893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2083; }","duration":"234.068165ms","start":"2026-04-08T00:31:19.87829Z","end":"2026-04-08T00:31:20.112358Z","steps":["trace[951469893] 'agreement among raft nodes before linearized reading' (duration: 233.107271ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:20.135503Z","caller":"traceutil/trace.go:171","msg":"trace[394921457] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"108.27277ms","start":"2026-04-08T00:31:20.027207Z","end":"2026-04-08T00:31:20.135479Z","steps":["trace[394921457] 'process raft request' (duration: 108.094485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:20.135672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.27199ms","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-04-08T00:31:20.135753Z","caller":"traceutil/trace.go:171","msg":"trace[1772731801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2084; }","duration":"257.586928ms","start":"2026-04-08T00:31:19.878146Z","end":"2026-04-08T00:31:20.135733Z","steps":["trace[1772731801] 'agreement among raft nodes before linearized reading' (duration: 257.28769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:20.423337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.496429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-08T00:31:20.42344Z","caller":"traceutil/trace.go:171","msg":"trace[690139863] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2087; }","duration":"145.680544ms","start":"2026-04-08T00:31:20.277733Z","end":"2026-04-08T00:31:20.423413Z","steps":["trace[690139863] 'range keys from in-memory index tree' (duration: 145.264713ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:20.975142Z","caller":"traceutil/trace.go:171","msg":"trace[1954927126] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"126.595463ms","start":"2026-04-08T00:31:20.848513Z","end":"2026-04-08T00:31:20.975108Z","steps":["trace[1954927126] 'process raft request' (duration: 110.196709ms)","trace[1954927126] 'compare' (duration: 16.28653ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:31:21.001167Z","caller":"traceutil/trace.go:171","msg":"trace[684972867] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"152.585998ms","start":"2026-04-08T00:31:20.84856Z","end":"2026-04-08T00:31:21.001146Z","steps":["trace[684972867] 'process raft request' (duration: 152.455805ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:21.883004Z","caller":"traceutil/trace.go:171","msg":"trace[397873686] linearizableReadLoop","detail":"{readStateIndex:2172; appliedIndex:2171; }","duration":"300.754773ms","start":"2026-04-08T00:31:21.582225Z","end":"2026-04-08T00:31:21.88298Z","steps":["trace[397873686] 'read index received' (duration: 300.555709ms)","trace[397873686] 'applied index is now lower than readState.Index' (duration: 198.214µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:31:21.883403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.156144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:31:21.883498Z","caller":"traceutil/trace.go:171","msg":"trace[293646210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2092; }","duration":"301.312818ms","start":"2026-04-08T00:31:21.58216Z","end":"2026-04-08T00:31:21.883473Z","steps":["trace[293646210] 'agreement among raft nodes before linearized reading' (duration: 301.109933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:21.883545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:21.582083Z","time spent":"301.453731ms","remote":"127.0.0.1:34458","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-04-08T00:31:21.883581Z","caller":"traceutil/trace.go:171","msg":"trace[484619442] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"332.216656ms","start":"2026-04-08T00:31:21.551348Z","end":"2026-04-08T00:31:21.883565Z","steps":["trace[484619442] 'process raft request' (duration: 331.500778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:21.883661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.165619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-08T00:31:21.883693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:21.551325Z","time spent":"332.295619ms","remote":"127.0.0.1:34768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:31:21.883715Z","caller":"traceutil/trace.go:171","msg":"trace[1828781402] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2092; }","duration":"279.266841ms","start":"2026-04-08T00:31:21.604432Z","end":"2026-04-08T00:31:21.883699Z","steps":["trace[1828781402] 'agreement among raft nodes before linearized reading' (duration: 279.083737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:22.402785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.861082ms","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-04-08T00:31:22.40287Z","caller":"traceutil/trace.go:171","msg":"trace[1855741799] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2092; }","duration":"286.995165ms","start":"2026-04-08T00:31:22.115857Z","end":"2026-04-08T00:31:22.402853Z","steps":["trace[1855741799] 'range keys from in-memory index tree' (duration: 286.677618ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:22.44324Z","caller":"traceutil/trace.go:171","msg":"trace[588768819] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"301.265175ms","start":"2026-04-08T00:31:22.14194Z","end":"2026-04-08T00:31:22.443206Z","steps":["trace[588768819] 'process raft request' (duration: 301.071959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:22.443455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:22.14192Z","time spent":"301.423399ms","remote":"127.0.0.1:34768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:31:22.443903Z","caller":"traceutil/trace.go:171","msg":"trace[2126068517] linearizableReadLoop","detail":"{readStateIndex:2173; appliedIndex:2173; }","duration":"288.584026ms","start":"2026-04-08T00:31:22.155302Z","end":"2026-04-08T00:31:22.443886Z","steps":["trace[2126068517] 'read index received' (duration: 288.577316ms)","trace[2126068517] 'applied index is now lower than readState.Index' (duration: 5.49µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:31:22.444109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.804711ms","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-04-08T00:31:22.444165Z","caller":"traceutil/trace.go:171","msg":"trace[1959973287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2093; }","duration":"288.899623ms","start":"2026-04-08T00:31:22.155252Z","end":"2026-04-08T00:31:22.444151Z","steps":["trace[1959973287] 'agreement among raft nodes before linearized reading' (duration: 288.726339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:22.465017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.046908ms","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-04-08T00:31:22.465361Z","caller":"traceutil/trace.go:171","msg":"trace[1627977224] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2094; }","duration":"293.432077ms","start":"2026-04-08T00:31:22.17191Z","end":"2026-04-08T00:31:22.465342Z","steps":["trace[1627977224] 'agreement among raft nodes before linearized reading' (duration: 292.953586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:22.465058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.557275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T00:31:22.465512Z","caller":"traceutil/trace.go:171","msg":"trace[1443112686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2094; }","duration":"293.047427ms","start":"2026-04-08T00:31:22.172445Z","end":"2026-04-08T00:31:22.465493Z","steps":["trace[1443112686] 'agreement among raft nodes before linearized reading' (duration: 292.455843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:23.049622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.329994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:31:23.049861Z","caller":"traceutil/trace.go:171","msg":"trace[1439271791] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"601.458822ms","start":"2026-04-08T00:31:22.448375Z","end":"2026-04-08T00:31:23.049834Z","steps":["trace[1439271791] 'process raft request' (duration: 455.808741ms)","trace[1439271791] 'compare' (duration: 144.879762ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:31:23.050635Z","caller":"traceutil/trace.go:171","msg":"trace[359332487] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"579.95366ms","start":"2026-04-08T00:31:22.470588Z","end":"2026-04-08T00:31:23.050541Z","steps":["trace[359332487] 'process raft request' (duration: 579.1658ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:23.05065Z","caller":"traceutil/trace.go:171","msg":"trace[807607351] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"580.032842ms","start":"2026-04-08T00:31:22.470588Z","end":"2026-04-08T00:31:23.05062Z","steps":["trace[807607351] 'process raft request' (duration: 579.257382ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:23.050713Z","caller":"traceutil/trace.go:171","msg":"trace[1316526509] linearizableReadLoop","detail":"{readStateIndex:2177; appliedIndex:2174; }","duration":"529.371795ms","start":"2026-04-08T00:31:22.521224Z","end":"2026-04-08T00:31:23.050595Z","steps":["trace[1316526509] 'read index received' (duration: 383.001356ms)","trace[1316526509] 'applied index is now lower than readState.Index' (duration: 146.366339ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:31:23.050742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:22.470562Z","time spent":"580.112534ms","remote":"127.0.0.1:34768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:31:23.050897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:22.470562Z","time spent":"580.148075ms","remote":"127.0.0.1:34768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:31:23.050954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.639377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-04-08T00:31:23.050947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.729774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-08T00:31:23.051001Z","caller":"traceutil/trace.go:171","msg":"trace[194966823] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2099; }","duration":"288.721458ms","start":"2026-04-08T00:31:22.762263Z","end":"2026-04-08T00:31:23.050984Z","steps":["trace[194966823] 'agreement among raft nodes before linearized reading' (duration: 288.565365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:23.051055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.998399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-04-08T00:31:23.051095Z","caller":"traceutil/trace.go:171","msg":"trace[1060518442] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2099; }","duration":"113.084941ms","start":"2026-04-08T00:31:22.938Z","end":"2026-04-08T00:31:23.051085Z","steps":["trace[1060518442] 'agreement among raft nodes before linearized reading' (duration: 112.966218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:23.051134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.454599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:31:23.051215Z","caller":"traceutil/trace.go:171","msg":"trace[690791152] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2099; }","duration":"132.591281ms","start":"2026-04-08T00:31:22.91861Z","end":"2026-04-08T00:31:23.051201Z","steps":["trace[690791152] 'agreement among raft nodes before linearized reading' (duration: 132.432608ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:23.051051Z","caller":"traceutil/trace.go:171","msg":"trace[1800358790] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2099; }","duration":"529.843937ms","start":"2026-04-08T00:31:22.521153Z","end":"2026-04-08T00:31:23.050997Z","steps":["trace[1800358790] 'agreement among raft nodes before linearized reading' (duration: 529.644912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:23.051278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:22.521129Z","time spent":"530.136044ms","remote":"127.0.0.1:34768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-08T00:31:23.051419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:22.448349Z","time spent":"602.080438ms","remote":"127.0.0.1:34768","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-04-08T00:31:23.053174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.823369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-08T00:31:23.053237Z","caller":"traceutil/trace.go:171","msg":"trace[189242462] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2099; }","duration":"161.87906ms","start":"2026-04-08T00:31:22.891334Z","end":"2026-04-08T00:31:23.053213Z","steps":["trace[189242462] 'agreement among raft nodes before linearized reading' (duration: 159.733586ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:54.982621Z","caller":"traceutil/trace.go:171","msg":"trace[353911789] transaction","detail":"{read_only:false; response_revision:2276; number_of_response:1; }","duration":"117.198786ms","start":"2026-04-08T00:31:54.865268Z","end":"2026-04-08T00:31:54.982467Z","steps":["trace[353911789] 'process raft request' (duration: 24.979555ms)","trace[353911789] 'compare' (duration: 92.028866ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:31:56.3824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.654199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:31:56.382563Z","caller":"traceutil/trace.go:171","msg":"trace[1032567148] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2286; }","duration":"173.903395ms","start":"2026-04-08T00:31:56.20864Z","end":"2026-04-08T00:31:56.382544Z","steps":["trace[1032567148] 'count revisions from in-memory index tree' (duration: 173.557027ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:57.362323Z","caller":"traceutil/trace.go:171","msg":"trace[433922378] linearizableReadLoop","detail":"{readStateIndex:2380; appliedIndex:2379; }","duration":"102.331412ms","start":"2026-04-08T00:31:57.259966Z","end":"2026-04-08T00:31:57.362297Z","steps":["trace[433922378] 'read index received' (duration: 19.096527ms)","trace[433922378] 'applied index is now lower than readState.Index' (duration: 83.233715ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:31:57.362526Z","caller":"traceutil/trace.go:171","msg":"trace[1169987844] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"124.288793ms","start":"2026-04-08T00:31:57.2382Z","end":"2026-04-08T00:31:57.362488Z","steps":["trace[1169987844] 'process raft request' (duration: 40.822042ms)","trace[1169987844] 'compare' (duration: 83.112482ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:31:57.362739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.753543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T00:31:57.362781Z","caller":"traceutil/trace.go:171","msg":"trace[1526564699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2292; }","duration":"102.836795ms","start":"2026-04-08T00:31:57.259932Z","end":"2026-04-08T00:31:57.362769Z","steps":["trace[1526564699] 'agreement among raft nodes before linearized reading' (duration: 102.573479ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:32:59.230786Z","caller":"traceutil/trace.go:171","msg":"trace[105958328] linearizableReadLoop","detail":"{readStateIndex:2825; appliedIndex:2824; }","duration":"129.324471ms","start":"2026-04-08T00:32:59.101434Z","end":"2026-04-08T00:32:59.230759Z","steps":["trace[105958328] 'read index received' (duration: 129.093975ms)","trace[105958328] 'applied index is now lower than readState.Index' (duration: 208.305µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:32:59.230909Z","caller":"traceutil/trace.go:171","msg":"trace[495529530] transaction","detail":"{read_only:false; response_revision:2722; number_of_response:1; }","duration":"153.82482ms","start":"2026-04-08T00:32:59.077073Z","end":"2026-04-08T00:32:59.230897Z","steps":["trace[495529530] 'process raft request' (duration: 153.525063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:32:59.231128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.69261ms","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-04-08T00:32:59.231172Z","caller":"traceutil/trace.go:171","msg":"trace[2068136966] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2722; }","duration":"129.783362ms","start":"2026-04-08T00:32:59.101377Z","end":"2026-04-08T00:32:59.231161Z","steps":["trace[2068136966] 'agreement among raft nodes before linearized reading' (duration: 129.68692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:32:59.700327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.703874ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:32:59.700426Z","caller":"traceutil/trace.go:171","msg":"trace[11986327] linearizableReadLoop","detail":"{readStateIndex:2827; appliedIndex:2826; }","duration":"208.734467ms","start":"2026-04-08T00:32:59.491677Z","end":"2026-04-08T00:32:59.700411Z","steps":["trace[11986327] 'read index received' (duration: 8.801338ms)","trace[11986327] 'applied index is now lower than readState.Index' (duration: 199.931979ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:32:59.700491Z","caller":"traceutil/trace.go:171","msg":"trace[909164068] transaction","detail":"{read_only:false; response_revision:2724; number_of_response:1; }","duration":"309.294742ms","start":"2026-04-08T00:32:59.391185Z","end":"2026-04-08T00:32:59.70048Z","steps":["trace[909164068] 'process raft request' (duration: 109.342792ms)","trace[909164068] 'compare' (duration: 199.393766ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:32:59.70055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:32:59.39116Z","time spent":"309.359023ms","remote":"127.0.0.1:34768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:32:59.700746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.074656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:32:59.700781Z","caller":"traceutil/trace.go:171","msg":"trace[1149816229] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2724; }","duration":"209.147058ms","start":"2026-04-08T00:32:59.491624Z","end":"2026-04-08T00:32:59.700771Z","steps":["trace[1149816229] 'agreement among raft nodes before linearized reading' (duration: 209.088616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:32:59.705008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.687714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:32:59.705087Z","caller":"traceutil/trace.go:171","msg":"trace[648702168] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2725; }","duration":"126.815628ms","start":"2026-04-08T00:32:59.578255Z","end":"2026-04-08T00:32:59.705071Z","steps":["trace[648702168] 'agreement among raft nodes before linearized reading' (duration: 126.656724ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:32:59.87726Z","caller":"traceutil/trace.go:171","msg":"trace[594656281] transaction","detail":"{read_only:false; response_revision:2726; number_of_response:1; }","duration":"170.25883ms","start":"2026-04-08T00:32:59.706974Z","end":"2026-04-08T00:32:59.877233Z","steps":["trace[594656281] 'process raft request' (duration: 159.454671ms)","trace[594656281] 'compare' (duration: 10.664835ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:33:31.979132Z","caller":"traceutil/trace.go:171","msg":"trace[1612767852] transaction","detail":"{read_only:false; response_revision:2896; number_of_response:1; }","duration":"151.095307ms","start":"2026-04-08T00:33:31.828005Z","end":"2026-04-08T00:33:31.9791Z","steps":["trace[1612767852] 'process raft request' (duration: 71.852447ms)","trace[1612767852] 'compare' (duration: 79.126267ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:33:32.279938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.739054ms","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-04-08T00:33:32.280057Z","caller":"traceutil/trace.go:171","msg":"trace[890222647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2896; }","duration":"114.921788ms","start":"2026-04-08T00:33:32.165109Z","end":"2026-04-08T00:33:32.280031Z","steps":["trace[890222647] 'range keys from in-memory index tree' (duration: 114.530068ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:46.610759Z","caller":"traceutil/trace.go:171","msg":"trace[765463279] linearizableReadLoop","detail":"{readStateIndex:3085; appliedIndex:3084; }","duration":"122.343831ms","start":"2026-04-08T00:33:46.48839Z","end":"2026-04-08T00:33:46.610734Z","steps":["trace[765463279] 'read index received' (duration: 122.168166ms)","trace[765463279] 'applied index is now lower than readState.Index' (duration: 174.825µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:33:46.610862Z","caller":"traceutil/trace.go:171","msg":"trace[109742153] transaction","detail":"{read_only:false; response_revision:2972; number_of_response:1; }","duration":"143.838205ms","start":"2026-04-08T00:33:46.466959Z","end":"2026-04-08T00:33:46.610798Z","steps":["trace[109742153] 'process raft request' (duration: 143.608849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:46.611071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.627258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-08T00:33:46.611165Z","caller":"traceutil/trace.go:171","msg":"trace[853017679] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2972; }","duration":"122.827983ms","start":"2026-04-08T00:33:46.488314Z","end":"2026-04-08T00:33:46.611142Z","steps":["trace[853017679] 'agreement among raft nodes before linearized reading' (duration: 122.518005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:46.611099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.69915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-08T00:33:46.611332Z","caller":"traceutil/trace.go:171","msg":"trace[264912264] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2972; }","duration":"123.002178ms","start":"2026-04-08T00:33:46.488314Z","end":"2026-04-08T00:33:46.611316Z","steps":["trace[264912264] 'agreement among raft nodes before linearized reading' (duration: 122.552146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:35:09.18241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.09208ms","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-04-08T00:35:09.182504Z","caller":"traceutil/trace.go:171","msg":"trace[902651663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3499; }","duration":"143.229552ms","start":"2026-04-08T00:35:09.039255Z","end":"2026-04-08T00:35:09.182484Z","steps":["trace[902651663] 'range keys from in-memory index tree' (duration: 142.918295ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:35.800387Z","caller":"traceutil/trace.go:171","msg":"trace[608479997] linearizableReadLoop","detail":"{readStateIndex:4620; appliedIndex:4619; }","duration":"219.389705ms","start":"2026-04-08T00:36:35.580975Z","end":"2026-04-08T00:36:35.800365Z","steps":["trace[608479997] 'read index received' (duration: 219.117109ms)","trace[608479997] 'applied index is now lower than readState.Index' (duration: 271.446µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:35.80047Z","caller":"traceutil/trace.go:171","msg":"trace[738639088] transaction","detail":"{read_only:false; response_revision:4450; number_of_response:1; }","duration":"284.605839ms","start":"2026-04-08T00:36:35.515816Z","end":"2026-04-08T00:36:35.800422Z","steps":["trace[738639088] 'process raft request' (duration: 284.358153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:35.80089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.332732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-04-08T00:36:35.800971Z","caller":"traceutil/trace.go:171","msg":"trace[893265660] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4450; }","duration":"216.445864ms","start":"2026-04-08T00:36:35.584509Z","end":"2026-04-08T00:36:35.800955Z","steps":["trace[893265660] 'agreement among raft nodes before linearized reading' (duration: 216.238209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:35.801367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.312679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-08T00:36:35.801437Z","caller":"traceutil/trace.go:171","msg":"trace[445523471] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4450; }","duration":"131.436602ms","start":"2026-04-08T00:36:35.669982Z","end":"2026-04-08T00:36:35.801418Z","steps":["trace[445523471] 'agreement among raft nodes before linearized reading' (duration: 131.249957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:35.801365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.369529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:35.802041Z","caller":"traceutil/trace.go:171","msg":"trace[143689840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4450; }","duration":"221.098507ms","start":"2026-04-08T00:36:35.58092Z","end":"2026-04-08T00:36:35.802019Z","steps":["trace[143689840] 'agreement among raft nodes before linearized reading' (duration: 219.637511ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:41.641442Z","caller":"traceutil/trace.go:171","msg":"trace[12995093] transaction","detail":"{read_only:false; response_revision:4599; number_of_response:1; }","duration":"218.625501ms","start":"2026-04-08T00:36:41.422788Z","end":"2026-04-08T00:36:41.641413Z","steps":["trace[12995093] 'process raft request' (duration: 218.429276ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:41.64146Z","caller":"traceutil/trace.go:171","msg":"trace[1037829870] linearizableReadLoop","detail":"{readStateIndex:4772; appliedIndex:4771; }","duration":"194.876347ms","start":"2026-04-08T00:36:41.446543Z","end":"2026-04-08T00:36:41.641419Z","steps":["trace[1037829870] 'read index received' (duration: 194.823246ms)","trace[1037829870] 'applied index is now lower than readState.Index' (duration: 49.611µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:36:41.641947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.342589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-08T00:36:41.641998Z","caller":"traceutil/trace.go:171","msg":"trace[184841132] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4599; }","duration":"195.513333ms","start":"2026-04-08T00:36:41.446468Z","end":"2026-04-08T00:36:41.641981Z","steps":["trace[184841132] 'agreement among raft nodes before linearized reading' (duration: 195.183885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:41.642288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.84468ms","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-04-08T00:36:41.642373Z","caller":"traceutil/trace.go:171","msg":"trace[1519339389] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:4600; }","duration":"149.983254ms","start":"2026-04-08T00:36:41.492357Z","end":"2026-04-08T00:36:41.642341Z","steps":["trace[1519339389] 'agreement among raft nodes before linearized reading' (duration: 149.82741ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:41.642648Z","caller":"traceutil/trace.go:171","msg":"trace[625823451] transaction","detail":"{read_only:false; response_revision:4600; number_of_response:1; }","duration":"119.866176ms","start":"2026-04-08T00:36:41.522768Z","end":"2026-04-08T00:36:41.642635Z","steps":["trace[625823451] 'process raft request' (duration: 119.268751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:41.642752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.470198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-bs88r\" ","response":"range_response_count:1 size:9716"} {"level":"info","ts":"2026-04-08T00:36:41.642883Z","caller":"traceutil/trace.go:171","msg":"trace[1930223005] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-bs88r; range_end:; response_count:1; response_revision:4600; }","duration":"122.668193ms","start":"2026-04-08T00:36:41.520185Z","end":"2026-04-08T00:36:41.642853Z","steps":["trace[1930223005] 'agreement among raft nodes before linearized reading' (duration: 122.336265ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:47.516775Z","caller":"traceutil/trace.go:171","msg":"trace[172014769] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"299.168604ms","start":"2026-04-08T00:36:47.217577Z","end":"2026-04-08T00:36:47.516746Z","steps":["trace[172014769] 'process raft request' (duration: 299.111982ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:47.516869Z","caller":"traceutil/trace.go:171","msg":"trace[1191649416] linearizableReadLoop","detail":"{readStateIndex:4858; appliedIndex:4857; }","duration":"464.717425ms","start":"2026-04-08T00:36:47.052065Z","end":"2026-04-08T00:36:47.516783Z","steps":["trace[1191649416] 'read index received' (duration: 383.492132ms)","trace[1191649416] 'applied index is now lower than readState.Index' (duration: 81.219963ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:47.517207Z","caller":"traceutil/trace.go:171","msg":"trace[1805961052] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"454.56343ms","start":"2026-04-08T00:36:47.062631Z","end":"2026-04-08T00:36:47.517194Z","steps":["trace[1805961052] 'process raft request' (duration: 453.993956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:47.517334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:47.062612Z","time spent":"454.636921ms","remote":"127.0.0.1:34768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:36:47.517524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.430924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:47.517626Z","caller":"traceutil/trace.go:171","msg":"trace[369169702] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:4686; }","duration":"465.593277ms","start":"2026-04-08T00:36:47.052008Z","end":"2026-04-08T00:36:47.517601Z","steps":["trace[369169702] 'agreement among raft nodes before linearized reading' (duration: 465.431633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:47.517659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.324356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"warn","ts":"2026-04-08T00:36:47.517678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:47.051982Z","time spent":"465.687409ms","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" "} {"level":"info","ts":"2026-04-08T00:36:47.517725Z","caller":"traceutil/trace.go:171","msg":"trace[866760677] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4686; }","duration":"464.442418ms","start":"2026-04-08T00:36:47.053262Z","end":"2026-04-08T00:36:47.517705Z","steps":["trace[866760677] 'agreement among raft nodes before linearized reading' (duration: 464.200473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:47.517759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.963083ms","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-04-08T00:36:47.517773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:47.053248Z","time spent":"464.515451ms","remote":"127.0.0.1:34566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":731,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" "} {"level":"info","ts":"2026-04-08T00:36:47.517819Z","caller":"traceutil/trace.go:171","msg":"trace[1762189806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4686; }","duration":"159.059256ms","start":"2026-04-08T00:36:47.358741Z","end":"2026-04-08T00:36:47.5178Z","steps":["trace[1762189806] 'agreement among raft nodes before linearized reading' (duration: 158.894662ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:47.517861Z","caller":"traceutil/trace.go:171","msg":"trace[1260642738] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"467.346668ms","start":"2026-04-08T00:36:47.050481Z","end":"2026-04-08T00:36:47.517828Z","steps":["trace[1260642738] 'process raft request' (duration: 385.172482ms)","trace[1260642738] 'compare' (duration: 80.784303ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:36:47.518084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:47.050459Z","time spent":"467.492432ms","remote":"127.0.0.1:49310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3208,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:36:47.518875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.556801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193165"} {"level":"info","ts":"2026-04-08T00:36:47.518917Z","caller":"traceutil/trace.go:171","msg":"trace[1876473203] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4686; }","duration":"320.635062ms","start":"2026-04-08T00:36:47.198271Z","end":"2026-04-08T00:36:47.518906Z","steps":["trace[1876473203] 'agreement among raft nodes before linearized reading' (duration: 319.734419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:47.518958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:47.198239Z","time spent":"320.710354ms","remote":"127.0.0.1:34572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2193188,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-04-08T00:36:48.025737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.644973ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:36:48.026226Z","caller":"traceutil/trace.go:171","msg":"trace[2106081374] transaction","detail":"{read_only:false; response_revision:4696; number_of_response:1; }","duration":"334.62112ms","start":"2026-04-08T00:36:47.691571Z","end":"2026-04-08T00:36:48.026192Z","steps":["trace[2106081374] 'process raft request' (duration: 140.433435ms)","trace[2106081374] 'compare' (duration: 193.427527ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:48.026536Z","caller":"traceutil/trace.go:171","msg":"trace[78741640] transaction","detail":"{read_only:false; response_revision:4697; number_of_response:1; }","duration":"334.404316ms","start":"2026-04-08T00:36:47.69211Z","end":"2026-04-08T00:36:48.026515Z","steps":["trace[78741640] 'process raft request' (duration: 333.726279ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:48.026567Z","caller":"traceutil/trace.go:171","msg":"trace[772966636] linearizableReadLoop","detail":"{readStateIndex:4872; appliedIndex:4870; }","duration":"328.571225ms","start":"2026-04-08T00:36:47.69797Z","end":"2026-04-08T00:36:48.026541Z","steps":["trace[772966636] 'read index received' (duration: 134.133133ms)","trace[772966636] 'applied index is now lower than readState.Index' (duration: 194.435922ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:36:48.026514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:47.691536Z","time spent":"334.813176ms","remote":"127.0.0.1:34546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":771,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T00:36:48.026871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:47.692099Z","time spent":"334.487087ms","remote":"127.0.0.1:34638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1436,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:36:48.026932Z","caller":"traceutil/trace.go:171","msg":"trace[1393024753] transaction","detail":"{read_only:false; response_revision:4698; number_of_response:1; }","duration":"316.749158ms","start":"2026-04-08T00:36:47.71016Z","end":"2026-04-08T00:36:48.026909Z","steps":["trace[1393024753] 'process raft request' (duration: 316.286957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:48.027071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:47.710088Z","time spent":"316.898981ms","remote":"127.0.0.1:34776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1853,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:36:48.027412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.611137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61355"} {"level":"info","ts":"2026-04-08T00:36:48.027496Z","caller":"traceutil/trace.go:171","msg":"trace[926998200] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4698; }","duration":"333.74698ms","start":"2026-04-08T00:36:47.69373Z","end":"2026-04-08T00:36:48.027477Z","steps":["trace[926998200] 'agreement among raft nodes before linearized reading' (duration: 333.304629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:48.027545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:47.693721Z","time spent":"333.811981ms","remote":"127.0.0.1:34670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":6,"response size":61378,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"warn","ts":"2026-04-08T00:36:48.02802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.987649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-08T00:36:48.03147Z","caller":"traceutil/trace.go:171","msg":"trace[1984841532] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4699; }","duration":"338.469884ms","start":"2026-04-08T00:36:47.692977Z","end":"2026-04-08T00:36:48.031447Z","steps":["trace[1984841532] 'agreement among raft nodes before linearized reading' (duration: 334.238632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:48.031612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:47.692964Z","time spent":"338.626987ms","remote":"127.0.0.1:34884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1147,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"warn","ts":"2026-04-08T00:36:48.028123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.126952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1162"} {"level":"info","ts":"2026-04-08T00:36:48.028157Z","caller":"traceutil/trace.go:171","msg":"trace[133318744] transaction","detail":"{read_only:false; response_revision:4699; number_of_response:1; }","duration":"316.611425ms","start":"2026-04-08T00:36:47.711532Z","end":"2026-04-08T00:36:48.028143Z","steps":["trace[133318744] 'process raft request' (duration: 315.249492ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:48.028239Z","caller":"traceutil/trace.go:171","msg":"trace[536431313] transaction","detail":"{read_only:false; response_revision:4700; number_of_response:1; }","duration":"316.643216ms","start":"2026-04-08T00:36:47.711582Z","end":"2026-04-08T00:36:48.028226Z","steps":["trace[536431313] 'process raft request' (duration: 316.157184ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:48.028374Z","caller":"traceutil/trace.go:171","msg":"trace[1897812002] transaction","detail":"{read_only:false; response_revision:4701; number_of_response:1; }","duration":"316.686657ms","start":"2026-04-08T00:36:47.711673Z","end":"2026-04-08T00:36:48.02836Z","steps":["trace[1897812002] 'process raft request' (duration: 316.500802ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:48.028511Z","caller":"traceutil/trace.go:171","msg":"trace[320282998] transaction","detail":"{read_only:false; response_revision:4702; number_of_response:1; }","duration":"310.299333ms","start":"2026-04-08T00:36:47.718199Z","end":"2026-04-08T00:36:48.028499Z","steps":["trace[320282998] 'process raft request' (duration: 310.103177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:48.029243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.439663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-665c7a70-c885-4097-bb73-07ca0253e81a\" ","response":"range_response_count:1 size:1450"} {"level":"warn","ts":"2026-04-08T00:36:48.029556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.585699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"warn","ts":"2026-04-08T00:36:48.029647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.781411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"warn","ts":"2026-04-08T00:36:48.029654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.821052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-08T00:36:48.032376Z","caller":"traceutil/trace.go:171","msg":"trace[1835771678] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4699; }","duration":"341.423635ms","start":"2026-04-08T00:36:47.690935Z","end":"2026-04-08T00:36:48.032359Z","steps":["trace[1835771678] 'agreement among raft nodes before linearized reading' (duration: 336.722142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:48.032943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:47.690917Z","time spent":"342.013849ms","remote":"127.0.0.1:34566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1185,"request content":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" "} {"level":"info","ts":"2026-04-08T00:36:48.032965Z","caller":"traceutil/trace.go:171","msg":"trace[1818543718] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4702; }","duration":"330.113872ms","start":"2026-04-08T00:36:47.702842Z","end":"2026-04-08T00:36:48.032956Z","steps":["trace[1818543718] 'agreement among raft nodes before linearized reading' (duration: 326.73686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:48.033307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:47.702828Z","time spent":"330.46789ms","remote":"127.0.0.1:34670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":12998,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-04-08T00:36:48.032512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:47.71157Z","time spent":"320.929169ms","remote":"127.0.0.1:34642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":850,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:36:48.032537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:47.711668Z","time spent":"320.857517ms","remote":"127.0.0.1:34642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1273,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:36:48.03256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:47.718186Z","time spent":"314.362691ms","remote":"127.0.0.1:34962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9558,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:36:48.032576Z","caller":"traceutil/trace.go:171","msg":"trace[248118479] range","detail":"{range_begin:/registry/persistentvolumes/pvc-665c7a70-c885-4097-bb73-07ca0253e81a; range_end:; response_count:1; response_revision:4702; }","duration":"329.795093ms","start":"2026-04-08T00:36:47.702776Z","end":"2026-04-08T00:36:48.032571Z","steps":["trace[248118479] 'agreement among raft nodes before linearized reading' (duration: 326.376962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:48.033737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:47.702769Z","time spent":"330.961021ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1473,"request content":"key:\"/registry/persistentvolumes/pvc-665c7a70-c885-4097-bb73-07ca0253e81a\" "} {"level":"info","ts":"2026-04-08T00:36:48.032654Z","caller":"traceutil/trace.go:171","msg":"trace[1179426807] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4702; }","duration":"327.699474ms","start":"2026-04-08T00:36:47.70495Z","end":"2026-04-08T00:36:48.03265Z","steps":["trace[1179426807] 'agreement among raft nodes before linearized reading' (duration: 324.542897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:48.033865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:47.704943Z","time spent":"328.916342ms","remote":"127.0.0.1:34566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3529,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-04-08T00:36:48.032991Z","caller":"traceutil/trace.go:171","msg":"trace[14391993] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4702; }","duration":"160.220024ms","start":"2026-04-08T00:36:47.872768Z","end":"2026-04-08T00:36:48.032988Z","steps":["trace[14391993] 'agreement among raft nodes before linearized reading' (duration: 156.676478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:48.032481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:47.711523Z","time spent":"320.894038ms","remote":"127.0.0.1:34776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:36:48.242376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.758444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-26beda2b-a0c6-4dac-8b62-1cd72b9aae25\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-08T00:36:48.242491Z","caller":"traceutil/trace.go:171","msg":"trace[1308325897] range","detail":"{range_begin:/registry/persistentvolumes/pvc-26beda2b-a0c6-4dac-8b62-1cd72b9aae25; range_end:; response_count:1; response_revision:4712; }","duration":"114.912459ms","start":"2026-04-08T00:36:48.127553Z","end":"2026-04-08T00:36:48.242465Z","steps":["trace[1308325897] 'range keys from in-memory index tree' (duration: 114.57383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:48.242922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.162245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-08T00:36:48.243005Z","caller":"traceutil/trace.go:171","msg":"trace[2029989290] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4712; }","duration":"115.268847ms","start":"2026-04-08T00:36:48.127714Z","end":"2026-04-08T00:36:48.242983Z","steps":["trace[2029989290] 'range keys from in-memory index tree' (duration: 115.003471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:48.24315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.840658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1453"} {"level":"info","ts":"2026-04-08T00:36:48.243166Z","caller":"traceutil/trace.go:171","msg":"trace[1231989450] transaction","detail":"{read_only:false; response_revision:4713; number_of_response:1; }","duration":"114.416046ms","start":"2026-04-08T00:36:48.128728Z","end":"2026-04-08T00:36:48.243144Z","steps":["trace[1231989450] 'process raft request' (duration: 99.382133ms)","trace[1231989450] 'compare' (duration: 13.881826ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:48.243214Z","caller":"traceutil/trace.go:171","msg":"trace[438906196] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4713; }","duration":"112.91418ms","start":"2026-04-08T00:36:48.130289Z","end":"2026-04-08T00:36:48.243203Z","steps":["trace[438906196] 'agreement among raft nodes before linearized reading' (duration: 112.679335ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:48.242929Z","caller":"traceutil/trace.go:171","msg":"trace[1343001592] linearizableReadLoop","detail":"{readStateIndex:4888; appliedIndex:4887; }","duration":"112.597913ms","start":"2026-04-08T00:36:48.130305Z","end":"2026-04-08T00:36:48.242903Z","steps":["trace[1343001592] 'read index received' (duration: 97.839886ms)","trace[1343001592] 'applied index is now lower than readState.Index' (duration: 14.756417ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:48.311661Z","caller":"traceutil/trace.go:171","msg":"trace[394653789] transaction","detail":"{read_only:false; response_revision:4714; number_of_response:1; }","duration":"180.178367ms","start":"2026-04-08T00:36:48.131467Z","end":"2026-04-08T00:36:48.311645Z","steps":["trace[394653789] 'process raft request' (duration: 179.989302ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:48.311997Z","caller":"traceutil/trace.go:171","msg":"trace[1538942955] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"180.420463ms","start":"2026-04-08T00:36:48.131566Z","end":"2026-04-08T00:36:48.311986Z","steps":["trace[1538942955] 'process raft request' (duration: 180.015742ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:48.312206Z","caller":"traceutil/trace.go:171","msg":"trace[1298849157] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"179.651955ms","start":"2026-04-08T00:36:48.132527Z","end":"2026-04-08T00:36:48.312179Z","steps":["trace[1298849157] 'process raft request' (duration: 179.331767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:48.312359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.204721ms","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:1342"} {"level":"info","ts":"2026-04-08T00:36:48.31241Z","caller":"traceutil/trace.go:171","msg":"trace[269311881] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4717; }","duration":"171.274062ms","start":"2026-04-08T00:36:48.141123Z","end":"2026-04-08T00:36:48.312397Z","steps":["trace[269311881] 'agreement among raft nodes before linearized reading' (duration: 171.118388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:48.312454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.695066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bf9cca6e-618f-461a-b1d2-7f1f4ef54e80\" ","response":"range_response_count:1 size:2580"} {"level":"warn","ts":"2026-04-08T00:36:48.31252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.880495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-08T00:36:48.312532Z","caller":"traceutil/trace.go:171","msg":"trace[2098588764] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bf9cca6e-618f-461a-b1d2-7f1f4ef54e80; range_end:; response_count:1; response_revision:4717; }","duration":"179.792708ms","start":"2026-04-08T00:36:48.13272Z","end":"2026-04-08T00:36:48.312512Z","steps":["trace[2098588764] 'agreement among raft nodes before linearized reading' (duration: 179.533732ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:48.312585Z","caller":"traceutil/trace.go:171","msg":"trace[1269292362] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4717; }","duration":"171.984778ms","start":"2026-04-08T00:36:48.140581Z","end":"2026-04-08T00:36:48.312566Z","steps":["trace[1269292362] 'agreement among raft nodes before linearized reading' (duration: 171.811214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:48.31261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.152039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:48.312655Z","caller":"traceutil/trace.go:171","msg":"trace[1191302937] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:4717; }","duration":"109.18621ms","start":"2026-04-08T00:36:48.203443Z","end":"2026-04-08T00:36:48.31263Z","steps":["trace[1191302937] 'agreement among raft nodes before linearized reading' (duration: 109.102768ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:48.312662Z","caller":"traceutil/trace.go:171","msg":"trace[1499259944] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"180.057754ms","start":"2026-04-08T00:36:48.132592Z","end":"2026-04-08T00:36:48.31265Z","steps":["trace[1499259944] 'process raft request' (duration: 179.371287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:48.312875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.211692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bf9cca6e-618f-461a-b1d2-7f1f4ef54e80\" ","response":"range_response_count:1 size:2580"} {"level":"info","ts":"2026-04-08T00:36:48.313959Z","caller":"traceutil/trace.go:171","msg":"trace[22276815] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bf9cca6e-618f-461a-b1d2-7f1f4ef54e80; range_end:; response_count:1; response_revision:4717; }","duration":"182.332449ms","start":"2026-04-08T00:36:48.131606Z","end":"2026-04-08T00:36:48.313939Z","steps":["trace[22276815] 'agreement among raft nodes before linearized reading' (duration: 181.058368ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:51.068181Z","caller":"traceutil/trace.go:171","msg":"trace[1709327593] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"263.304009ms","start":"2026-04-08T00:36:50.80486Z","end":"2026-04-08T00:36:51.068164Z","steps":["trace[1709327593] 'process raft request' (duration: 263.214307ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:56.306579Z","caller":"traceutil/trace.go:171","msg":"trace[257597116] transaction","detail":"{read_only:false; response_revision:4845; number_of_response:1; }","duration":"126.633085ms","start":"2026-04-08T00:36:56.179885Z","end":"2026-04-08T00:36:56.306518Z","steps":["trace[257597116] 'process raft request' (duration: 126.473822ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:52.233119Z","caller":"traceutil/trace.go:171","msg":"trace[1471645871] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"144.491697ms","start":"2026-04-08T00:37:52.0886Z","end":"2026-04-08T00:37:52.233092Z","steps":["trace[1471645871] 'process raft request' (duration: 52.871886ms)","trace[1471645871] 'compare' (duration: 91.478808ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:38:24.798311Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2861} {"level":"info","ts":"2026-04-08T00:38:24.875304Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2861,"took":"75.641605ms","hash":4162402299,"current-db-size-bytes":53264384,"current-db-size":"53 MB","current-db-size-in-use-bytes":29315072,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-08T00:38:24.875456Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4162402299,"revision":2861,"compact-revision":-1} {"level":"info","ts":"2026-04-08T00:41:00.088178Z","caller":"traceutil/trace.go:171","msg":"trace[1260910083] transaction","detail":"{read_only:false; response_revision:6643; number_of_response:1; }","duration":"156.754107ms","start":"2026-04-08T00:40:59.931399Z","end":"2026-04-08T00:41:00.088153Z","steps":["trace[1260910083] 'process raft request' (duration: 156.610234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:41:01.254891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.529701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:25 size:437670"} {"level":"info","ts":"2026-04-08T00:41:01.25497Z","caller":"traceutil/trace.go:171","msg":"trace[1665003143] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:25; response_revision:6644; }","duration":"125.623953ms","start":"2026-04-08T00:41:01.129329Z","end":"2026-04-08T00:41:01.254953Z","steps":["trace[1665003143] 'range keys from in-memory index tree' (duration: 125.09266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:41:12.113914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.888607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:41:12.114657Z","caller":"traceutil/trace.go:171","msg":"trace[158441604] transaction","detail":"{read_only:false; response_revision:6703; number_of_response:1; }","duration":"507.917908ms","start":"2026-04-08T00:41:11.606703Z","end":"2026-04-08T00:41:12.114621Z","steps":["trace[158441604] 'process raft request' (duration: 239.183601ms)","trace[158441604] 'compare' (duration: 267.475926ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:41:12.114916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:41:11.606677Z","time spent":"508.074882ms","remote":"127.0.0.1:34768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:41:12.180888Z","caller":"traceutil/trace.go:171","msg":"trace[591329432] linearizableReadLoop","detail":"{readStateIndex:6946; appliedIndex:6943; }","duration":"512.949703ms","start":"2026-04-08T00:41:11.66791Z","end":"2026-04-08T00:41:12.180859Z","steps":["trace[591329432] 'read index received' (duration: 177.970829ms)","trace[591329432] 'applied index is now lower than readState.Index' (duration: 334.977734ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:41:12.180921Z","caller":"traceutil/trace.go:171","msg":"trace[307070204] transaction","detail":"{read_only:false; response_revision:6704; number_of_response:1; }","duration":"574.059253ms","start":"2026-04-08T00:41:11.606828Z","end":"2026-04-08T00:41:12.180887Z","steps":["trace[307070204] 'process raft request' (duration: 573.822047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:41:12.181228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:41:11.606816Z","time spent":"574.204506ms","remote":"127.0.0.1:34768","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-04-08T00:41:12.181183Z","caller":"traceutil/trace.go:171","msg":"trace[651454742] transaction","detail":"{read_only:false; response_revision:6705; number_of_response:1; }","duration":"573.802316ms","start":"2026-04-08T00:41:11.607301Z","end":"2026-04-08T00:41:12.181104Z","steps":["trace[651454742] 'process raft request' (duration: 573.512189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:41:12.181281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.353692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-08T00:41:12.181427Z","caller":"traceutil/trace.go:171","msg":"trace[2385462] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6705; }","duration":"513.558388ms","start":"2026-04-08T00:41:11.667846Z","end":"2026-04-08T00:41:12.181405Z","steps":["trace[2385462] 'agreement among raft nodes before linearized reading' (duration: 513.165508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:41:12.181483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:41:11.667824Z","time spent":"513.645509ms","remote":"127.0.0.1:34768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-08T00:41:12.1815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:41:11.607295Z","time spent":"574.035882ms","remote":"127.0.0.1:34768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:41:12.640618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.022128ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:41:12.640745Z","caller":"traceutil/trace.go:171","msg":"trace[1878036841] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:6705; }","duration":"241.146841ms","start":"2026-04-08T00:41:12.399569Z","end":"2026-04-08T00:41:12.640716Z","steps":["trace[1878036841] 'range keys from in-memory index tree' (duration: 241.007547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:41:12.641046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.135955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:41:12.641201Z","caller":"traceutil/trace.go:171","msg":"trace[2067078096] transaction","detail":"{read_only:false; response_revision:6707; number_of_response:1; }","duration":"453.338299ms","start":"2026-04-08T00:41:12.187852Z","end":"2026-04-08T00:41:12.64119Z","steps":["trace[2067078096] 'process raft request' (duration: 453.259407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:41:12.641262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:41:12.187821Z","time spent":"453.411011ms","remote":"127.0.0.1:34768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:41:12.641285Z","caller":"traceutil/trace.go:171","msg":"trace[304462793] transaction","detail":"{read_only:false; response_revision:6706; number_of_response:1; }","duration":"516.806058ms","start":"2026-04-08T00:41:12.124415Z","end":"2026-04-08T00:41:12.641222Z","steps":["trace[304462793] 'process raft request' (duration: 120.442447ms)","trace[304462793] 'compare' (duration: 395.613552ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:41:12.641474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:41:12.124395Z","time spent":"517.006364ms","remote":"127.0.0.1:34768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:41:12.641485Z","caller":"traceutil/trace.go:171","msg":"trace[394615626] linearizableReadLoop","detail":"{readStateIndex:6947; appliedIndex:6946; }","duration":"460.451254ms","start":"2026-04-08T00:41:12.180972Z","end":"2026-04-08T00:41:12.641423Z","steps":["trace[394615626] 'read index received' (duration: 63.902909ms)","trace[394615626] 'applied index is now lower than readState.Index' (duration: 396.539415ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:41:12.641772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.530266ms","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-04-08T00:41:12.641885Z","caller":"traceutil/trace.go:171","msg":"trace[1739416455] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6707; }","duration":"339.649688ms","start":"2026-04-08T00:41:12.302196Z","end":"2026-04-08T00:41:12.641845Z","steps":["trace[1739416455] 'agreement among raft nodes before linearized reading' (duration: 339.535345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:41:12.64205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.616893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:25 size:437670"} {"level":"warn","ts":"2026-04-08T00:41:12.641776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.214958ms","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-04-08T00:41:12.64213Z","caller":"traceutil/trace.go:171","msg":"trace[1936831907] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:25; response_revision:6707; }","duration":"263.695465ms","start":"2026-04-08T00:41:12.37839Z","end":"2026-04-08T00:41:12.642085Z","steps":["trace[1936831907] 'agreement among raft nodes before linearized reading' (duration: 263.385146ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:41:12.642218Z","caller":"traceutil/trace.go:171","msg":"trace[194206034] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6707; }","duration":"880.68003ms","start":"2026-04-08T00:41:11.761477Z","end":"2026-04-08T00:41:12.642157Z","steps":["trace[194206034] 'agreement among raft nodes before linearized reading' (duration: 880.062665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:41:12.642305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:41:11.761455Z","time spent":"880.835484ms","remote":"127.0.0.1:34768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T00:41:12.642038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:41:12.302177Z","time spent":"339.846884ms","remote":"127.0.0.1:44084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-04-08T00:41:14.315974Z","caller":"traceutil/trace.go:171","msg":"trace[1553231419] transaction","detail":"{read_only:false; response_revision:6714; number_of_response:1; }","duration":"125.603574ms","start":"2026-04-08T00:41:14.190323Z","end":"2026-04-08T00:41:14.315926Z","steps":["trace[1553231419] 'process raft request' (duration: 91.183598ms)","trace[1553231419] 'compare' (duration: 34.223441ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:41:14.3467Z","caller":"traceutil/trace.go:171","msg":"trace[1162200862] transaction","detail":"{read_only:false; response_revision:6715; number_of_response:1; }","duration":"155.172559ms","start":"2026-04-08T00:41:14.191503Z","end":"2026-04-08T00:41:14.346676Z","steps":["trace[1162200862] 'process raft request' (duration: 155.050877ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:41:14.784821Z","caller":"traceutil/trace.go:171","msg":"trace[1272576998] transaction","detail":"{read_only:false; response_revision:6717; number_of_response:1; }","duration":"121.663987ms","start":"2026-04-08T00:41:14.663127Z","end":"2026-04-08T00:41:14.784791Z","steps":["trace[1272576998] 'process raft request' (duration: 76.52442ms)","trace[1272576998] 'compare' (duration: 44.953353ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:41:14.789712Z","caller":"traceutil/trace.go:171","msg":"trace[51029121] transaction","detail":"{read_only:false; response_revision:6718; number_of_response:1; }","duration":"126.526086ms","start":"2026-04-08T00:41:14.66315Z","end":"2026-04-08T00:41:14.789676Z","steps":["trace[51029121] 'process raft request' (duration: 126.320812ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:41:15.645803Z","caller":"traceutil/trace.go:171","msg":"trace[1576995799] linearizableReadLoop","detail":"{readStateIndex:6962; appliedIndex:6961; }","duration":"129.038728ms","start":"2026-04-08T00:41:15.516737Z","end":"2026-04-08T00:41:15.645776Z","steps":["trace[1576995799] 'read index received' (duration: 128.951696ms)","trace[1576995799] 'applied index is now lower than readState.Index' (duration: 85.922µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:41:15.645937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.177381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:41:15.645838Z","caller":"traceutil/trace.go:171","msg":"trace[1438779699] transaction","detail":"{read_only:false; response_revision:6720; number_of_response:1; }","duration":"208.091769ms","start":"2026-04-08T00:41:15.437724Z","end":"2026-04-08T00:41:15.645816Z","steps":["trace[1438779699] 'process raft request' (duration: 207.913195ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:41:15.645977Z","caller":"traceutil/trace.go:171","msg":"trace[1101574717] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:6720; }","duration":"129.272133ms","start":"2026-04-08T00:41:15.516693Z","end":"2026-04-08T00:41:15.645965Z","steps":["trace[1101574717] 'agreement among raft nodes before linearized reading' (duration: 129.192441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:41:15.648074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.987892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/percona-xtradb\" ","response":"range_response_count:1 size:724"} {"level":"info","ts":"2026-04-08T00:41:15.64812Z","caller":"traceutil/trace.go:171","msg":"trace[1845775997] range","detail":"{range_begin:/registry/secrets/openstack/percona-xtradb; range_end:; response_count:1; response_revision:6721; }","duration":"119.051973ms","start":"2026-04-08T00:41:15.529057Z","end":"2026-04-08T00:41:15.648109Z","steps":["trace[1845775997] 'agreement among raft nodes before linearized reading' (duration: 118.870929ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:41:16.303123Z","caller":"traceutil/trace.go:171","msg":"trace[1236492415] transaction","detail":"{read_only:false; response_revision:6722; number_of_response:1; }","duration":"108.247308ms","start":"2026-04-08T00:41:16.19486Z","end":"2026-04-08T00:41:16.303108Z","steps":["trace[1236492415] 'process raft request' (duration: 108.141975ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:41:16.386088Z","caller":"traceutil/trace.go:171","msg":"trace[1431743703] transaction","detail":"{read_only:false; response_revision:6723; number_of_response:1; }","duration":"156.648085ms","start":"2026-04-08T00:41:16.229409Z","end":"2026-04-08T00:41:16.386058Z","steps":["trace[1431743703] 'process raft request' (duration: 142.766635ms)","trace[1431743703] 'compare' (duration: 13.352157ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:41:17.76762Z","caller":"traceutil/trace.go:171","msg":"trace[2041548600] transaction","detail":"{read_only:false; response_revision:6734; number_of_response:1; }","duration":"116.144381ms","start":"2026-04-08T00:41:17.651446Z","end":"2026-04-08T00:41:17.76759Z","steps":["trace[2041548600] 'process raft request' (duration: 70.504031ms)","trace[2041548600] 'compare' (duration: 45.466246ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:41:17.769755Z","caller":"traceutil/trace.go:171","msg":"trace[1556043432] transaction","detail":"{read_only:false; response_revision:6735; number_of_response:1; }","duration":"116.590243ms","start":"2026-04-08T00:41:17.653144Z","end":"2026-04-08T00:41:17.769734Z","steps":["trace[1556043432] 'process raft request' (duration: 116.496481ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:24.807411Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5600} {"level":"info","ts":"2026-04-08T00:43:24.881024Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5600,"took":"72.022518ms","hash":1659254881,"current-db-size-bytes":53264384,"current-db-size":"53 MB","current-db-size-in-use-bytes":18128896,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-08T00:43:24.881097Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1659254881,"revision":5600,"compact-revision":2861} {"level":"warn","ts":"2026-04-08T00:43:49.381945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.86848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:43:49.382179Z","caller":"traceutil/trace.go:171","msg":"trace[1916921652] transaction","detail":"{read_only:false; response_revision:7523; number_of_response:1; }","duration":"122.275432ms","start":"2026-04-08T00:43:49.259889Z","end":"2026-04-08T00:43:49.382165Z","steps":["trace[1916921652] 'process raft request' (duration: 122.18984ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:49.382332Z","caller":"traceutil/trace.go:171","msg":"trace[1283770621] linearizableReadLoop","detail":"{readStateIndex:7796; appliedIndex:7795; }","duration":"164.132085ms","start":"2026-04-08T00:43:49.218163Z","end":"2026-04-08T00:43:49.382295Z","steps":["trace[1283770621] 'read index received' (duration: 12.385002ms)","trace[1283770621] 'applied index is now lower than readState.Index' (duration: 151.744673ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:43:49.38239Z","caller":"traceutil/trace.go:171","msg":"trace[1322619350] transaction","detail":"{read_only:false; response_revision:7522; number_of_response:1; }","duration":"184.809711ms","start":"2026-04-08T00:43:49.197555Z","end":"2026-04-08T00:43:49.382365Z","steps":["trace[1322619350] 'process raft request' (duration: 33.040207ms)","trace[1322619350] 'compare' (duration: 150.587712ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:43:49.382554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.179936ms","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-04-08T00:43:49.382645Z","caller":"traceutil/trace.go:171","msg":"trace[334812516] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:7523; }","duration":"164.28262ms","start":"2026-04-08T00:43:49.218341Z","end":"2026-04-08T00:43:49.382624Z","steps":["trace[334812516] 'agreement among raft nodes before linearized reading' (duration: 164.144145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:49.382845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.854052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:service-account-controller\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-04-08T00:43:49.382987Z","caller":"traceutil/trace.go:171","msg":"trace[1892800022] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:service-account-controller; range_end:; response_count:1; response_revision:7523; }","duration":"163.034266ms","start":"2026-04-08T00:43:49.219916Z","end":"2026-04-08T00:43:49.382951Z","steps":["trace[1892800022] 'agreement among raft nodes before linearized reading' (duration: 162.726608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:49.382518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.34039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:43:49.383116Z","caller":"traceutil/trace.go:171","msg":"trace[1637200858] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:7523; }","duration":"165.006009ms","start":"2026-04-08T00:43:49.218092Z","end":"2026-04-08T00:43:49.383099Z","steps":["trace[1637200858] 'agreement among raft nodes before linearized reading' (duration: 164.342841ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:50.126513Z","caller":"traceutil/trace.go:171","msg":"trace[1928258923] linearizableReadLoop","detail":"{readStateIndex:7800; appliedIndex:7799; }","duration":"158.104585ms","start":"2026-04-08T00:43:49.968381Z","end":"2026-04-08T00:43:50.126486Z","steps":["trace[1928258923] 'read index received' (duration: 157.894779ms)","trace[1928258923] 'applied index is now lower than readState.Index' (duration: 208.916µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:43:50.126658Z","caller":"traceutil/trace.go:171","msg":"trace[1302097373] transaction","detail":"{read_only:false; response_revision:7526; number_of_response:1; }","duration":"167.924959ms","start":"2026-04-08T00:43:49.958723Z","end":"2026-04-08T00:43:50.126648Z","steps":["trace[1302097373] 'process raft request' (duration: 167.626931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:50.126824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.429873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-dns\" ","response":"range_response_count:1 size:576"} {"level":"info","ts":"2026-04-08T00:43:50.12686Z","caller":"traceutil/trace.go:171","msg":"trace[747159753] range","detail":"{range_begin:/registry/clusterroles/system:kube-dns; range_end:; response_count:1; response_revision:7526; }","duration":"158.503536ms","start":"2026-04-08T00:43:49.968348Z","end":"2026-04-08T00:43:50.126852Z","steps":["trace[747159753] 'agreement among raft nodes before linearized reading' (duration: 158.419333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:50.126904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.546146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ttl-after-finished-controller\" ","response":"range_response_count:1 size:791"} {"level":"info","ts":"2026-04-08T00:43:50.126942Z","caller":"traceutil/trace.go:171","msg":"trace[812925270] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ttl-after-finished-controller; range_end:; response_count:1; response_revision:7526; }","duration":"136.615077ms","start":"2026-04-08T00:43:49.990319Z","end":"2026-04-08T00:43:50.126934Z","steps":["trace[812925270] 'agreement among raft nodes before linearized reading' (duration: 136.550625ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:51.490938Z","caller":"traceutil/trace.go:171","msg":"trace[644966081] transaction","detail":"{read_only:false; response_revision:7531; number_of_response:1; }","duration":"103.97616ms","start":"2026-04-08T00:43:51.386934Z","end":"2026-04-08T00:43:51.49091Z","steps":["trace[644966081] 'process raft request' (duration: 103.804886ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:51.516424Z","caller":"traceutil/trace.go:171","msg":"trace[1185568413] linearizableReadLoop","detail":"{readStateIndex:7806; appliedIndex:7804; }","duration":"115.9304ms","start":"2026-04-08T00:43:51.400469Z","end":"2026-04-08T00:43:51.5164Z","steps":["trace[1185568413] 'read index received' (duration: 90.294642ms)","trace[1185568413] 'applied index is now lower than readState.Index' (duration: 25.634788ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:43:51.516595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.102245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:persistent-volume-provisioner\" ","response":"range_response_count:1 size:812"} {"level":"warn","ts":"2026-04-08T00:43:51.516697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.745639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:monitoring\" ","response":"range_response_count:1 size:694"} {"level":"info","ts":"2026-04-08T00:43:51.516904Z","caller":"traceutil/trace.go:171","msg":"trace[586011286] transaction","detail":"{read_only:false; response_revision:7532; number_of_response:1; }","duration":"128.351123ms","start":"2026-04-08T00:43:51.38813Z","end":"2026-04-08T00:43:51.516482Z","steps":["trace[586011286] 'process raft request' (duration: 128.138467ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:51.517005Z","caller":"traceutil/trace.go:171","msg":"trace[1562164663] range","detail":"{range_begin:/registry/clusterroles/system:persistent-volume-provisioner; range_end:; response_count:1; response_revision:7532; }","duration":"116.184427ms","start":"2026-04-08T00:43:51.400439Z","end":"2026-04-08T00:43:51.516624Z","steps":["trace[1562164663] 'agreement among raft nodes before linearized reading' (duration: 116.081965ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:51.517053Z","caller":"traceutil/trace.go:171","msg":"trace[1567500520] range","detail":"{range_begin:/registry/clusterrolebindings/system:monitoring; range_end:; response_count:1; response_revision:7532; }","duration":"114.835281ms","start":"2026-04-08T00:43:51.401922Z","end":"2026-04-08T00:43:51.516757Z","steps":["trace[1567500520] 'agreement among raft nodes before linearized reading' (duration: 114.687737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:52.042521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.470275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:43:52.042606Z","caller":"traceutil/trace.go:171","msg":"trace[701600614] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:7533; }","duration":"283.589427ms","start":"2026-04-08T00:43:51.758993Z","end":"2026-04-08T00:43:52.042583Z","steps":["trace[701600614] 'count revisions from in-memory index tree' (duration: 283.400083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:52.042823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.515163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:public-info-viewer\" ","response":"range_response_count:1 size:613"} {"level":"info","ts":"2026-04-08T00:43:52.042923Z","caller":"traceutil/trace.go:171","msg":"trace[320761232] range","detail":"{range_begin:/registry/clusterroles/system:public-info-viewer; range_end:; response_count:1; response_revision:7533; }","duration":"287.653895ms","start":"2026-04-08T00:43:51.755244Z","end":"2026-04-08T00:43:52.042898Z","steps":["trace[320761232] 'range keys from in-memory index tree' (duration: 287.326417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:52.042876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.701632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-08T00:43:52.043006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.454109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node\" ","response":"range_response_count:1 size:603"} {"level":"info","ts":"2026-04-08T00:43:52.043065Z","caller":"traceutil/trace.go:171","msg":"trace[336424860] range","detail":"{range_begin:/registry/clusterrolebindings/system:node; range_end:; response_count:1; response_revision:7533; }","duration":"332.532921ms","start":"2026-04-08T00:43:51.710517Z","end":"2026-04-08T00:43:52.04305Z","steps":["trace[336424860] 'range keys from in-memory index tree' (duration: 332.388326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:52.043064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.038067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-08T00:43:52.043102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:43:51.710504Z","time spent":"332.588982ms","remote":"127.0.0.1:34862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":626,"request content":"key:\"/registry/clusterrolebindings/system:node\" "} {"level":"info","ts":"2026-04-08T00:43:52.043127Z","caller":"traceutil/trace.go:171","msg":"trace[213544254] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:7533; }","duration":"241.14071ms","start":"2026-04-08T00:43:51.801969Z","end":"2026-04-08T00:43:52.04311Z","steps":["trace[213544254] 'count revisions from in-memory index tree' (duration: 240.967725ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:52.043081Z","caller":"traceutil/trace.go:171","msg":"trace[383833823] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7533; }","duration":"273.887409ms","start":"2026-04-08T00:43:51.769136Z","end":"2026-04-08T00:43:52.043024Z","steps":["trace[383833823] 'range keys from in-memory index tree' (duration: 273.570149ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:55.621168Z","caller":"traceutil/trace.go:171","msg":"trace[347112407] linearizableReadLoop","detail":"{readStateIndex:7827; appliedIndex:7826; }","duration":"106.775721ms","start":"2026-04-08T00:43:55.514376Z","end":"2026-04-08T00:43:55.621152Z","steps":["trace[347112407] 'read index received' (duration: 106.72687ms)","trace[347112407] 'applied index is now lower than readState.Index' (duration: 48.121µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:43:55.621347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.083486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/\" range_end:\"/registry/pods/cert-manager0\" limit:500 ","response":"range_response_count:3 size:13280"} {"level":"info","ts":"2026-04-08T00:43:55.62137Z","caller":"traceutil/trace.go:171","msg":"trace[753920149] range","detail":"{range_begin:/registry/pods/cert-manager/; range_end:/registry/pods/cert-manager0; response_count:3; response_revision:7552; }","duration":"105.136027ms","start":"2026-04-08T00:43:55.516227Z","end":"2026-04-08T00:43:55.621363Z","steps":["trace[753920149] 'agreement among raft nodes before linearized reading' (duration: 105.024255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:55.621355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.962227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/cert-manager/cert-manager\" ","response":"range_response_count:1 size:1096"} {"level":"info","ts":"2026-04-08T00:43:55.621418Z","caller":"traceutil/trace.go:171","msg":"trace[459551139] range","detail":"{range_begin:/registry/services/endpoints/cert-manager/cert-manager; range_end:; response_count:1; response_revision:7552; }","duration":"107.058479ms","start":"2026-04-08T00:43:55.514344Z","end":"2026-04-08T00:43:55.621403Z","steps":["trace[459551139] 'agreement among raft nodes before linearized reading' (duration: 106.897885ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:55.621715Z","caller":"traceutil/trace.go:171","msg":"trace[491713420] transaction","detail":"{read_only:false; response_revision:7552; number_of_response:1; }","duration":"121.870566ms","start":"2026-04-08T00:43:55.499816Z","end":"2026-04-08T00:43:55.621686Z","steps":["trace[491713420] 'process raft request' (duration: 121.220078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:56.195705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.251962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:1084"} {"level":"info","ts":"2026-04-08T00:43:56.195775Z","caller":"traceutil/trace.go:171","msg":"trace[695775306] range","detail":"{range_begin:/registry/services/endpoints/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:7554; }","duration":"351.363805ms","start":"2026-04-08T00:43:55.844394Z","end":"2026-04-08T00:43:56.195758Z","steps":["trace[695775306] 'range keys from in-memory index tree' (duration: 351.111378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:56.195811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:43:55.844374Z","time spent":"351.427766ms","remote":"127.0.0.1:34642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":1107,"request content":"key:\"/registry/services/endpoints/cert-manager/cert-manager-webhook\" "} {"level":"warn","ts":"2026-04-08T00:43:56.195979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.846072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:43:56.196001Z","caller":"traceutil/trace.go:171","msg":"trace[28554631] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:7554; }","duration":"337.868353ms","start":"2026-04-08T00:43:55.858126Z","end":"2026-04-08T00:43:56.195994Z","steps":["trace[28554631] 'range keys from in-memory index tree' (duration: 337.802071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:56.196015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:43:55.858076Z","time spent":"337.936234ms","remote":"127.0.0.1:34952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-04-08T00:43:56.196105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.901343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/auth-system/keycloak\" ","response":"range_response_count:1 size:992"} {"level":"info","ts":"2026-04-08T00:43:56.196119Z","caller":"traceutil/trace.go:171","msg":"trace[1736904251] range","detail":"{range_begin:/registry/services/endpoints/auth-system/keycloak; range_end:; response_count:1; response_revision:7554; }","duration":"312.941135ms","start":"2026-04-08T00:43:55.883174Z","end":"2026-04-08T00:43:56.196115Z","steps":["trace[1736904251] 'range keys from in-memory index tree' (duration: 312.807871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:56.196131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:43:55.88316Z","time spent":"312.968755ms","remote":"127.0.0.1:34642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":1015,"request content":"key:\"/registry/services/endpoints/auth-system/keycloak\" "} {"level":"warn","ts":"2026-04-08T00:43:56.196214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.757685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/auth-system/\" range_end:\"/registry/persistentvolumeclaims/auth-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:43:56.196228Z","caller":"traceutil/trace.go:171","msg":"trace[1852001452] range","detail":"{range_begin:/registry/persistentvolumeclaims/auth-system/; range_end:/registry/persistentvolumeclaims/auth-system0; response_count:0; response_revision:7554; }","duration":"301.804247ms","start":"2026-04-08T00:43:55.894419Z","end":"2026-04-08T00:43:56.196223Z","steps":["trace[1852001452] 'range keys from in-memory index tree' (duration: 301.655962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:56.196242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:43:55.894403Z","time spent":"301.834867ms","remote":"127.0.0.1:34638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/auth-system/\" range_end:\"/registry/persistentvolumeclaims/auth-system0\" limit:500 "} {"level":"info","ts":"2026-04-08T00:43:56.196402Z","caller":"traceutil/trace.go:171","msg":"trace[256587852] linearizableReadLoop","detail":"{readStateIndex:7830; appliedIndex:7829; }","duration":"219.808019ms","start":"2026-04-08T00:43:55.976582Z","end":"2026-04-08T00:43:56.19639Z","steps":["trace[256587852] 'read index received' (duration: 139.322312ms)","trace[256587852] 'applied index is now lower than readState.Index' (duration: 80.484377ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:43:56.196434Z","caller":"traceutil/trace.go:171","msg":"trace[714368993] transaction","detail":"{read_only:false; response_revision:7555; number_of_response:1; }","duration":"292.262079ms","start":"2026-04-08T00:43:55.904154Z","end":"2026-04-08T00:43:56.196416Z","steps":["trace[714368993] 'process raft request' (duration: 211.807624ms)","trace[714368993] 'compare' (duration: 79.788548ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:43:56.196543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.835871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-08T00:43:56.196552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.957743ms","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-04-08T00:43:56.196579Z","caller":"traceutil/trace.go:171","msg":"trace[1457554844] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7555; }","duration":"106.906283ms","start":"2026-04-08T00:43:56.089663Z","end":"2026-04-08T00:43:56.196569Z","steps":["trace[1457554844] 'agreement among raft nodes before linearized reading' (duration: 106.808291ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:56.196588Z","caller":"traceutil/trace.go:171","msg":"trace[1211631759] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:7555; }","duration":"220.016925ms","start":"2026-04-08T00:43:55.97656Z","end":"2026-04-08T00:43:56.196577Z","steps":["trace[1211631759] 'agreement among raft nodes before linearized reading' (duration: 219.923802ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:56.353633Z","caller":"traceutil/trace.go:171","msg":"trace[348822709] transaction","detail":"{read_only:false; response_revision:7557; number_of_response:1; }","duration":"152.244209ms","start":"2026-04-08T00:43:56.201359Z","end":"2026-04-08T00:43:56.353603Z","steps":["trace[348822709] 'process raft request' (duration: 137.167905ms)","trace[348822709] 'compare' (duration: 14.888278ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:43:56.353649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.584247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/cert-manager/\" range_end:\"/registry/persistentvolumeclaims/cert-manager0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:43:56.353724Z","caller":"traceutil/trace.go:171","msg":"trace[175982419] range","detail":"{range_begin:/registry/persistentvolumeclaims/cert-manager/; range_end:/registry/persistentvolumeclaims/cert-manager0; response_count:0; response_revision:7557; }","duration":"146.67442ms","start":"2026-04-08T00:43:56.207031Z","end":"2026-04-08T00:43:56.353705Z","steps":["trace[175982419] 'agreement among raft nodes before linearized reading' (duration: 146.556217ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:56.517968Z","caller":"traceutil/trace.go:171","msg":"trace[1560453552] transaction","detail":"{read_only:false; response_revision:7558; number_of_response:1; }","duration":"197.675266ms","start":"2026-04-08T00:43:56.320262Z","end":"2026-04-08T00:43:56.517937Z","steps":["trace[1560453552] 'process raft request' (duration: 170.584801ms)","trace[1560453552] 'compare' (duration: 26.870109ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:43:56.51817Z","caller":"traceutil/trace.go:171","msg":"trace[1585900685] linearizableReadLoop","detail":"{readStateIndex:7834; appliedIndex:7832; }","duration":"164.58437ms","start":"2026-04-08T00:43:56.353562Z","end":"2026-04-08T00:43:56.518146Z","steps":["trace[1585900685] 'read index received' (duration: 137.293719ms)","trace[1585900685] 'applied index is now lower than readState.Index' (duration: 27.289551ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:43:56.518315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.546912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/auth-system/\" range_end:\"/registry/rolebindings/auth-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:43:56.518349Z","caller":"traceutil/trace.go:171","msg":"trace[1126677307] range","detail":"{range_begin:/registry/rolebindings/auth-system/; range_end:/registry/rolebindings/auth-system0; response_count:0; response_revision:7559; }","duration":"238.606874ms","start":"2026-04-08T00:43:56.27973Z","end":"2026-04-08T00:43:56.518336Z","steps":["trace[1126677307] 'agreement among raft nodes before linearized reading' (duration: 238.533432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:56.518363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.693424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/auth-system/keycloak\" ","response":"range_response_count:1 size:992"} {"level":"warn","ts":"2026-04-08T00:43:56.518432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.078793ms","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-04-08T00:43:56.518429Z","caller":"traceutil/trace.go:171","msg":"trace[163501872] range","detail":"{range_begin:/registry/services/endpoints/auth-system/keycloak; range_end:; response_count:1; response_revision:7559; }","duration":"232.788247ms","start":"2026-04-08T00:43:56.285624Z","end":"2026-04-08T00:43:56.518412Z","steps":["trace[163501872] 'agreement among raft nodes before linearized reading' (duration: 232.543171ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:56.518451Z","caller":"traceutil/trace.go:171","msg":"trace[1416199258] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:7559; }","duration":"240.101524ms","start":"2026-04-08T00:43:56.278344Z","end":"2026-04-08T00:43:56.518446Z","steps":["trace[1416199258] 'agreement among raft nodes before linearized reading' (duration: 240.064113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:56.518486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.505479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:12296"} {"level":"info","ts":"2026-04-08T00:43:56.518387Z","caller":"traceutil/trace.go:171","msg":"trace[670941776] transaction","detail":"{read_only:false; response_revision:7559; number_of_response:1; }","duration":"181.965066ms","start":"2026-04-08T00:43:56.336414Z","end":"2026-04-08T00:43:56.518379Z","steps":["trace[670941776] 'process raft request' (duration: 181.469553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:56.518557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.747431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:1084"} {"level":"info","ts":"2026-04-08T00:43:56.518564Z","caller":"traceutil/trace.go:171","msg":"trace[1886056432] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:7559; }","duration":"254.614571ms","start":"2026-04-08T00:43:56.263929Z","end":"2026-04-08T00:43:56.518544Z","steps":["trace[1886056432] 'agreement among raft nodes before linearized reading' (duration: 254.377575ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:56.518614Z","caller":"traceutil/trace.go:171","msg":"trace[756679847] range","detail":"{range_begin:/registry/services/endpoints/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:7559; }","duration":"240.836484ms","start":"2026-04-08T00:43:56.27776Z","end":"2026-04-08T00:43:56.518596Z","steps":["trace[756679847] 'agreement among raft nodes before linearized reading' (duration: 240.6939ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:57.808246Z","caller":"traceutil/trace.go:171","msg":"trace[555381634] transaction","detail":"{read_only:false; response_revision:7563; number_of_response:1; }","duration":"107.88055ms","start":"2026-04-08T00:43:57.700339Z","end":"2026-04-08T00:43:57.80822Z","steps":["trace[555381634] 'process raft request' (duration: 68.551856ms)","trace[555381634] 'compare' (duration: 39.097038ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:43:57.9502Z","caller":"traceutil/trace.go:171","msg":"trace[1682928070] transaction","detail":"{read_only:false; response_revision:7564; number_of_response:1; }","duration":"137.151684ms","start":"2026-04-08T00:43:57.813022Z","end":"2026-04-08T00:43:57.950173Z","steps":["trace[1682928070] 'process raft request' (duration: 113.144261ms)","trace[1682928070] 'compare' (duration: 23.851969ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:43:58.383561Z","caller":"traceutil/trace.go:171","msg":"trace[1930258657] linearizableReadLoop","detail":"{readStateIndex:7840; appliedIndex:7839; }","duration":"105.808403ms","start":"2026-04-08T00:43:58.277735Z","end":"2026-04-08T00:43:58.383543Z","steps":["trace[1930258657] 'read index received' (duration: 105.66611ms)","trace[1930258657] 'applied index is now lower than readState.Index' (duration: 141.353µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:43:58.383619Z","caller":"traceutil/trace.go:171","msg":"trace[1697805246] transaction","detail":"{read_only:false; response_revision:7565; number_of_response:1; }","duration":"115.137973ms","start":"2026-04-08T00:43:58.268451Z","end":"2026-04-08T00:43:58.383589Z","steps":["trace[1697805246] 'process raft request' (duration: 114.958388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:58.383911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.157663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/cert-manager/cert-manager-cainjector:leaderelection\" ","response":"range_response_count:1 size:1201"} {"level":"info","ts":"2026-04-08T00:43:58.383958Z","caller":"traceutil/trace.go:171","msg":"trace[1653315912] range","detail":"{range_begin:/registry/roles/cert-manager/cert-manager-cainjector:leaderelection; range_end:; response_count:1; response_revision:7565; }","duration":"106.228305ms","start":"2026-04-08T00:43:58.277716Z","end":"2026-04-08T00:43:58.383944Z","steps":["trace[1653315912] 'agreement among raft nodes before linearized reading' (duration: 105.939456ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:58.688178Z","caller":"traceutil/trace.go:171","msg":"trace[1232595836] transaction","detail":"{read_only:false; response_revision:7566; number_of_response:1; }","duration":"301.579495ms","start":"2026-04-08T00:43:58.386574Z","end":"2026-04-08T00:43:58.688153Z","steps":["trace[1232595836] 'process raft request' (duration: 211.932375ms)","trace[1232595836] 'compare' (duration: 89.496426ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:43:58.688211Z","caller":"traceutil/trace.go:171","msg":"trace[719643759] transaction","detail":"{read_only:false; response_revision:7567; number_of_response:1; }","duration":"163.899519ms","start":"2026-04-08T00:43:58.524289Z","end":"2026-04-08T00:43:58.688189Z","steps":["trace[719643759] 'process raft request' (duration: 163.833197ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:58.688201Z","caller":"traceutil/trace.go:171","msg":"trace[1933833423] linearizableReadLoop","detail":"{readStateIndex:7841; appliedIndex:7840; }","duration":"295.902423ms","start":"2026-04-08T00:43:58.392277Z","end":"2026-04-08T00:43:58.68818Z","steps":["trace[1933833423] 'read index received' (duration: 206.267123ms)","trace[1933833423] 'applied index is now lower than readState.Index' (duration: 89.63357ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:43:58.688242Z","caller":"traceutil/trace.go:171","msg":"trace[1140477863] transaction","detail":"{read_only:false; response_revision:7568; number_of_response:1; }","duration":"163.415245ms","start":"2026-04-08T00:43:58.524817Z","end":"2026-04-08T00:43:58.688232Z","steps":["trace[1140477863] 'process raft request' (duration: 163.342223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:58.688422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.127139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/auth-system/sh.helm.release.v1.keycloak.v1\" ","response":"range_response_count:1 size:74836"} {"level":"info","ts":"2026-04-08T00:43:58.68847Z","caller":"traceutil/trace.go:171","msg":"trace[494666669] range","detail":"{range_begin:/registry/secrets/auth-system/sh.helm.release.v1.keycloak.v1; range_end:; response_count:1; response_revision:7568; }","duration":"296.197922ms","start":"2026-04-08T00:43:58.392261Z","end":"2026-04-08T00:43:58.688459Z","steps":["trace[494666669] 'agreement among raft nodes before linearized reading' (duration: 295.973365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:58.688487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.398007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/cert-manager/cert-manager-cainjector:leaderelection\" ","response":"range_response_count:1 size:1201"} {"level":"info","ts":"2026-04-08T00:43:58.68851Z","caller":"traceutil/trace.go:171","msg":"trace[1094982960] range","detail":"{range_begin:/registry/roles/cert-manager/cert-manager-cainjector:leaderelection; range_end:; response_count:1; response_revision:7568; }","duration":"231.460179ms","start":"2026-04-08T00:43:58.457042Z","end":"2026-04-08T00:43:58.688503Z","steps":["trace[1094982960] 'agreement among raft nodes before linearized reading' (duration: 231.349886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:58.688598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.919629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:43:58.68862Z","caller":"traceutil/trace.go:171","msg":"trace[1047973021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7568; }","duration":"107.960721ms","start":"2026-04-08T00:43:58.580653Z","end":"2026-04-08T00:43:58.688614Z","steps":["trace[1047973021] 'agreement among raft nodes before linearized reading' (duration: 107.93339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:58.68858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:43:58.386564Z","time spent":"301.699668ms","remote":"127.0.0.1:34768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:43:58.688762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.186613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/\" range_end:\"/registry/rolebindings/envoy-gateway-system0\" limit:500 ","response":"range_response_count:3 size:3302"} {"level":"info","ts":"2026-04-08T00:43:58.688785Z","caller":"traceutil/trace.go:171","msg":"trace[346748356] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/; range_end:/registry/rolebindings/envoy-gateway-system0; response_count:3; response_revision:7568; }","duration":"224.210444ms","start":"2026-04-08T00:43:58.464568Z","end":"2026-04-08T00:43:58.688779Z","steps":["trace[346748356] 'agreement among raft nodes before linearized reading' (duration: 224.147962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:58.688781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.064542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T00:43:58.688813Z","caller":"traceutil/trace.go:171","msg":"trace[1165039029] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:7568; }","duration":"152.110343ms","start":"2026-04-08T00:43:58.536692Z","end":"2026-04-08T00:43:58.688802Z","steps":["trace[1165039029] 'agreement among raft nodes before linearized reading' (duration: 152.02768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:58.688695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.438959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/default/\" range_end:\"/registry/roles/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:43:58.689008Z","caller":"traceutil/trace.go:171","msg":"trace[10530241] range","detail":"{range_begin:/registry/roles/default/; range_end:/registry/roles/default0; response_count:0; response_revision:7568; }","duration":"221.775279ms","start":"2026-04-08T00:43:58.467228Z","end":"2026-04-08T00:43:58.689004Z","steps":["trace[10530241] 'agreement among raft nodes before linearized reading' (duration: 221.434129ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:59.471006Z","caller":"traceutil/trace.go:171","msg":"trace[1039183739] linearizableReadLoop","detail":"{readStateIndex:7848; appliedIndex:7847; }","duration":"117.67461ms","start":"2026-04-08T00:43:59.353305Z","end":"2026-04-08T00:43:59.47098Z","steps":["trace[1039183739] 'read index received' (duration: 115.064141ms)","trace[1039183739] 'applied index is now lower than readState.Index' (duration: 2.609379ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:43:59.471132Z","caller":"traceutil/trace.go:171","msg":"trace[2075738348] transaction","detail":"{read_only:false; response_revision:7572; number_of_response:1; }","duration":"118.956215ms","start":"2026-04-08T00:43:59.352165Z","end":"2026-04-08T00:43:59.471121Z","steps":["trace[2075738348] 'process raft request' (duration: 116.275963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:59.471236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.886171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:43:59.471305Z","caller":"traceutil/trace.go:171","msg":"trace[2107177943] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:7572; }","duration":"104.000144ms","start":"2026-04-08T00:43:59.367289Z","end":"2026-04-08T00:43:59.471289Z","steps":["trace[2107177943] 'agreement among raft nodes before linearized reading' (duration: 103.869501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:59.471371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.401928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/kube-prometheus-stack-ca\" ","response":"range_response_count:1 size:2550"} {"level":"warn","ts":"2026-04-08T00:43:59.471348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.01193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/\" range_end:\"/registry/configmaps/kube-node-lease0\" limit:500 ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-04-08T00:43:59.471413Z","caller":"traceutil/trace.go:171","msg":"trace[378702532] range","detail":"{range_begin:/registry/secrets/cert-manager/kube-prometheus-stack-ca; range_end:; response_count:1; response_revision:7572; }","duration":"100.454889ms","start":"2026-04-08T00:43:59.370947Z","end":"2026-04-08T00:43:59.471402Z","steps":["trace[378702532] 'agreement among raft nodes before linearized reading' (duration: 100.311806ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:59.471437Z","caller":"traceutil/trace.go:171","msg":"trace[1811547053] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/; range_end:/registry/configmaps/kube-node-lease0; response_count:1; response_revision:7572; }","duration":"118.129223ms","start":"2026-04-08T00:43:59.353287Z","end":"2026-04-08T00:43:59.471416Z","steps":["trace[1811547053] 'agreement among raft nodes before linearized reading' (duration: 117.963918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:03.582951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.640888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/local-path-storage/\" range_end:\"/registry/persistentvolumeclaims/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:44:03.583035Z","caller":"traceutil/trace.go:171","msg":"trace[981515830] range","detail":"{range_begin:/registry/persistentvolumeclaims/local-path-storage/; range_end:/registry/persistentvolumeclaims/local-path-storage0; response_count:0; response_revision:7590; }","duration":"135.767561ms","start":"2026-04-08T00:44:03.447249Z","end":"2026-04-08T00:44:03.583016Z","steps":["trace[981515830] 'range keys from in-memory index tree' (duration: 135.570626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:03.583053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.31817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-08T00:44:03.583102Z","caller":"traceutil/trace.go:171","msg":"trace[1388671446] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7590; }","duration":"101.405492ms","start":"2026-04-08T00:44:03.481685Z","end":"2026-04-08T00:44:03.58309Z","steps":["trace[1388671446] 'range keys from in-memory index tree' (duration: 101.234117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:03.583637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.297514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/ingress-nginx/ingress-nginx-tcp\" ","response":"range_response_count:1 size:1042"} {"level":"info","ts":"2026-04-08T00:44:03.583728Z","caller":"traceutil/trace.go:171","msg":"trace[823612494] range","detail":"{range_begin:/registry/configmaps/ingress-nginx/ingress-nginx-tcp; range_end:; response_count:1; response_revision:7590; }","duration":"167.410087ms","start":"2026-04-08T00:44:03.416296Z","end":"2026-04-08T00:44:03.583706Z","steps":["trace[823612494] 'range keys from in-memory index tree' (duration: 167.15187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:04.157532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.120888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/local-path-storage/\" range_end:\"/registry/roles/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:44:04.157619Z","caller":"traceutil/trace.go:171","msg":"trace[2082336703] range","detail":"{range_begin:/registry/roles/local-path-storage/; range_end:/registry/roles/local-path-storage0; response_count:0; response_revision:7594; }","duration":"109.274542ms","start":"2026-04-08T00:44:04.048326Z","end":"2026-04-08T00:44:04.1576Z","steps":["trace[2082336703] 'range keys from in-memory index tree' (duration: 109.003344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:05.06912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.718984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/ingress-nginx/ingress-nginx\" ","response":"range_response_count:1 size:1159"} {"level":"info","ts":"2026-04-08T00:44:05.069186Z","caller":"traceutil/trace.go:171","msg":"trace[304394993] range","detail":"{range_begin:/registry/rolebindings/ingress-nginx/ingress-nginx; range_end:; response_count:1; response_revision:7598; }","duration":"121.802116ms","start":"2026-04-08T00:44:04.947368Z","end":"2026-04-08T00:44:05.069171Z","steps":["trace[304394993] 'range keys from in-memory index tree' (duration: 121.581521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:05.069188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.834243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/ingress-nginx/\" range_end:\"/registry/events/ingress-nginx0\" limit:500 ","response":"range_response_count:30 size:23669"} {"level":"info","ts":"2026-04-08T00:44:05.06924Z","caller":"traceutil/trace.go:171","msg":"trace[706834551] range","detail":"{range_begin:/registry/events/ingress-nginx/; range_end:/registry/events/ingress-nginx0; response_count:30; response_revision:7598; }","duration":"119.911375ms","start":"2026-04-08T00:44:04.949316Z","end":"2026-04-08T00:44:05.069228Z","steps":["trace[706834551] 'range keys from in-memory index tree' (duration: 119.630068ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:05.912219Z","caller":"traceutil/trace.go:171","msg":"trace[157338477] transaction","detail":"{read_only:false; response_revision:7601; number_of_response:1; }","duration":"152.117686ms","start":"2026-04-08T00:44:05.760067Z","end":"2026-04-08T00:44:05.912184Z","steps":["trace[157338477] 'process raft request' (duration: 132.336577ms)","trace[157338477] 'compare' (duration: 19.498401ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:44:05.912386Z","caller":"traceutil/trace.go:171","msg":"trace[1878633034] linearizableReadLoop","detail":"{readStateIndex:7878; appliedIndex:7877; }","duration":"143.124106ms","start":"2026-04-08T00:44:05.769008Z","end":"2026-04-08T00:44:05.912132Z","steps":["trace[1878633034] 'read index received' (duration: 123.367438ms)","trace[1878633034] 'applied index is now lower than readState.Index' (duration: 19.755658ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:44:05.912526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.497385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" ","response":"range_response_count:1 size:1041"} {"level":"info","ts":"2026-04-08T00:44:05.912629Z","caller":"traceutil/trace.go:171","msg":"trace[2093562094] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend; range_end:; response_count:1; response_revision:7601; }","duration":"143.60776ms","start":"2026-04-08T00:44:05.768974Z","end":"2026-04-08T00:44:05.912582Z","steps":["trace[2093562094] 'agreement among raft nodes before linearized reading' (duration: 143.443264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:05.912719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.673908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-04-08T00:44:05.912766Z","caller":"traceutil/trace.go:171","msg":"trace[1478968389] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:7601; }","duration":"110.762661ms","start":"2026-04-08T00:44:05.80199Z","end":"2026-04-08T00:44:05.912753Z","steps":["trace[1478968389] 'agreement among raft nodes before linearized reading' (duration: 110.541624ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:06.31802Z","caller":"traceutil/trace.go:171","msg":"trace[228481335] linearizableReadLoop","detail":"{readStateIndex:7879; appliedIndex:7878; }","duration":"401.967963ms","start":"2026-04-08T00:44:05.916021Z","end":"2026-04-08T00:44:06.317989Z","steps":["trace[228481335] 'read index received' (duration: 312.371059ms)","trace[228481335] 'applied index is now lower than readState.Index' (duration: 89.595304ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:44:06.3183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.26287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" ","response":"range_response_count:1 size:1041"} {"level":"info","ts":"2026-04-08T00:44:06.318361Z","caller":"traceutil/trace.go:171","msg":"trace[2040812683] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend; range_end:; response_count:1; response_revision:7602; }","duration":"402.363932ms","start":"2026-04-08T00:44:05.915983Z","end":"2026-04-08T00:44:06.318347Z","steps":["trace[2040812683] 'agreement among raft nodes before linearized reading' (duration: 402.183448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:06.31839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:44:05.915968Z","time spent":"402.416414ms","remote":"127.0.0.1:34696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":1064,"request content":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" "} {"level":"info","ts":"2026-04-08T00:44:06.318391Z","caller":"traceutil/trace.go:171","msg":"trace[264412963] transaction","detail":"{read_only:false; response_revision:7602; number_of_response:1; }","duration":"402.412984ms","start":"2026-04-08T00:44:05.915955Z","end":"2026-04-08T00:44:06.318368Z","steps":["trace[264412963] 'process raft request' (duration: 312.41377ms)","trace[264412963] 'compare' (duration: 89.412338ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:44:06.318438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.485923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/secretgen-controller/\" range_end:\"/registry/configmaps/secretgen-controller0\" limit:500 ","response":"range_response_count:1 size:1764"} {"level":"warn","ts":"2026-04-08T00:44:06.318487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.560697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/\" range_end:\"/registry/persistentvolumeclaims/openstack0\" limit:500 ","response":"range_response_count:2 size:3711"} {"level":"warn","ts":"2026-04-08T00:44:06.318486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.798374ms","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-04-08T00:44:06.3185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:44:05.915937Z","time spent":"402.495996ms","remote":"127.0.0.1:34768","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-04-08T00:44:06.318504Z","caller":"traceutil/trace.go:171","msg":"trace[603710850] range","detail":"{range_begin:/registry/configmaps/secretgen-controller/; range_end:/registry/configmaps/secretgen-controller0; response_count:1; response_revision:7602; }","duration":"247.774991ms","start":"2026-04-08T00:44:06.070714Z","end":"2026-04-08T00:44:06.318489Z","steps":["trace[603710850] 'agreement among raft nodes before linearized reading' (duration: 247.604346ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:06.31853Z","caller":"traceutil/trace.go:171","msg":"trace[114266935] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/; range_end:/registry/persistentvolumeclaims/openstack0; response_count:2; response_revision:7602; }","duration":"222.628578ms","start":"2026-04-08T00:44:06.095888Z","end":"2026-04-08T00:44:06.318517Z","steps":["trace[114266935] 'agreement among raft nodes before linearized reading' (duration: 222.452894ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:06.318537Z","caller":"traceutil/trace.go:171","msg":"trace[1020716022] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7602; }","duration":"289.870996ms","start":"2026-04-08T00:44:06.028653Z","end":"2026-04-08T00:44:06.318524Z","steps":["trace[1020716022] 'agreement among raft nodes before linearized reading' (duration: 289.715252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:06.318536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.436883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-04-08T00:44:06.318623Z","caller":"traceutil/trace.go:171","msg":"trace[1715826515] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:7602; }","duration":"323.574997ms","start":"2026-04-08T00:44:05.995026Z","end":"2026-04-08T00:44:06.318601Z","steps":["trace[1715826515] 'agreement among raft nodes before linearized reading' (duration: 323.286379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:06.318679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:44:05.995004Z","time spent":"323.657489ms","remote":"127.0.0.1:34682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2011,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-04-08T00:44:08.703734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.533069ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-08T00:44:08.70381Z","caller":"traceutil/trace.go:171","msg":"trace[1687345488] linearizableReadLoop","detail":"{readStateIndex:7891; appliedIndex:7890; }","duration":"139.557378ms","start":"2026-04-08T00:44:08.564239Z","end":"2026-04-08T00:44:08.703796Z","steps":["trace[1687345488] 'read index received' (duration: 21.899285ms)","trace[1687345488] 'applied index is now lower than readState.Index' (duration: 117.657283ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:44:08.704124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.874327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-08T00:44:08.704151Z","caller":"traceutil/trace.go:171","msg":"trace[952929438] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:7613; }","duration":"139.924248ms","start":"2026-04-08T00:44:08.56422Z","end":"2026-04-08T00:44:08.704144Z","steps":["trace[952929438] 'agreement among raft nodes before linearized reading' (duration: 139.788614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:08.704243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.060977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-08T00:44:08.704321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.80444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:44:08.704372Z","caller":"traceutil/trace.go:171","msg":"trace[1280641207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7613; }","duration":"125.860322ms","start":"2026-04-08T00:44:08.578497Z","end":"2026-04-08T00:44:08.704357Z","steps":["trace[1280641207] 'agreement among raft nodes before linearized reading' (duration: 125.7978ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:08.704317Z","caller":"traceutil/trace.go:171","msg":"trace[1257014330] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7613; }","duration":"126.249181ms","start":"2026-04-08T00:44:08.578049Z","end":"2026-04-08T00:44:08.704299Z","steps":["trace[1257014330] 'agreement among raft nodes before linearized reading' (duration: 126.044746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:08.704243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.768924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/kube-prometheus-stack-kube-controller-manager\" ","response":"range_response_count:1 size:1329"} {"level":"info","ts":"2026-04-08T00:44:08.704662Z","caller":"traceutil/trace.go:171","msg":"trace[1601432450] range","detail":"{range_begin:/registry/services/endpoints/kube-system/kube-prometheus-stack-kube-controller-manager; range_end:; response_count:1; response_revision:7613; }","duration":"109.229717ms","start":"2026-04-08T00:44:08.59542Z","end":"2026-04-08T00:44:08.70465Z","steps":["trace[1601432450] 'agreement among raft nodes before linearized reading' (duration: 108.687293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:09.024161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.280031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:44:09.024465Z","caller":"traceutil/trace.go:171","msg":"trace[2056378443] transaction","detail":"{read_only:false; response_revision:7618; number_of_response:1; }","duration":"255.444361ms","start":"2026-04-08T00:44:08.76899Z","end":"2026-04-08T00:44:09.024435Z","steps":["trace[2056378443] 'process raft request' (duration: 255.289537ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:09.024488Z","caller":"traceutil/trace.go:171","msg":"trace[1318389997] transaction","detail":"{read_only:false; response_revision:7616; number_of_response:1; }","duration":"312.442154ms","start":"2026-04-08T00:44:08.712016Z","end":"2026-04-08T00:44:09.024458Z","steps":["trace[1318389997] 'process raft request' (duration: 312.209208ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:09.024595Z","caller":"traceutil/trace.go:171","msg":"trace[1857785055] transaction","detail":"{read_only:false; response_revision:7615; number_of_response:1; }","duration":"315.129716ms","start":"2026-04-08T00:44:08.709455Z","end":"2026-04-08T00:44:09.024585Z","steps":["trace[1857785055] 'process raft request' (duration: 212.373711ms)","trace[1857785055] 'compare' (duration: 102.134968ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:44:09.024616Z","caller":"traceutil/trace.go:171","msg":"trace[1403954079] linearizableReadLoop","detail":"{readStateIndex:7894; appliedIndex:7892; }","duration":"300.191117ms","start":"2026-04-08T00:44:08.724408Z","end":"2026-04-08T00:44:09.024599Z","steps":["trace[1403954079] 'read index received' (duration: 197.431292ms)","trace[1403954079] 'applied index is now lower than readState.Index' (duration: 102.758235ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:44:09.024629Z","caller":"traceutil/trace.go:171","msg":"trace[1946134216] transaction","detail":"{read_only:false; response_revision:7617; number_of_response:1; }","duration":"261.473493ms","start":"2026-04-08T00:44:08.763138Z","end":"2026-04-08T00:44:09.024612Z","steps":["trace[1946134216] 'process raft request' (duration: 261.124583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:09.024632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:44:08.712005Z","time spent":"312.548678ms","remote":"127.0.0.1:34768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:44:09.02471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:44:08.709437Z","time spent":"315.179488ms","remote":"127.0.0.1:34768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:44:09.024802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.145957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/kube-prometheus-stack-kube-controller-manager\" ","response":"range_response_count:1 size:1329"} {"level":"warn","ts":"2026-04-08T00:44:09.024788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.361471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-08T00:44:09.024845Z","caller":"traceutil/trace.go:171","msg":"trace[1538882173] range","detail":"{range_begin:/registry/services/endpoints/kube-system/kube-prometheus-stack-kube-controller-manager; range_end:; response_count:1; response_revision:7618; }","duration":"242.209349ms","start":"2026-04-08T00:44:08.782623Z","end":"2026-04-08T00:44:09.024832Z","steps":["trace[1538882173] 'agreement among raft nodes before linearized reading' (duration: 242.072605ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:09.02486Z","caller":"traceutil/trace.go:171","msg":"trace[1586417057] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:7618; }","duration":"300.468414ms","start":"2026-04-08T00:44:08.724375Z","end":"2026-04-08T00:44:09.024843Z","steps":["trace[1586417057] 'agreement among raft nodes before linearized reading' (duration: 300.3112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:09.024897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/kube-prometheus-stack-kube-etcd\" ","response":"range_response_count:1 size:1254"} {"level":"warn","ts":"2026-04-08T00:44:09.024899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:44:08.724361Z","time spent":"300.530506ms","remote":"127.0.0.1:34642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-04-08T00:44:09.024929Z","caller":"traceutil/trace.go:171","msg":"trace[1103749094] range","detail":"{range_begin:/registry/services/endpoints/kube-system/kube-prometheus-stack-kube-etcd; range_end:; response_count:1; response_revision:7618; }","duration":"239.743452ms","start":"2026-04-08T00:44:08.785177Z","end":"2026-04-08T00:44:09.024921Z","steps":["trace[1103749094] 'agreement among raft nodes before linearized reading' (duration: 239.627469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:09.025003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.715629ms","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-04-08T00:44:09.025206Z","caller":"traceutil/trace.go:171","msg":"trace[640882894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7618; }","duration":"164.947514ms","start":"2026-04-08T00:44:08.860186Z","end":"2026-04-08T00:44:09.025134Z","steps":["trace[640882894] 'agreement among raft nodes before linearized reading' (duration: 164.516023ms)"],"step_count":1}