{"level":"warn","ts":"2026-07-16T06:48:36.792949Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T06:48:36.793048Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.103:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.103:2380","--initial-cluster=instance=https://199.19.213.103:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.103:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.103:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-16T06:48:36.793123Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T06:48:36.793133Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.103:2380"]} {"level":"info","ts":"2026-07-16T06:48:36.793152Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-16T06:48:36.794126Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.103:2379"]} {"level":"info","ts":"2026-07-16T06:48:36.794204Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.103:2380"],"listen-peer-urls":["https://199.19.213.103:2380"],"advertise-client-urls":["https://199.19.213.103:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.103:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.103:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-16T06:48:36.800937Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.423394ms"} {"level":"info","ts":"2026-07-16T06:48:36.810574Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d82047944f922143","cluster-id":"223a09c0da413f53"} {"level":"info","ts":"2026-07-16T06:48:36.810709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 switched to configuration voters=()"} {"level":"info","ts":"2026-07-16T06:48:36.810746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became follower at term 0"} {"level":"info","ts":"2026-07-16T06:48:36.810764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d82047944f922143 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-16T06:48:36.810773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became follower at term 1"} {"level":"info","ts":"2026-07-16T06:48:36.810816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 switched to configuration voters=(15573526213762883907)"} {"level":"warn","ts":"2026-07-16T06:48:36.820313Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-16T06:48:36.822581Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-16T06:48:36.825598Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-16T06:48:36.828959Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d82047944f922143","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-16T06:48:36.829257Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d82047944f922143","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-16T06:48:36.829375Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-16T06:48:36.82953Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-16T06:48:36.829567Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-16T06:48:36.830997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 switched to configuration voters=(15573526213762883907)"} {"level":"info","ts":"2026-07-16T06:48:36.831201Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"223a09c0da413f53","local-member-id":"d82047944f922143","added-peer-id":"d82047944f922143","added-peer-peer-urls":["https://199.19.213.103:2380"]} {"level":"info","ts":"2026-07-16T06:48:36.834267Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-16T06:48:36.83439Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.103:2380"} {"level":"info","ts":"2026-07-16T06:48:36.834436Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.103:2380"} {"level":"info","ts":"2026-07-16T06:48:36.834595Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-16T06:48:36.834496Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d82047944f922143","initial-advertise-peer-urls":["https://199.19.213.103:2380"],"listen-peer-urls":["https://199.19.213.103:2380"],"advertise-client-urls":["https://199.19.213.103:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.103:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-16T06:48:37.31194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 is starting a new election at term 1"} {"level":"info","ts":"2026-07-16T06:48:37.312058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-16T06:48:37.312128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 received MsgPreVoteResp from d82047944f922143 at term 1"} {"level":"info","ts":"2026-07-16T06:48:37.312154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became candidate at term 2"} {"level":"info","ts":"2026-07-16T06:48:37.312178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 received MsgVoteResp from d82047944f922143 at term 2"} {"level":"info","ts":"2026-07-16T06:48:37.3122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became leader at term 2"} {"level":"info","ts":"2026-07-16T06:48:37.312224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d82047944f922143 elected leader d82047944f922143 at term 2"} {"level":"info","ts":"2026-07-16T06:48:37.313235Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d82047944f922143","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.103:2379]}","request-path":"/0/members/d82047944f922143/attributes","cluster-id":"223a09c0da413f53","publish-timeout":"7s"} {"level":"info","ts":"2026-07-16T06:48:37.313285Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T06:48:37.313297Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:48:37.313559Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T06:48:37.314238Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"223a09c0da413f53","local-member-id":"d82047944f922143","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:48:37.314385Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:48:37.314434Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:48:37.314787Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-16T06:48:37.31486Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-16T06:48:37.316441Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-16T06:48:37.317412Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.103:2379"} {"level":"info","ts":"2026-07-16T06:49:13.703573Z","caller":"traceutil/trace.go:171","msg":"trace[531441729] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"176.379693ms","start":"2026-07-16T06:49:13.527175Z","end":"2026-07-16T06:49:13.703554Z","steps":["trace[531441729] 'process raft request' (duration: 176.344401ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:13.703909Z","caller":"traceutil/trace.go:171","msg":"trace[544780261] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"243.057833ms","start":"2026-07-16T06:49:13.460843Z","end":"2026-07-16T06:49:13.7039Z","steps":["trace[544780261] 'process raft request' (duration: 238.12529ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:13.703981Z","caller":"traceutil/trace.go:171","msg":"trace[1506366745] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"224.690287ms","start":"2026-07-16T06:49:13.479285Z","end":"2026-07-16T06:49:13.703975Z","steps":["trace[1506366745] 'process raft request' (duration: 224.094802ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:13.704056Z","caller":"traceutil/trace.go:171","msg":"trace[746923062] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"227.73744ms","start":"2026-07-16T06:49:13.47625Z","end":"2026-07-16T06:49:13.703987Z","steps":["trace[746923062] 'process raft request' (duration: 227.098462ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:13.704131Z","caller":"traceutil/trace.go:171","msg":"trace[1837285595] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"220.293749ms","start":"2026-07-16T06:49:13.483829Z","end":"2026-07-16T06:49:13.704123Z","steps":["trace[1837285595] 'process raft request' (duration: 219.582569ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:13.70426Z","caller":"traceutil/trace.go:171","msg":"trace[1502514256] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"177.257346ms","start":"2026-07-16T06:49:13.526994Z","end":"2026-07-16T06:49:13.704252Z","steps":["trace[1502514256] 'process raft request' (duration: 176.458125ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:13.704339Z","caller":"traceutil/trace.go:171","msg":"trace[1846362981] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"242.93689ms","start":"2026-07-16T06:49:13.461388Z","end":"2026-07-16T06:49:13.704325Z","steps":["trace[1846362981] 'process raft request' (duration: 241.82373ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:13.704428Z","caller":"traceutil/trace.go:171","msg":"trace[949123215] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"230.529014ms","start":"2026-07-16T06:49:13.473883Z","end":"2026-07-16T06:49:13.704412Z","steps":["trace[949123215] 'process raft request' (duration: 229.432475ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:13.704435Z","caller":"traceutil/trace.go:171","msg":"trace[87532572] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"230.684829ms","start":"2026-07-16T06:49:13.473684Z","end":"2026-07-16T06:49:13.704369Z","steps":["trace[87532572] 'process raft request' (duration: 229.59833ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:13.70458Z","caller":"traceutil/trace.go:171","msg":"trace[245006240] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"230.925796ms","start":"2026-07-16T06:49:13.473617Z","end":"2026-07-16T06:49:13.704543Z","steps":["trace[245006240] 'process raft request' (duration: 229.633912ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:13.714326Z","caller":"traceutil/trace.go:171","msg":"trace[270835170] transaction","detail":"{read_only:false; number_of_response:1; response_revision:503; }","duration":"165.729545ms","start":"2026-07-16T06:49:13.548585Z","end":"2026-07-16T06:49:13.714314Z","steps":["trace[270835170] 'process raft request' (duration: 165.637573ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:13.714463Z","caller":"traceutil/trace.go:171","msg":"trace[2044029851] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"166.624169ms","start":"2026-07-16T06:49:13.547831Z","end":"2026-07-16T06:49:13.714455Z","steps":["trace[2044029851] 'process raft request' (duration: 166.340871ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:13.714522Z","caller":"traceutil/trace.go:171","msg":"trace[838189630] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"167.674478ms","start":"2026-07-16T06:49:13.546842Z","end":"2026-07-16T06:49:13.714516Z","steps":["trace[838189630] 'process raft request' (duration: 167.268388ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:20.400018Z","caller":"traceutil/trace.go:171","msg":"trace[152738716] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"183.156872ms","start":"2026-07-16T06:49:20.216835Z","end":"2026-07-16T06:49:20.399992Z","steps":["trace[152738716] 'read index received' (duration: 182.345162ms)","trace[152738716] 'applied index is now lower than readState.Index' (duration: 810.81µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:49:20.400129Z","caller":"traceutil/trace.go:171","msg":"trace[1461339904] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"192.988028ms","start":"2026-07-16T06:49:20.20711Z","end":"2026-07-16T06:49:20.400098Z","steps":["trace[1461339904] 'process raft request' (duration: 192.28195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:20.40036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.065403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.103\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-16T06:49:20.400473Z","caller":"traceutil/trace.go:171","msg":"trace[1349771946] range","detail":"{range_begin:/registry/masterleases/199.19.213.103; range_end:; response_count:1; response_revision:598; }","duration":"165.217257ms","start":"2026-07-16T06:49:20.235243Z","end":"2026-07-16T06:49:20.40046Z","steps":["trace[1349771946] 'agreement among raft nodes before linearized reading' (duration: 165.056892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:20.400479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.629845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nf5z8\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-16T06:49:20.400696Z","caller":"traceutil/trace.go:171","msg":"trace[983949033] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nf5z8; range_end:; response_count:1; response_revision:598; }","duration":"183.871612ms","start":"2026-07-16T06:49:20.216803Z","end":"2026-07-16T06:49:20.400675Z","steps":["trace[983949033] 'agreement among raft nodes before linearized reading' (duration: 183.382199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:21.253162Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2396934702950830136,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T06:49:21.266473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.637814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:49:21.266818Z","caller":"traceutil/trace.go:171","msg":"trace[1672909951] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:621; }","duration":"513.917869ms","start":"2026-07-16T06:49:20.752885Z","end":"2026-07-16T06:49:21.266803Z","steps":["trace[1672909951] 'read index received' (duration: 511.375663ms)","trace[1672909951] 'applied index is now lower than readState.Index' (duration: 2.541145ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:49:21.266797Z","caller":"traceutil/trace.go:171","msg":"trace[1298503397] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"849.278971ms","start":"2026-07-16T06:49:20.417471Z","end":"2026-07-16T06:49:21.26675Z","steps":["trace[1298503397] 'process raft request' (duration: 51.808755ms)","trace[1298503397] 'compare' (duration: 796.381738ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:49:21.266917Z","caller":"traceutil/trace.go:171","msg":"trace[2114884745] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"849.435934ms","start":"2026-07-16T06:49:20.417472Z","end":"2026-07-16T06:49:21.266908Z","steps":["trace[2114884745] 'process raft request' (duration: 849.111436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:21.266986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:20.417452Z","time spent":"849.496786ms","remote":"127.0.0.1:54234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:49:21.26726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:20.417457Z","time spent":"849.425335ms","remote":"127.0.0.1:54396","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-07-16T06:49:21.267311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.423442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-16T06:49:21.267365Z","caller":"traceutil/trace.go:171","msg":"trace[1152992341] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"514.508394ms","start":"2026-07-16T06:49:20.752841Z","end":"2026-07-16T06:49:21.26735Z","steps":["trace[1152992341] 'agreement among raft nodes before linearized reading' (duration: 514.306358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:21.2674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:20.752822Z","time spent":"514.572815ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-16T06:49:21.267404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.774088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-16T06:49:21.267423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.993744ms","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-07-16T06:49:21.267441Z","caller":"traceutil/trace.go:171","msg":"trace[349990123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"401.825299ms","start":"2026-07-16T06:49:20.865605Z","end":"2026-07-16T06:49:21.26743Z","steps":["trace[349990123] 'agreement among raft nodes before linearized reading' (duration: 401.695996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:21.267314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.728748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:49:21.26747Z","caller":"traceutil/trace.go:171","msg":"trace[1588438752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"402.055315ms","start":"2026-07-16T06:49:20.865401Z","end":"2026-07-16T06:49:21.267456Z","steps":["trace[1588438752] 'agreement among raft nodes before linearized reading' (duration: 401.947812ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:21.267423Z","caller":"traceutil/trace.go:171","msg":"trace[1781008035] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"807.682841ms","start":"2026-07-16T06:49:20.459537Z","end":"2026-07-16T06:49:21.267219Z","steps":["trace[1781008035] 'process raft request' (duration: 807.133627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:21.267535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.283146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-16T06:49:21.267601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:20.86539Z","time spent":"402.202179ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-16T06:49:21.267528Z","caller":"traceutil/trace.go:171","msg":"trace[1533722717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"423.985404ms","start":"2026-07-16T06:49:20.843527Z","end":"2026-07-16T06:49:21.267512Z","steps":["trace[1533722717] 'agreement among raft nodes before linearized reading' (duration: 423.728307ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:21.267659Z","caller":"traceutil/trace.go:171","msg":"trace[1348096763] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"203.41186ms","start":"2026-07-16T06:49:21.064197Z","end":"2026-07-16T06:49:21.267608Z","steps":["trace[1348096763] 'agreement among raft nodes before linearized reading' (duration: 203.178243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:21.267475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:20.865598Z","time spent":"401.87042ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T06:49:21.267698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:20.843508Z","time spent":"424.172419ms","remote":"127.0.0.1:54192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T06:49:21.26779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:20.45951Z","time spent":"808.096542ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:49:36.708815Z","caller":"traceutil/trace.go:171","msg":"trace[2140729434] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"133.680829ms","start":"2026-07-16T06:49:36.575114Z","end":"2026-07-16T06:49:36.708795Z","steps":["trace[2140729434] 'process raft request' (duration: 133.516826ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:06.640591Z","caller":"traceutil/trace.go:171","msg":"trace[702268959] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"116.832527ms","start":"2026-07-16T06:50:06.523733Z","end":"2026-07-16T06:50:06.640565Z","steps":["trace[702268959] 'process raft request' (duration: 116.664653ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:18.717291Z","caller":"traceutil/trace.go:171","msg":"trace[1816147428] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"117.540833ms","start":"2026-07-16T06:50:18.599727Z","end":"2026-07-16T06:50:18.717267Z","steps":["trace[1816147428] 'process raft request' (duration: 117.37642ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:28.926902Z","caller":"traceutil/trace.go:171","msg":"trace[1275444249] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"154.629253ms","start":"2026-07-16T06:50:28.772236Z","end":"2026-07-16T06:50:28.926865Z","steps":["trace[1275444249] 'process raft request' (duration: 154.271936ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:41.71666Z","caller":"traceutil/trace.go:171","msg":"trace[121003612] transaction","detail":"{read_only:false; response_revision:1631; number_of_response:1; }","duration":"135.316629ms","start":"2026-07-16T06:50:41.581317Z","end":"2026-07-16T06:50:41.716633Z","steps":["trace[121003612] 'process raft request' (duration: 135.138535ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:41.727636Z","caller":"traceutil/trace.go:171","msg":"trace[1200015959] transaction","detail":"{read_only:false; response_revision:1632; number_of_response:1; }","duration":"145.352405ms","start":"2026-07-16T06:50:41.582266Z","end":"2026-07-16T06:50:41.727619Z","steps":["trace[1200015959] 'process raft request' (duration: 145.11672ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:41.72765Z","caller":"traceutil/trace.go:171","msg":"trace[1786755050] linearizableReadLoop","detail":"{readStateIndex:1683; appliedIndex:1681; }","duration":"113.212879ms","start":"2026-07-16T06:50:41.61442Z","end":"2026-07-16T06:50:41.727633Z","steps":["trace[1786755050] 'read index received' (duration: 102.150124ms)","trace[1786755050] 'applied index is now lower than readState.Index' (duration: 11.061965ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:50:41.727831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.392832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-6b5c8b7cdc-bnqcr\" ","response":"range_response_count:1 size:4959"} {"level":"info","ts":"2026-07-16T06:50:41.727875Z","caller":"traceutil/trace.go:171","msg":"trace[823059731] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-6b5c8b7cdc-bnqcr; range_end:; response_count:1; response_revision:1633; }","duration":"113.487975ms","start":"2026-07-16T06:50:41.614378Z","end":"2026-07-16T06:50:41.727866Z","steps":["trace[823059731] 'agreement among raft nodes before linearized reading' (duration: 113.321352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:42.031484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.329581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:50:42.031791Z","caller":"traceutil/trace.go:171","msg":"trace[431896788] transaction","detail":"{read_only:false; response_revision:1634; number_of_response:1; }","duration":"308.232967ms","start":"2026-07-16T06:50:41.723521Z","end":"2026-07-16T06:50:42.031754Z","steps":["trace[431896788] 'process raft request' (duration: 159.57232ms)","trace[431896788] 'compare' (duration: 148.209608ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:50:42.031847Z","caller":"traceutil/trace.go:171","msg":"trace[822401268] transaction","detail":"{read_only:false; response_revision:1635; number_of_response:1; }","duration":"304.717629ms","start":"2026-07-16T06:50:41.727105Z","end":"2026-07-16T06:50:42.031822Z","steps":["trace[822401268] 'process raft request' (duration: 304.465654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:42.031982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:41.723502Z","time spent":"308.377609ms","remote":"127.0.0.1:54776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T06:50:42.032179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:41.72709Z","time spent":"304.935502ms","remote":"127.0.0.1:54256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6985,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:50:42.034978Z","caller":"traceutil/trace.go:171","msg":"trace[1330746111] transaction","detail":"{read_only:false; response_revision:1636; number_of_response:1; }","duration":"301.605938ms","start":"2026-07-16T06:50:41.73334Z","end":"2026-07-16T06:50:42.034946Z","steps":["trace[1330746111] 'process raft request' (duration: 301.445634ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:42.035125Z","caller":"traceutil/trace.go:171","msg":"trace[1955416235] transaction","detail":"{read_only:false; response_revision:1637; number_of_response:1; }","duration":"264.353155ms","start":"2026-07-16T06:50:41.770758Z","end":"2026-07-16T06:50:42.035111Z","steps":["trace[1955416235] 'process raft request' (duration: 264.09946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:42.035218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:41.733322Z","time spent":"301.729771ms","remote":"127.0.0.1:54396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:50:42.035298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.358044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterbackups.pxc.percona.com\" ","response":"range_response_count:1 size:3741"} {"level":"info","ts":"2026-07-16T06:50:42.035383Z","caller":"traceutil/trace.go:171","msg":"trace[2115757419] transaction","detail":"{read_only:false; response_revision:1638; number_of_response:1; }","duration":"264.382824ms","start":"2026-07-16T06:50:41.770992Z","end":"2026-07-16T06:50:42.035374Z","steps":["trace[2115757419] 'process raft request' (duration: 263.899444ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:42.035413Z","caller":"traceutil/trace.go:171","msg":"trace[37335228] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterbackups.pxc.percona.com; range_end:; response_count:1; response_revision:1638; }","duration":"264.507338ms","start":"2026-07-16T06:50:41.770888Z","end":"2026-07-16T06:50:42.035396Z","steps":["trace[37335228] 'agreement among raft nodes before linearized reading' (duration: 264.14711ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:42.034998Z","caller":"traceutil/trace.go:171","msg":"trace[2133454242] linearizableReadLoop","detail":"{readStateIndex:1688; appliedIndex:1684; }","duration":"264.011177ms","start":"2026-07-16T06:50:41.770921Z","end":"2026-07-16T06:50:42.034933Z","steps":["trace[2133454242] 'read index received' (duration: 112.25755ms)","trace[2133454242] 'applied index is now lower than readState.Index' (duration: 151.752497ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:50:42.035676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.553045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:50:42.035749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.576605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-16T06:50:42.035814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.534875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-16T06:50:42.035822Z","caller":"traceutil/trace.go:171","msg":"trace[626126067] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1638; }","duration":"166.664677ms","start":"2026-07-16T06:50:41.869138Z","end":"2026-07-16T06:50:42.035803Z","steps":["trace[626126067] 'agreement among raft nodes before linearized reading' (duration: 166.442741ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:42.035848Z","caller":"traceutil/trace.go:171","msg":"trace[1106306316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1638; }","duration":"166.594145ms","start":"2026-07-16T06:50:41.869243Z","end":"2026-07-16T06:50:42.035837Z","steps":["trace[1106306316] 'agreement among raft nodes before linearized reading' (duration: 166.514853ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:42.035796Z","caller":"traceutil/trace.go:171","msg":"trace[1806316372] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:0; response_revision:1638; }","duration":"199.710168ms","start":"2026-07-16T06:50:41.836067Z","end":"2026-07-16T06:50:42.035777Z","steps":["trace[1806316372] 'agreement among raft nodes before linearized reading' (duration: 199.507914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:42.035984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.862085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:50:42.035936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.650842ms","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-07-16T06:50:42.036018Z","caller":"traceutil/trace.go:171","msg":"trace[17871255] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1638; }","duration":"191.926297ms","start":"2026-07-16T06:50:41.844083Z","end":"2026-07-16T06:50:42.036009Z","steps":["trace[17871255] 'agreement among raft nodes before linearized reading' (duration: 191.875286ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:42.036026Z","caller":"traceutil/trace.go:171","msg":"trace[1058600068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1638; }","duration":"156.803125ms","start":"2026-07-16T06:50:41.879214Z","end":"2026-07-16T06:50:42.036017Z","steps":["trace[1058600068] 'agreement among raft nodes before linearized reading' (duration: 156.433498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:42.035719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.90442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-16T06:50:42.036252Z","caller":"traceutil/trace.go:171","msg":"trace[1382400221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1638; }","duration":"188.46546ms","start":"2026-07-16T06:50:41.847776Z","end":"2026-07-16T06:50:42.036241Z","steps":["trace[1382400221] 'agreement among raft nodes before linearized reading' (duration: 187.859618ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:42.222613Z","caller":"traceutil/trace.go:171","msg":"trace[257586248] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"182.025159ms","start":"2026-07-16T06:50:42.040564Z","end":"2026-07-16T06:50:42.222589Z","steps":["trace[257586248] 'process raft request' (duration: 121.422713ms)","trace[257586248] 'compare' (duration: 60.227298ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:50:42.222956Z","caller":"traceutil/trace.go:171","msg":"trace[1419121339] transaction","detail":"{read_only:false; response_revision:1645; number_of_response:1; }","duration":"179.744755ms","start":"2026-07-16T06:50:42.043187Z","end":"2026-07-16T06:50:42.222932Z","steps":["trace[1419121339] 'process raft request' (duration: 179.706055ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:42.222975Z","caller":"traceutil/trace.go:171","msg":"trace[201673309] transaction","detail":"{read_only:false; response_revision:1643; number_of_response:1; }","duration":"180.063201ms","start":"2026-07-16T06:50:42.04289Z","end":"2026-07-16T06:50:42.222954Z","steps":["trace[201673309] 'process raft request' (duration: 179.925479ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:42.223044Z","caller":"traceutil/trace.go:171","msg":"trace[1619311332] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"180.422718ms","start":"2026-07-16T06:50:42.04261Z","end":"2026-07-16T06:50:42.223033Z","steps":["trace[1619311332] 'process raft request' (duration: 179.99052ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:42.223087Z","caller":"traceutil/trace.go:171","msg":"trace[405323137] transaction","detail":"{read_only:false; response_revision:1644; number_of_response:1; }","duration":"180.131282ms","start":"2026-07-16T06:50:42.042948Z","end":"2026-07-16T06:50:42.22308Z","steps":["trace[405323137] 'process raft request' (duration: 179.910877ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:42.223143Z","caller":"traceutil/trace.go:171","msg":"trace[1931917598] transaction","detail":"{read_only:false; response_revision:1640; number_of_response:1; }","duration":"182.242743ms","start":"2026-07-16T06:50:42.04089Z","end":"2026-07-16T06:50:42.223133Z","steps":["trace[1931917598] 'process raft request' (duration: 181.476558ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:42.223194Z","caller":"traceutil/trace.go:171","msg":"trace[700712878] transaction","detail":"{read_only:false; response_revision:1642; number_of_response:1; }","duration":"180.427598ms","start":"2026-07-16T06:50:42.042759Z","end":"2026-07-16T06:50:42.223187Z","steps":["trace[700712878] 'process raft request' (duration: 179.987999ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:42.378351Z","caller":"traceutil/trace.go:171","msg":"trace[1506224577] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"141.574626ms","start":"2026-07-16T06:50:42.236732Z","end":"2026-07-16T06:50:42.378306Z","steps":["trace[1506224577] 'process raft request' (duration: 138.472475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:42.378414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.452707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:50:42.378433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.500589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:50:42.378429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.586261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:50:42.378493Z","caller":"traceutil/trace.go:171","msg":"trace[1553980080] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:1648; }","duration":"117.558508ms","start":"2026-07-16T06:50:42.260912Z","end":"2026-07-16T06:50:42.378471Z","steps":["trace[1553980080] 'agreement among raft nodes before linearized reading' (duration: 117.394656ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:42.378523Z","caller":"traceutil/trace.go:171","msg":"trace[789944457] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:1648; }","duration":"117.627942ms","start":"2026-07-16T06:50:42.260862Z","end":"2026-07-16T06:50:42.37849Z","steps":["trace[789944457] 'agreement among raft nodes before linearized reading' (duration: 117.477208ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:42.378527Z","caller":"traceutil/trace.go:171","msg":"trace[450333638] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:1648; }","duration":"116.721174ms","start":"2026-07-16T06:50:42.261788Z","end":"2026-07-16T06:50:42.378509Z","steps":["trace[450333638] 'agreement among raft nodes before linearized reading' (duration: 116.51018ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:42.424848Z","caller":"traceutil/trace.go:171","msg":"trace[253793866] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"108.966273ms","start":"2026-07-16T06:50:42.315856Z","end":"2026-07-16T06:50:42.424822Z","steps":["trace[253793866] 'process raft request' (duration: 108.597355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:42.424872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.014635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-16T06:50:42.424941Z","caller":"traceutil/trace.go:171","msg":"trace[1829969618] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1649; }","duration":"139.122798ms","start":"2026-07-16T06:50:42.285794Z","end":"2026-07-16T06:50:42.424917Z","steps":["trace[1829969618] 'agreement among raft nodes before linearized reading' (duration: 138.926034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:42.700406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.562291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:50:42.700972Z","caller":"traceutil/trace.go:171","msg":"trace[1286777617] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"318.089187ms","start":"2026-07-16T06:50:42.382843Z","end":"2026-07-16T06:50:42.700932Z","steps":["trace[1286777617] 'process raft request' (duration: 169.920474ms)","trace[1286777617] 'compare' (duration: 147.443618ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:50:42.701117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:42.382826Z","time spent":"318.224679ms","remote":"127.0.0.1:54282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":828,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:50:42.701565Z","caller":"traceutil/trace.go:171","msg":"trace[2146658682] transaction","detail":"{read_only:false; response_revision:1651; number_of_response:1; }","duration":"272.231438ms","start":"2026-07-16T06:50:42.429305Z","end":"2026-07-16T06:50:42.701536Z","steps":["trace[2146658682] 'process raft request' (duration: 271.542005ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:42.702303Z","caller":"traceutil/trace.go:171","msg":"trace[1003275455] transaction","detail":"{read_only:false; response_revision:1652; number_of_response:1; }","duration":"106.799909ms","start":"2026-07-16T06:50:42.595487Z","end":"2026-07-16T06:50:42.702287Z","steps":["trace[1003275455] 'process raft request' (duration: 105.827971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:52.885717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.38277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:50:52.885828Z","caller":"traceutil/trace.go:171","msg":"trace[1899739116] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1818; }","duration":"438.588985ms","start":"2026-07-16T06:50:52.447217Z","end":"2026-07-16T06:50:52.885806Z","steps":["trace[1899739116] 'count revisions from in-memory index tree' (duration: 438.264927ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:52.885845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.073855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-14ec5aad-f002-48ca-a7f1-65ba5de8d488\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-07-16T06:50:52.885879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:52.447193Z","time spent":"438.674596ms","remote":"127.0.0.1:34990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-07-16T06:50:52.885929Z","caller":"traceutil/trace.go:171","msg":"trace[1246475472] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-14ec5aad-f002-48ca-a7f1-65ba5de8d488; range_end:; response_count:1; response_revision:1818; }","duration":"109.186668ms","start":"2026-07-16T06:50:52.776718Z","end":"2026-07-16T06:50:52.885905Z","steps":["trace[1246475472] 'range keys from in-memory index tree' (duration: 108.937744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:52.885941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.159547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-16T06:50:52.886059Z","caller":"traceutil/trace.go:171","msg":"trace[1284939267] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1818; }","duration":"592.360319ms","start":"2026-07-16T06:50:52.293669Z","end":"2026-07-16T06:50:52.88603Z","steps":["trace[1284939267] 'range keys from in-memory index tree' (duration: 591.904901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:52.886078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.694196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-16T06:50:52.886125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:52.293643Z","time spent":"592.465523ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-16T06:50:52.886143Z","caller":"traceutil/trace.go:171","msg":"trace[674271368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1818; }","duration":"591.768789ms","start":"2026-07-16T06:50:52.294355Z","end":"2026-07-16T06:50:52.886124Z","steps":["trace[674271368] 'range keys from in-memory index tree' (duration: 591.587635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:52.88619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:52.294344Z","time spent":"591.837449ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T06:50:52.886374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.437946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-16T06:50:52.88642Z","caller":"traceutil/trace.go:171","msg":"trace[1641255745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1818; }","duration":"617.53484ms","start":"2026-07-16T06:50:52.268872Z","end":"2026-07-16T06:50:52.886406Z","steps":["trace[1641255745] 'range keys from in-memory index tree' (duration: 617.288514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:52.886415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.628618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-16T06:50:52.886451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:52.26884Z","time spent":"617.60393ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-16T06:50:52.886459Z","caller":"traceutil/trace.go:171","msg":"trace[1349012607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1818; }","duration":"620.706779ms","start":"2026-07-16T06:50:52.26574Z","end":"2026-07-16T06:50:52.886447Z","steps":["trace[1349012607] 'range keys from in-memory index tree' (duration: 620.427114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:52.886488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:52.26572Z","time spent":"620.76126ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-16T06:50:52.886624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.925353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-14ec5aad-f002-48ca-a7f1-65ba5de8d488\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-16T06:50:52.886665Z","caller":"traceutil/trace.go:171","msg":"trace[374099259] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-14ec5aad-f002-48ca-a7f1-65ba5de8d488; range_end:; response_count:1; response_revision:1818; }","duration":"221.987244ms","start":"2026-07-16T06:50:52.664654Z","end":"2026-07-16T06:50:52.886641Z","steps":["trace[374099259] 'range keys from in-memory index tree' (duration: 221.81535ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:52.885948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.015517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-07-16T06:50:52.886747Z","caller":"traceutil/trace.go:171","msg":"trace[1552538185] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1818; }","duration":"568.879634ms","start":"2026-07-16T06:50:52.317845Z","end":"2026-07-16T06:50:52.886725Z","steps":["trace[1552538185] 'range keys from in-memory index tree' (duration: 567.751292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:52.886796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:52.317818Z","time spent":"568.961024ms","remote":"127.0.0.1:54308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":682,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-16T06:50:52.885712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.151396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-07-16T06:50:52.889421Z","caller":"traceutil/trace.go:171","msg":"trace[1901495728] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1818; }","duration":"523.901757ms","start":"2026-07-16T06:50:52.365497Z","end":"2026-07-16T06:50:52.889398Z","steps":["trace[1901495728] 'range keys from in-memory index tree' (duration: 519.951232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:52.889562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:52.365433Z","time spent":"524.110971ms","remote":"127.0.0.1:47988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4614,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-16T06:50:53.047825Z","caller":"traceutil/trace.go:171","msg":"trace[185643463] linearizableReadLoop","detail":"{readStateIndex:1875; appliedIndex:1874; }","duration":"156.349084ms","start":"2026-07-16T06:50:52.891453Z","end":"2026-07-16T06:50:53.047802Z","steps":["trace[185643463] 'read index received' (duration: 156.078009ms)","trace[185643463] 'applied index is now lower than readState.Index' (duration: 270.185µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:50:53.048076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.585858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-07-16T06:50:53.048135Z","caller":"traceutil/trace.go:171","msg":"trace[246825530] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1819; }","duration":"156.69897ms","start":"2026-07-16T06:50:52.891423Z","end":"2026-07-16T06:50:53.048122Z","steps":["trace[246825530] 'agreement among raft nodes before linearized reading' (duration: 156.504556ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:53.047897Z","caller":"traceutil/trace.go:171","msg":"trace[1115176076] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"157.001496ms","start":"2026-07-16T06:50:52.890873Z","end":"2026-07-16T06:50:53.047874Z","steps":["trace[1115176076] 'process raft request' (duration: 156.789092ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:53.159195Z","caller":"traceutil/trace.go:171","msg":"trace[432238437] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"265.836863ms","start":"2026-07-16T06:50:52.893322Z","end":"2026-07-16T06:50:53.159159Z","steps":["trace[432238437] 'process raft request' (duration: 265.6904ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:53.159384Z","caller":"traceutil/trace.go:171","msg":"trace[90450038] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"263.823175ms","start":"2026-07-16T06:50:52.89554Z","end":"2026-07-16T06:50:53.159364Z","steps":["trace[90450038] 'process raft request' (duration: 263.541ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:53.159775Z","caller":"traceutil/trace.go:171","msg":"trace[538292646] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"125.698708ms","start":"2026-07-16T06:50:53.034036Z","end":"2026-07-16T06:50:53.159735Z","steps":["trace[538292646] 'process raft request' (duration: 125.25908ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:53.159764Z","caller":"traceutil/trace.go:171","msg":"trace[1958282049] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"266.76506ms","start":"2026-07-16T06:50:52.892327Z","end":"2026-07-16T06:50:53.159092Z","steps":["trace[1958282049] 'process raft request' (duration: 266.607586ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:53.159715Z","caller":"traceutil/trace.go:171","msg":"trace[2121364594] linearizableReadLoop","detail":"{readStateIndex:1880; appliedIndex:1875; }","duration":"111.653501ms","start":"2026-07-16T06:50:53.048039Z","end":"2026-07-16T06:50:53.159693Z","steps":["trace[2121364594] 'read index received' (duration: 110.757354ms)","trace[2121364594] 'applied index is now lower than readState.Index' (duration: 893.537µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:50:53.160129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.602756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-07-16T06:50:53.160185Z","caller":"traceutil/trace.go:171","msg":"trace[1706806665] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1824; }","duration":"268.686168ms","start":"2026-07-16T06:50:52.891484Z","end":"2026-07-16T06:50:53.160171Z","steps":["trace[1706806665] 'agreement among raft nodes before linearized reading' (duration: 268.34702ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:53.160958Z","caller":"traceutil/trace.go:171","msg":"trace[1752473924] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"268.709967ms","start":"2026-07-16T06:50:52.892224Z","end":"2026-07-16T06:50:53.160934Z","steps":["trace[1752473924] 'process raft request' (duration: 266.505184ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:53.176166Z","caller":"traceutil/trace.go:171","msg":"trace[1076371628] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"121.319064ms","start":"2026-07-16T06:50:53.05482Z","end":"2026-07-16T06:50:53.176139Z","steps":["trace[1076371628] 'process raft request' (duration: 121.190972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:53.355324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.536423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:50:53.355521Z","caller":"traceutil/trace.go:171","msg":"trace[874872829] linearizableReadLoop","detail":"{readStateIndex:1882; appliedIndex:1881; }","duration":"175.649676ms","start":"2026-07-16T06:50:53.179801Z","end":"2026-07-16T06:50:53.35545Z","steps":["trace[874872829] 'read index received' (duration: 67.812068ms)","trace[874872829] 'applied index is now lower than readState.Index' (duration: 107.835568ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:50:53.355718Z","caller":"traceutil/trace.go:171","msg":"trace[1199060526] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"188.656333ms","start":"2026-07-16T06:50:53.166814Z","end":"2026-07-16T06:50:53.35547Z","steps":["trace[1199060526] 'process raft request' (duration: 80.855056ms)","trace[1199060526] 'compare' (duration: 107.357319ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:50:53.355725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.911051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-07-16T06:50:53.355788Z","caller":"traceutil/trace.go:171","msg":"trace[2109202573] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1826; }","duration":"176.017914ms","start":"2026-07-16T06:50:53.17975Z","end":"2026-07-16T06:50:53.355768Z","steps":["trace[2109202573] 'agreement among raft nodes before linearized reading' (duration: 175.77459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:53.356909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.320885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-16T06:50:53.357007Z","caller":"traceutil/trace.go:171","msg":"trace[1607190956] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1826; }","duration":"110.488929ms","start":"2026-07-16T06:50:53.246503Z","end":"2026-07-16T06:50:53.356992Z","steps":["trace[1607190956] 'agreement among raft nodes before linearized reading' (duration: 110.241255ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:55.858071Z","caller":"traceutil/trace.go:171","msg":"trace[1475591752] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"162.940214ms","start":"2026-07-16T06:50:55.695097Z","end":"2026-07-16T06:50:55.858037Z","steps":["trace[1475591752] 'process raft request' (duration: 162.686489ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:59.678224Z","caller":"traceutil/trace.go:171","msg":"trace[1050371582] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"179.330522ms","start":"2026-07-16T06:50:59.498863Z","end":"2026-07-16T06:50:59.678194Z","steps":["trace[1050371582] 'process raft request' (duration: 179.144279ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:59.688354Z","caller":"traceutil/trace.go:171","msg":"trace[390889096] linearizableReadLoop","detail":"{readStateIndex:1960; appliedIndex:1958; }","duration":"178.353054ms","start":"2026-07-16T06:50:59.509977Z","end":"2026-07-16T06:50:59.68833Z","steps":["trace[390889096] 'read index received' (duration: 168.065201ms)","trace[390889096] 'applied index is now lower than readState.Index' (duration: 10.286633ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:50:59.688412Z","caller":"traceutil/trace.go:171","msg":"trace[189520906] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"186.776032ms","start":"2026-07-16T06:50:59.501601Z","end":"2026-07-16T06:50:59.688377Z","steps":["trace[189520906] 'process raft request' (duration: 186.606029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:59.688775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.725661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-07-16T06:50:59.688839Z","caller":"traceutil/trace.go:171","msg":"trace[682435213] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1902; }","duration":"178.883904ms","start":"2026-07-16T06:50:59.509935Z","end":"2026-07-16T06:50:59.688819Z","steps":["trace[682435213] 'agreement among raft nodes before linearized reading' (duration: 178.487736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:51:00.027994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.065277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:51:00.028226Z","caller":"traceutil/trace.go:171","msg":"trace[692559378] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"188.446143ms","start":"2026-07-16T06:50:59.839751Z","end":"2026-07-16T06:51:00.028197Z","steps":["trace[692559378] 'process raft request' (duration: 27.07398ms)","trace[692559378] 'compare' (duration: 160.917235ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:51:00.045902Z","caller":"traceutil/trace.go:171","msg":"trace[1538336800] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"204.537265ms","start":"2026-07-16T06:50:59.841348Z","end":"2026-07-16T06:51:00.045886Z","steps":["trace[1538336800] 'process raft request' (duration: 204.370023ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:00.046232Z","caller":"traceutil/trace.go:171","msg":"trace[1377676445] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"204.554035ms","start":"2026-07-16T06:50:59.841635Z","end":"2026-07-16T06:51:00.046189Z","steps":["trace[1377676445] 'process raft request' (duration: 204.202299ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:00.046668Z","caller":"traceutil/trace.go:171","msg":"trace[1258588471] linearizableReadLoop","detail":"{readStateIndex:1968; appliedIndex:1964; }","duration":"203.376453ms","start":"2026-07-16T06:50:59.843275Z","end":"2026-07-16T06:51:00.046651Z","steps":["trace[1258588471] 'read index received' (duration: 23.563193ms)","trace[1258588471] 'applied index is now lower than readState.Index' (duration: 179.81176ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:51:00.046899Z","caller":"traceutil/trace.go:171","msg":"trace[1415271432] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"205.005385ms","start":"2026-07-16T06:50:59.841873Z","end":"2026-07-16T06:51:00.046878Z","steps":["trace[1415271432] 'process raft request' (duration: 204.220449ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:51:00.047032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.491268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-07-16T06:51:00.047043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.75195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:51:00.047093Z","caller":"traceutil/trace.go:171","msg":"trace[286817056] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1910; }","duration":"109.56477ms","start":"2026-07-16T06:50:59.937511Z","end":"2026-07-16T06:51:00.047076Z","steps":["trace[286817056] 'agreement among raft nodes before linearized reading' (duration: 109.376616ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:00.047096Z","caller":"traceutil/trace.go:171","msg":"trace[1432169572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1910; }","duration":"203.837183ms","start":"2026-07-16T06:50:59.843247Z","end":"2026-07-16T06:51:00.047084Z","steps":["trace[1432169572] 'agreement among raft nodes before linearized reading' (duration: 203.609807ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:01.856976Z","caller":"traceutil/trace.go:171","msg":"trace[1568225189] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"169.815943ms","start":"2026-07-16T06:51:01.687123Z","end":"2026-07-16T06:51:01.856939Z","steps":["trace[1568225189] 'process raft request' (duration: 169.650631ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:02.440214Z","caller":"traceutil/trace.go:171","msg":"trace[1679905043] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"242.414978ms","start":"2026-07-16T06:51:02.197779Z","end":"2026-07-16T06:51:02.440194Z","steps":["trace[1679905043] 'process raft request' (duration: 242.267755ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:20.677918Z","caller":"traceutil/trace.go:171","msg":"trace[2065860544] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"154.397191ms","start":"2026-07-16T06:51:20.523495Z","end":"2026-07-16T06:51:20.677892Z","steps":["trace[2065860544] 'process raft request' (duration: 154.268818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:51:26.9033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.262554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:566"} {"level":"info","ts":"2026-07-16T06:51:26.903405Z","caller":"traceutil/trace.go:171","msg":"trace[743494040] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2098; }","duration":"696.387177ms","start":"2026-07-16T06:51:26.206986Z","end":"2026-07-16T06:51:26.903374Z","steps":["trace[743494040] 'range keys from in-memory index tree' (duration: 696.069581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:51:26.903456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:51:26.206977Z","time spent":"696.467068ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":589,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-16T06:51:26.903516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.072311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11670"} {"level":"info","ts":"2026-07-16T06:51:26.903605Z","caller":"traceutil/trace.go:171","msg":"trace[2068063783] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2098; }","duration":"410.197553ms","start":"2026-07-16T06:51:26.49339Z","end":"2026-07-16T06:51:26.903587Z","steps":["trace[2068063783] 'range keys from in-memory index tree' (duration: 409.919178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:51:26.903653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:51:26.493364Z","time spent":"410.278544ms","remote":"127.0.0.1:54396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11693,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-16T06:51:26.903762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.829558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-07-16T06:51:26.90392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.709611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:51:26.903899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.323241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:551"} {"level":"info","ts":"2026-07-16T06:51:26.903971Z","caller":"traceutil/trace.go:171","msg":"trace[1339145720] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2098; }","duration":"109.796232ms","start":"2026-07-16T06:51:26.794161Z","end":"2026-07-16T06:51:26.903957Z","steps":["trace[1339145720] 'count revisions from in-memory index tree' (duration: 109.6282ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:26.903939Z","caller":"traceutil/trace.go:171","msg":"trace[1728038073] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2098; }","duration":"690.128743ms","start":"2026-07-16T06:51:26.213779Z","end":"2026-07-16T06:51:26.903908Z","steps":["trace[1728038073] 'range keys from in-memory index tree' (duration: 689.44015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:51:26.90412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:51:26.213768Z","time spent":"690.335157ms","remote":"127.0.0.1:47988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4614,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-16T06:51:26.90404Z","caller":"traceutil/trace.go:171","msg":"trace[994849933] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2098; }","duration":"698.506855ms","start":"2026-07-16T06:51:26.205494Z","end":"2026-07-16T06:51:26.904001Z","steps":["trace[994849933] 'range keys from in-memory index tree' (duration: 698.094858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:51:26.90506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:51:26.205484Z","time spent":"699.541753ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":574,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-16T06:51:26.904008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.973441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c2b2828e644695\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-07-16T06:51:26.905301Z","caller":"traceutil/trace.go:171","msg":"trace[1645939817] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18c2b2828e644695; range_end:; response_count:1; response_revision:2098; }","duration":"701.335297ms","start":"2026-07-16T06:51:26.203944Z","end":"2026-07-16T06:51:26.905279Z","steps":["trace[1645939817] 'range keys from in-memory index tree' (duration: 699.711406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:51:26.905344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:51:26.203926Z","time spent":"701.408007ms","remote":"127.0.0.1:54282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c2b2828e644695\" "} {"level":"warn","ts":"2026-07-16T06:51:26.903952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.087627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-07-16T06:51:26.906014Z","caller":"traceutil/trace.go:171","msg":"trace[160852433] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2098; }","duration":"204.165845ms","start":"2026-07-16T06:51:26.701823Z","end":"2026-07-16T06:51:26.905988Z","steps":["trace[160852433] 'range keys from in-memory index tree' (duration: 201.948294ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:27.552622Z","caller":"traceutil/trace.go:171","msg":"trace[1423502373] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"643.75037ms","start":"2026-07-16T06:51:26.908848Z","end":"2026-07-16T06:51:27.552598Z","steps":["trace[1423502373] 'process raft request' (duration: 643.595027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:51:27.552889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:51:26.908812Z","time spent":"643.880463ms","remote":"127.0.0.1:54282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:51:27.926721Z","caller":"traceutil/trace.go:171","msg":"trace[72149766] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"1.016601538s","start":"2026-07-16T06:51:26.910093Z","end":"2026-07-16T06:51:27.926694Z","steps":["trace[72149766] 'process raft request' (duration: 1.016499016s)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:27.92678Z","caller":"traceutil/trace.go:171","msg":"trace[1024942575] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"1.016972865s","start":"2026-07-16T06:51:26.909784Z","end":"2026-07-16T06:51:27.926756Z","steps":["trace[1024942575] 'process raft request' (duration: 1.016645669s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:51:27.926987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:51:26.909765Z","time spent":"1.017176229s","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":536,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:51:27.92689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:51:26.910065Z","time spent":"1.01673736s","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":551,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:51:27.927401Z","caller":"traceutil/trace.go:171","msg":"trace[371109003] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"1.015715221s","start":"2026-07-16T06:51:26.911668Z","end":"2026-07-16T06:51:27.927383Z","steps":["trace[371109003] 'process raft request' (duration: 1.014980479s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:51:27.927602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:51:26.911651Z","time spent":"1.015894785s","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:51:27.927662Z","caller":"traceutil/trace.go:171","msg":"trace[1298548796] linearizableReadLoop","detail":"{readStateIndex:2172; appliedIndex:2167; }","duration":"709.402949ms","start":"2026-07-16T06:51:27.218233Z","end":"2026-07-16T06:51:27.927636Z","steps":["trace[1298548796] 'read index received' (duration: 334.265219ms)","trace[1298548796] 'applied index is now lower than readState.Index' (duration: 375.12389ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:51:27.927699Z","caller":"traceutil/trace.go:171","msg":"trace[888533430] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"977.217163ms","start":"2026-07-16T06:51:26.950456Z","end":"2026-07-16T06:51:27.927673Z","steps":["trace[888533430] 'process raft request' (duration: 976.672963ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:27.927745Z","caller":"traceutil/trace.go:171","msg":"trace[1594606241] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"661.783354ms","start":"2026-07-16T06:51:27.265944Z","end":"2026-07-16T06:51:27.927727Z","steps":["trace[1594606241] 'process raft request' (duration: 661.555549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:51:27.927832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:51:27.26592Z","time spent":"661.867096ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:51:27.927829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:51:26.950435Z","time spent":"977.328445ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16383,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:51:27.927874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.624693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-16T06:51:27.927908Z","caller":"traceutil/trace.go:171","msg":"trace[1255242368] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2104; }","duration":"709.703893ms","start":"2026-07-16T06:51:27.218194Z","end":"2026-07-16T06:51:27.927898Z","steps":["trace[1255242368] 'agreement among raft nodes before linearized reading' (duration: 709.563111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:51:27.92795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:51:27.218118Z","time spent":"709.823656ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-16T06:51:27.938456Z","caller":"traceutil/trace.go:171","msg":"trace[947677719] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"380.483446ms","start":"2026-07-16T06:51:27.557952Z","end":"2026-07-16T06:51:27.938435Z","steps":["trace[947677719] 'process raft request' (duration: 380.312883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:51:27.938624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.062582ms","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-07-16T06:51:27.938665Z","caller":"traceutil/trace.go:171","msg":"trace[747159093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2105; }","duration":"587.137292ms","start":"2026-07-16T06:51:27.351519Z","end":"2026-07-16T06:51:27.938657Z","steps":["trace[747159093] 'agreement among raft nodes before linearized reading' (duration: 587.00199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:51:27.938688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:51:27.351509Z","time spent":"587.174303ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T06:51:27.93857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.376176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-16T06:51:27.938641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:51:27.55792Z","time spent":"380.668029ms","remote":"127.0.0.1:54282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:51:27.938733Z","caller":"traceutil/trace.go:171","msg":"trace[1213865035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2105; }","duration":"582.57815ms","start":"2026-07-16T06:51:27.356146Z","end":"2026-07-16T06:51:27.938724Z","steps":["trace[1213865035] 'agreement among raft nodes before linearized reading' (duration: 582.331455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:51:27.938777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:51:27.356134Z","time spent":"582.63319ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T06:51:27.939625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.881015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-16T06:51:27.939696Z","caller":"traceutil/trace.go:171","msg":"trace[388210035] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2105; }","duration":"630.982066ms","start":"2026-07-16T06:51:27.308702Z","end":"2026-07-16T06:51:27.939684Z","steps":["trace[388210035] 'agreement among raft nodes before linearized reading' (duration: 629.696973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:51:27.939927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:51:27.308688Z","time spent":"631.229172ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-16T06:52:08.219893Z","caller":"traceutil/trace.go:171","msg":"trace[1599187429] transaction","detail":"{read_only:false; response_revision:2430; number_of_response:1; }","duration":"177.865997ms","start":"2026-07-16T06:52:08.041995Z","end":"2026-07-16T06:52:08.219861Z","steps":["trace[1599187429] 'process raft request' (duration: 177.66594ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:08.22125Z","caller":"traceutil/trace.go:171","msg":"trace[578778181] transaction","detail":"{read_only:false; response_revision:2431; number_of_response:1; }","duration":"153.430088ms","start":"2026-07-16T06:52:08.067809Z","end":"2026-07-16T06:52:08.221239Z","steps":["trace[578778181] 'process raft request' (duration: 153.331634ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:10.35813Z","caller":"traceutil/trace.go:171","msg":"trace[492666871] transaction","detail":"{read_only:false; response_revision:2452; number_of_response:1; }","duration":"117.953421ms","start":"2026-07-16T06:52:10.240152Z","end":"2026-07-16T06:52:10.358106Z","steps":["trace[492666871] 'process raft request' (duration: 20.766302ms)","trace[492666871] 'compare' (duration: 97.015893ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:52:10.358279Z","caller":"traceutil/trace.go:171","msg":"trace[158851994] transaction","detail":"{read_only:false; response_revision:2453; number_of_response:1; }","duration":"118.066526ms","start":"2026-07-16T06:52:10.24016Z","end":"2026-07-16T06:52:10.358226Z","steps":["trace[158851994] 'process raft request' (duration: 117.90774ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:10.512963Z","caller":"traceutil/trace.go:171","msg":"trace[1449169890] transaction","detail":"{read_only:false; response_revision:2455; number_of_response:1; }","duration":"272.679005ms","start":"2026-07-16T06:52:10.240263Z","end":"2026-07-16T06:52:10.512942Z","steps":["trace[1449169890] 'process raft request' (duration: 272.606062ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:10.51301Z","caller":"traceutil/trace.go:171","msg":"trace[584381398] transaction","detail":"{read_only:false; response_revision:2454; number_of_response:1; }","duration":"272.824881ms","start":"2026-07-16T06:52:10.240162Z","end":"2026-07-16T06:52:10.512986Z","steps":["trace[584381398] 'process raft request' (duration: 243.189102ms)","trace[584381398] 'compare' (duration: 29.369589ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:52:10.513131Z","caller":"traceutil/trace.go:171","msg":"trace[1454941679] linearizableReadLoop","detail":"{readStateIndex:2547; appliedIndex:2542; }","duration":"269.348266ms","start":"2026-07-16T06:52:10.243765Z","end":"2026-07-16T06:52:10.513114Z","steps":["trace[1454941679] 'read index received' (duration: 17.222565ms)","trace[1454941679] 'applied index is now lower than readState.Index' (duration: 252.124311ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:52:10.513244Z","caller":"traceutil/trace.go:171","msg":"trace[235465174] transaction","detail":"{read_only:false; response_revision:2456; number_of_response:1; }","duration":"272.897953ms","start":"2026-07-16T06:52:10.240335Z","end":"2026-07-16T06:52:10.513233Z","steps":["trace[235465174] 'process raft request' (duration: 272.575851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:10.513383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.775714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.103\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-16T06:52:10.51341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.630986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-16T06:52:10.513444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.495565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:2426"} {"level":"info","ts":"2026-07-16T06:52:10.513466Z","caller":"traceutil/trace.go:171","msg":"trace[1142250826] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2456; }","duration":"269.691608ms","start":"2026-07-16T06:52:10.243761Z","end":"2026-07-16T06:52:10.513452Z","steps":["trace[1142250826] 'agreement among raft nodes before linearized reading' (duration: 269.546263ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:10.513511Z","caller":"traceutil/trace.go:171","msg":"trace[398911454] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:2456; }","duration":"101.592357ms","start":"2026-07-16T06:52:10.411904Z","end":"2026-07-16T06:52:10.513497Z","steps":["trace[398911454] 'agreement among raft nodes before linearized reading' (duration: 101.375809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:10.513553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.714677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-07-16T06:52:10.513463Z","caller":"traceutil/trace.go:171","msg":"trace[2108149157] range","detail":"{range_begin:/registry/masterleases/199.19.213.103; range_end:; response_count:1; response_revision:2456; }","duration":"266.873738ms","start":"2026-07-16T06:52:10.24657Z","end":"2026-07-16T06:52:10.513443Z","steps":["trace[2108149157] 'agreement among raft nodes before linearized reading' (duration: 266.699001ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:10.513592Z","caller":"traceutil/trace.go:171","msg":"trace[659439677] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2456; }","duration":"109.785279ms","start":"2026-07-16T06:52:10.403795Z","end":"2026-07-16T06:52:10.51358Z","steps":["trace[659439677] 'agreement among raft nodes before linearized reading' (duration: 109.693035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:10.514042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.454539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-07-16T06:52:10.514239Z","caller":"traceutil/trace.go:171","msg":"trace[244489608] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2456; }","duration":"104.667337ms","start":"2026-07-16T06:52:10.409537Z","end":"2026-07-16T06:52:10.514204Z","steps":["trace[244489608] 'agreement among raft nodes before linearized reading' (duration: 103.831067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:16.646304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.61612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-07-16T06:52:16.64639Z","caller":"traceutil/trace.go:171","msg":"trace[319463698] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2540; }","duration":"111.717914ms","start":"2026-07-16T06:52:16.534652Z","end":"2026-07-16T06:52:16.64637Z","steps":["trace[319463698] 'range keys from in-memory index tree' (duration: 111.454335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:16.646517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.527758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-16T06:52:16.646596Z","caller":"traceutil/trace.go:171","msg":"trace[456296167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2540; }","duration":"107.649751ms","start":"2026-07-16T06:52:16.538924Z","end":"2026-07-16T06:52:16.646574Z","steps":["trace[456296167] 'range keys from in-memory index tree' (duration: 107.368622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:16.646985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.189241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-16T06:52:16.647022Z","caller":"traceutil/trace.go:171","msg":"trace[853189161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2540; }","duration":"106.242634ms","start":"2026-07-16T06:52:16.540766Z","end":"2026-07-16T06:52:16.647009Z","steps":["trace[853189161] 'range keys from in-memory index tree' (duration: 106.105307ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:17.700099Z","caller":"traceutil/trace.go:171","msg":"trace[118530949] linearizableReadLoop","detail":"{readStateIndex:2643; appliedIndex:2642; }","duration":"141.703784ms","start":"2026-07-16T06:52:17.558372Z","end":"2026-07-16T06:52:17.700076Z","steps":["trace[118530949] 'read index received' (duration: 141.472426ms)","trace[118530949] 'applied index is now lower than readState.Index' (duration: 230.468µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:52:17.700269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.871909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:52:17.700253Z","caller":"traceutil/trace.go:171","msg":"trace[514444192] transaction","detail":"{read_only:false; response_revision:2549; number_of_response:1; }","duration":"499.324198ms","start":"2026-07-16T06:52:17.200871Z","end":"2026-07-16T06:52:17.700195Z","steps":["trace[514444192] 'process raft request' (duration: 499.070549ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:17.700304Z","caller":"traceutil/trace.go:171","msg":"trace[1515413600] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2549; }","duration":"141.961213ms","start":"2026-07-16T06:52:17.558332Z","end":"2026-07-16T06:52:17.700293Z","steps":["trace[1515413600] 'agreement among raft nodes before linearized reading' (duration: 141.88314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:17.700454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:17.200855Z","time spent":"499.488833ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:52:38.925454Z","caller":"traceutil/trace.go:171","msg":"trace[1238308502] transaction","detail":"{read_only:false; response_revision:2695; number_of_response:1; }","duration":"118.771295ms","start":"2026-07-16T06:52:38.806667Z","end":"2026-07-16T06:52:38.925438Z","steps":["trace[1238308502] 'process raft request' (duration: 118.660352ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:38.932985Z","caller":"traceutil/trace.go:171","msg":"trace[170405220] transaction","detail":"{read_only:false; response_revision:2696; number_of_response:1; }","duration":"120.520092ms","start":"2026-07-16T06:52:38.81245Z","end":"2026-07-16T06:52:38.93297Z","steps":["trace[170405220] 'process raft request' (duration: 120.42174ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:38.933225Z","caller":"traceutil/trace.go:171","msg":"trace[827455581] transaction","detail":"{read_only:false; response_revision:2697; number_of_response:1; }","duration":"120.74175ms","start":"2026-07-16T06:52:38.81245Z","end":"2026-07-16T06:52:38.933191Z","steps":["trace[827455581] 'process raft request' (duration: 120.472061ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:44.491062Z","caller":"traceutil/trace.go:171","msg":"trace[1214353439] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"139.967999ms","start":"2026-07-16T06:52:44.351057Z","end":"2026-07-16T06:52:44.491025Z","steps":["trace[1214353439] 'process raft request' (duration: 139.783413ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:44.775799Z","caller":"traceutil/trace.go:171","msg":"trace[842072705] linearizableReadLoop","detail":"{readStateIndex:2837; appliedIndex:2836; }","duration":"210.195285ms","start":"2026-07-16T06:52:44.565582Z","end":"2026-07-16T06:52:44.775777Z","steps":["trace[842072705] 'read index received' (duration: 210.080541ms)","trace[842072705] 'applied index is now lower than readState.Index' (duration: 113.834µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:52:44.775891Z","caller":"traceutil/trace.go:171","msg":"trace[406096467] transaction","detail":"{read_only:false; response_revision:2733; number_of_response:1; }","duration":"215.591967ms","start":"2026-07-16T06:52:44.560247Z","end":"2026-07-16T06:52:44.775839Z","steps":["trace[406096467] 'process raft request' (duration: 215.392761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:44.776186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.568067ms","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-07-16T06:52:44.77628Z","caller":"traceutil/trace.go:171","msg":"trace[826547193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2733; }","duration":"210.721052ms","start":"2026-07-16T06:52:44.565536Z","end":"2026-07-16T06:52:44.776257Z","steps":["trace[826547193] 'agreement among raft nodes before linearized reading' (duration: 210.34876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:45.128325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.528105ms","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-07-16T06:52:45.128488Z","caller":"traceutil/trace.go:171","msg":"trace[406520389] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2733; }","duration":"178.72613ms","start":"2026-07-16T06:52:44.949736Z","end":"2026-07-16T06:52:45.128462Z","steps":["trace[406520389] 'range keys from in-memory index tree' (duration: 178.475232ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:45.155913Z","caller":"traceutil/trace.go:171","msg":"trace[971080054] linearizableReadLoop","detail":"{readStateIndex:2838; appliedIndex:2837; }","duration":"380.021329ms","start":"2026-07-16T06:52:44.775864Z","end":"2026-07-16T06:52:45.155885Z","steps":["trace[971080054] 'read index received' (duration: 379.844063ms)","trace[971080054] 'applied index is now lower than readState.Index' (duration: 175.726µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:52:45.155951Z","caller":"traceutil/trace.go:171","msg":"trace[1710175396] transaction","detail":"{read_only:false; response_revision:2734; number_of_response:1; }","duration":"570.814746ms","start":"2026-07-16T06:52:44.585113Z","end":"2026-07-16T06:52:45.155928Z","steps":["trace[1710175396] 'process raft request' (duration: 570.610741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:45.156214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:44.585083Z","time spent":"570.958291ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:52:45.156217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.382949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:52:45.15634Z","caller":"traceutil/trace.go:171","msg":"trace[1705679416] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2734; }","duration":"557.536393ms","start":"2026-07-16T06:52:44.598783Z","end":"2026-07-16T06:52:45.15632Z","steps":["trace[1705679416] 'agreement among raft nodes before linearized reading' (duration: 557.217433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:45.156459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:44.598772Z","time spent":"557.640047ms","remote":"127.0.0.1:34948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-16T06:52:45.193841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.978768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:52:45.193913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.187994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-16T06:52:45.19397Z","caller":"traceutil/trace.go:171","msg":"trace[1355296918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2735; }","duration":"236.272037ms","start":"2026-07-16T06:52:44.957682Z","end":"2026-07-16T06:52:45.193954Z","steps":["trace[1355296918] 'agreement among raft nodes before linearized reading' (duration: 235.956447ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:45.193936Z","caller":"traceutil/trace.go:171","msg":"trace[1541654139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2735; }","duration":"350.111623ms","start":"2026-07-16T06:52:44.843802Z","end":"2026-07-16T06:52:45.193913Z","steps":["trace[1541654139] 'agreement among raft nodes before linearized reading' (duration: 349.875195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:45.193876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.012993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"warn","ts":"2026-07-16T06:52:45.194051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:44.843787Z","time spent":"350.248446ms","remote":"127.0.0.1:54192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T06:52:45.194176Z","caller":"traceutil/trace.go:171","msg":"trace[495538094] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2735; }","duration":"238.325772ms","start":"2026-07-16T06:52:44.955792Z","end":"2026-07-16T06:52:45.194117Z","steps":["trace[495538094] 'agreement among raft nodes before linearized reading' (duration: 237.853488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:45.194427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.286963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-16T06:52:45.194511Z","caller":"traceutil/trace.go:171","msg":"trace[1799890962] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2735; }","duration":"396.396737ms","start":"2026-07-16T06:52:44.7981Z","end":"2026-07-16T06:52:45.194497Z","steps":["trace[1799890962] 'agreement among raft nodes before linearized reading' (duration: 396.242791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:45.194585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:44.798083Z","time spent":"396.488519ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T06:52:45.194613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.475264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-16T06:52:45.194679Z","caller":"traceutil/trace.go:171","msg":"trace[452972156] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2735; }","duration":"247.584268ms","start":"2026-07-16T06:52:44.947081Z","end":"2026-07-16T06:52:45.194665Z","steps":["trace[452972156] 'agreement among raft nodes before linearized reading' (duration: 247.425583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:45.194693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.874192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"warn","ts":"2026-07-16T06:52:45.194728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.716925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-07-16T06:52:45.194749Z","caller":"traceutil/trace.go:171","msg":"trace[2058187737] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2735; }","duration":"197.001236ms","start":"2026-07-16T06:52:44.997728Z","end":"2026-07-16T06:52:45.194729Z","steps":["trace[2058187737] 'agreement among raft nodes before linearized reading' (duration: 196.848061ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:45.19478Z","caller":"traceutil/trace.go:171","msg":"trace[322985730] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2735; }","duration":"205.785626ms","start":"2026-07-16T06:52:44.988977Z","end":"2026-07-16T06:52:45.194763Z","steps":["trace[322985730] 'agreement among raft nodes before linearized reading' (duration: 205.689973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:45.194567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.995109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-07-16T06:52:45.194461Z","caller":"traceutil/trace.go:171","msg":"trace[1221827489] transaction","detail":"{read_only:false; response_revision:2735; number_of_response:1; }","duration":"413.738369ms","start":"2026-07-16T06:52:44.780704Z","end":"2026-07-16T06:52:45.194443Z","steps":["trace[1221827489] 'process raft request' (duration: 412.726577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:45.194986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:44.780671Z","time spent":"414.254116ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:52:45.194885Z","caller":"traceutil/trace.go:171","msg":"trace[68670749] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2735; }","duration":"285.37284ms","start":"2026-07-16T06:52:44.909497Z","end":"2026-07-16T06:52:45.19487Z","steps":["trace[68670749] 'agreement among raft nodes before linearized reading' (duration: 284.891805ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:45.595835Z","caller":"traceutil/trace.go:171","msg":"trace[119462307] linearizableReadLoop","detail":"{readStateIndex:2840; appliedIndex:2839; }","duration":"395.065633ms","start":"2026-07-16T06:52:45.200748Z","end":"2026-07-16T06:52:45.595814Z","steps":["trace[119462307] 'read index received' (duration: 394.99833ms)","trace[119462307] 'applied index is now lower than readState.Index' (duration: 66.353µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:52:45.595898Z","caller":"traceutil/trace.go:171","msg":"trace[983212374] transaction","detail":"{read_only:false; number_of_response:0; response_revision:2735; }","duration":"397.931684ms","start":"2026-07-16T06:52:45.197934Z","end":"2026-07-16T06:52:45.595865Z","steps":["trace[983212374] 'process raft request' (duration: 397.80353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:45.596042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.27437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1375"} {"level":"info","ts":"2026-07-16T06:52:45.596082Z","caller":"traceutil/trace.go:171","msg":"trace[1221195304] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2735; }","duration":"395.355353ms","start":"2026-07-16T06:52:45.200716Z","end":"2026-07-16T06:52:45.596071Z","steps":["trace[1221195304] 'agreement among raft nodes before linearized reading' (duration: 395.207298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:45.596115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:45.200705Z","time spent":"395.402335ms","remote":"127.0.0.1:54402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1398,"request content":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" "} {"level":"warn","ts":"2026-07-16T06:52:45.596145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:45.197905Z","time spent":"398.113831ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:52:45.693861Z","caller":"traceutil/trace.go:171","msg":"trace[1131959881] transaction","detail":"{read_only:false; response_revision:2736; number_of_response:1; }","duration":"493.104977ms","start":"2026-07-16T06:52:45.200735Z","end":"2026-07-16T06:52:45.69384Z","steps":["trace[1131959881] 'process raft request' (duration: 492.938472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:45.693995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:45.200719Z","time spent":"493.20451ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:52:45.694251Z","caller":"traceutil/trace.go:171","msg":"trace[71240538] transaction","detail":"{read_only:false; response_revision:2737; number_of_response:1; }","duration":"493.357615ms","start":"2026-07-16T06:52:45.200857Z","end":"2026-07-16T06:52:45.694214Z","steps":["trace[71240538] 'process raft request' (duration: 492.950362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:45.694432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:45.200849Z","time spent":"493.490899ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:52:45.694733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.902122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"warn","ts":"2026-07-16T06:52:45.694761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.390177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c2b293c9ad28e9\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-07-16T06:52:45.694799Z","caller":"traceutil/trace.go:171","msg":"trace[171178108] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c2b293c9ad28e9; range_end:; response_count:1; response_revision:2739; }","duration":"488.46004ms","start":"2026-07-16T06:52:45.206329Z","end":"2026-07-16T06:52:45.694789Z","steps":["trace[171178108] 'agreement among raft nodes before linearized reading' (duration: 488.212092ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:45.694797Z","caller":"traceutil/trace.go:171","msg":"trace[974299386] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2739; }","duration":"493.987345ms","start":"2026-07-16T06:52:45.200789Z","end":"2026-07-16T06:52:45.694777Z","steps":["trace[974299386] 'agreement among raft nodes before linearized reading' (duration: 493.782088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:45.694826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:45.206315Z","time spent":"488.503841ms","remote":"127.0.0.1:54282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18c2b293c9ad28e9\" "} {"level":"warn","ts":"2026-07-16T06:52:45.694841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:45.200783Z","time spent":"494.046807ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":575,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-16T06:52:45.694929Z","caller":"traceutil/trace.go:171","msg":"trace[913397902] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"493.972254ms","start":"2026-07-16T06:52:45.200931Z","end":"2026-07-16T06:52:45.694903Z","steps":["trace[913397902] 'process raft request' (duration: 493.19475ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:45.69506Z","caller":"traceutil/trace.go:171","msg":"trace[804533371] transaction","detail":"{read_only:false; response_revision:2739; number_of_response:1; }","duration":"492.187038ms","start":"2026-07-16T06:52:45.202857Z","end":"2026-07-16T06:52:45.695044Z","steps":["trace[804533371] 'process raft request' (duration: 491.542007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:45.695077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:45.20086Z","time spent":"494.14225ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:52:45.695157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:45.202827Z","time spent":"492.281851ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:52:45.695156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.121319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-07-16T06:52:45.695241Z","caller":"traceutil/trace.go:171","msg":"trace[1298821944] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2739; }","duration":"494.258143ms","start":"2026-07-16T06:52:45.200963Z","end":"2026-07-16T06:52:45.695221Z","steps":["trace[1298821944] 'agreement among raft nodes before linearized reading' (duration: 493.966373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:45.695304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:45.200956Z","time spent":"494.335976ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":590,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-16T06:52:45.704343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.127589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1247"} {"level":"info","ts":"2026-07-16T06:52:45.704383Z","caller":"traceutil/trace.go:171","msg":"trace[137542556] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2740; }","duration":"105.18516ms","start":"2026-07-16T06:52:45.599188Z","end":"2026-07-16T06:52:45.704373Z","steps":["trace[137542556] 'agreement among raft nodes before linearized reading' (duration: 105.057576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:45.704526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.278177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:13485"} {"level":"info","ts":"2026-07-16T06:52:45.704577Z","caller":"traceutil/trace.go:171","msg":"trace[2030657857] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:2740; }","duration":"107.385399ms","start":"2026-07-16T06:52:45.59718Z","end":"2026-07-16T06:52:45.704566Z","steps":["trace[2030657857] 'agreement among raft nodes before linearized reading' (duration: 107.021597ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:56.26289Z","caller":"traceutil/trace.go:171","msg":"trace[627627138] transaction","detail":"{read_only:false; response_revision:2812; number_of_response:1; }","duration":"133.059028ms","start":"2026-07-16T06:52:56.129811Z","end":"2026-07-16T06:52:56.26287Z","steps":["trace[627627138] 'process raft request' (duration: 132.908613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:56.442793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.140954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T06:52:56.44289Z","caller":"traceutil/trace.go:171","msg":"trace[216766718] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2812; }","duration":"124.312309ms","start":"2026-07-16T06:52:56.318557Z","end":"2026-07-16T06:52:56.442869Z","steps":["trace[216766718] 'count revisions from in-memory index tree' (duration: 124.060721ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:12.265317Z","caller":"traceutil/trace.go:171","msg":"trace[1175565070] linearizableReadLoop","detail":"{readStateIndex:4598; appliedIndex:4597; }","duration":"156.679995ms","start":"2026-07-16T06:55:12.1086Z","end":"2026-07-16T06:55:12.26528Z","steps":["trace[1175565070] 'read index received' (duration: 93.149731ms)","trace[1175565070] 'applied index is now lower than readState.Index' (duration: 63.528874ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:55:12.266165Z","caller":"traceutil/trace.go:171","msg":"trace[1453358555] transaction","detail":"{read_only:false; response_revision:4408; number_of_response:1; }","duration":"158.070037ms","start":"2026-07-16T06:55:12.10808Z","end":"2026-07-16T06:55:12.26615Z","steps":["trace[1453358555] 'process raft request' (duration: 93.700523ms)","trace[1453358555] 'compare' (duration: 63.333779ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:55:12.266396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.868903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-07-16T06:55:12.2665Z","caller":"traceutil/trace.go:171","msg":"trace[731501817] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4408; }","duration":"157.994856ms","start":"2026-07-16T06:55:12.108491Z","end":"2026-07-16T06:55:12.266486Z","steps":["trace[731501817] 'agreement among raft nodes before linearized reading' (duration: 157.74412ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:12.293675Z","caller":"traceutil/trace.go:171","msg":"trace[871107201] transaction","detail":"{read_only:false; response_revision:4409; number_of_response:1; }","duration":"141.968253ms","start":"2026-07-16T06:55:12.151643Z","end":"2026-07-16T06:55:12.293612Z","steps":["trace[871107201] 'process raft request' (duration: 141.720138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:12.293753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.003934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:55:12.294943Z","caller":"traceutil/trace.go:171","msg":"trace[1938719125] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4409; }","duration":"168.281863ms","start":"2026-07-16T06:55:12.126631Z","end":"2026-07-16T06:55:12.294913Z","steps":["trace[1938719125] 'agreement among raft nodes before linearized reading' (duration: 166.973863ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:12.444698Z","caller":"traceutil/trace.go:171","msg":"trace[688825972] linearizableReadLoop","detail":"{readStateIndex:4600; appliedIndex:4599; }","duration":"140.848298ms","start":"2026-07-16T06:55:12.303829Z","end":"2026-07-16T06:55:12.444677Z","steps":["trace[688825972] 'read index received' (duration: 140.632053ms)","trace[688825972] 'applied index is now lower than readState.Index' (duration: 215.345µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:55:12.444827Z","caller":"traceutil/trace.go:171","msg":"trace[2123086480] transaction","detail":"{read_only:false; response_revision:4410; number_of_response:1; }","duration":"141.589795ms","start":"2026-07-16T06:55:12.303195Z","end":"2026-07-16T06:55:12.444785Z","steps":["trace[2123086480] 'process raft request' (duration: 141.341479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:12.445061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.802223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4849"} {"level":"info","ts":"2026-07-16T06:55:12.44512Z","caller":"traceutil/trace.go:171","msg":"trace[1617106897] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4410; }","duration":"136.990918ms","start":"2026-07-16T06:55:12.308113Z","end":"2026-07-16T06:55:12.445104Z","steps":["trace[1617106897] 'agreement among raft nodes before linearized reading' (duration: 136.702361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:12.445081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.202795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-07-16T06:55:12.445193Z","caller":"traceutil/trace.go:171","msg":"trace[825393136] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4410; }","duration":"141.343508ms","start":"2026-07-16T06:55:12.303822Z","end":"2026-07-16T06:55:12.445166Z","steps":["trace[825393136] 'agreement among raft nodes before linearized reading' (duration: 140.98668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:12.445306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.003902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"warn","ts":"2026-07-16T06:55:12.445424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.872805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T06:55:12.445469Z","caller":"traceutil/trace.go:171","msg":"trace[1304847956] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:4410; }","duration":"114.922975ms","start":"2026-07-16T06:55:12.330536Z","end":"2026-07-16T06:55:12.445459Z","steps":["trace[1304847956] 'agreement among raft nodes before linearized reading' (duration: 114.826273ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:12.445507Z","caller":"traceutil/trace.go:171","msg":"trace[1940199876] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4410; }","duration":"107.142825ms","start":"2026-07-16T06:55:12.338253Z","end":"2026-07-16T06:55:12.445396Z","steps":["trace[1940199876] 'agreement among raft nodes before linearized reading' (duration: 106.761616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:12.738008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.611185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:55:12.738079Z","caller":"traceutil/trace.go:171","msg":"trace[2101709282] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:4414; }","duration":"219.738277ms","start":"2026-07-16T06:55:12.518326Z","end":"2026-07-16T06:55:12.738064Z","steps":["trace[2101709282] 'count revisions from in-memory index tree' (duration: 219.538393ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:12.741861Z","caller":"traceutil/trace.go:171","msg":"trace[1706466133] transaction","detail":"{read_only:false; response_revision:4415; number_of_response:1; }","duration":"213.228256ms","start":"2026-07-16T06:55:12.528596Z","end":"2026-07-16T06:55:12.741824Z","steps":["trace[1706466133] 'process raft request' (duration: 212.95533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:19.507861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.688525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-697618e5-906a-4353-9a2c-32f6ef974a1c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-16T06:55:19.507954Z","caller":"traceutil/trace.go:171","msg":"trace[1173889703] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-697618e5-906a-4353-9a2c-32f6ef974a1c; range_end:; response_count:1; response_revision:4525; }","duration":"492.821699ms","start":"2026-07-16T06:55:19.015111Z","end":"2026-07-16T06:55:19.507933Z","steps":["trace[1173889703] 'range keys from in-memory index tree' (duration: 492.501091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:19.507961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.072245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-697618e5-906a-4353-9a2c-32f6ef974a1c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-16T06:55:19.508057Z","caller":"traceutil/trace.go:171","msg":"trace[1086858754] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-697618e5-906a-4353-9a2c-32f6ef974a1c; range_end:; response_count:1; response_revision:4525; }","duration":"590.178678ms","start":"2026-07-16T06:55:18.917852Z","end":"2026-07-16T06:55:19.50803Z","steps":["trace[1086858754] 'range keys from in-memory index tree' (duration: 589.871052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:19.508068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.745188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:55:19.50812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:18.917785Z","time spent":"590.320011ms","remote":"127.0.0.1:54396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-697618e5-906a-4353-9a2c-32f6ef974a1c\" "} {"level":"info","ts":"2026-07-16T06:55:19.508151Z","caller":"traceutil/trace.go:171","msg":"trace[660742867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4525; }","duration":"665.8641ms","start":"2026-07-16T06:55:18.842268Z","end":"2026-07-16T06:55:19.508132Z","steps":["trace[660742867] 'range keys from in-memory index tree' (duration: 665.592883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:19.508198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:18.842253Z","time spent":"665.936731ms","remote":"127.0.0.1:54192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T06:55:19.507995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:19.015095Z","time spent":"492.890919ms","remote":"127.0.0.1:54396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-697618e5-906a-4353-9a2c-32f6ef974a1c\" "} {"level":"warn","ts":"2026-07-16T06:55:19.508157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.409391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-75cfb965df-675dg.18c2b2badb5eee31\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-07-16T06:55:19.508415Z","caller":"traceutil/trace.go:171","msg":"trace[438997308] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-75cfb965df-675dg.18c2b2badb5eee31; range_end:; response_count:1; response_revision:4525; }","duration":"489.704406ms","start":"2026-07-16T06:55:19.018698Z","end":"2026-07-16T06:55:19.508402Z","steps":["trace[438997308] 'range keys from in-memory index tree' (duration: 489.215236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:19.508475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.945687ms","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":"warn","ts":"2026-07-16T06:55:19.50853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:19.018677Z","time spent":"489.816999ms","remote":"127.0.0.1:54282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":921,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-75cfb965df-675dg.18c2b2badb5eee31\" "} {"level":"info","ts":"2026-07-16T06:55:19.508548Z","caller":"traceutil/trace.go:171","msg":"trace[643701426] 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:4525; }","duration":"430.982047ms","start":"2026-07-16T06:55:19.077518Z","end":"2026-07-16T06:55:19.5085Z","steps":["trace[643701426] 'range keys from in-memory index tree' (duration: 430.766542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:19.508583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:19.077499Z","time spent":"431.076789ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-07-16T06:55:19.50994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.392356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:55:19.510399Z","caller":"traceutil/trace.go:171","msg":"trace[395907945] transaction","detail":"{read_only:false; response_revision:4526; number_of_response:1; }","duration":"416.996155ms","start":"2026-07-16T06:55:19.093335Z","end":"2026-07-16T06:55:19.510331Z","steps":["trace[395907945] 'process raft request' (duration: 225.656147ms)","trace[395907945] 'compare' (duration: 189.626509ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:55:19.510553Z","caller":"traceutil/trace.go:171","msg":"trace[1764687080] linearizableReadLoop","detail":"{readStateIndex:4719; appliedIndex:4718; }","duration":"223.982858ms","start":"2026-07-16T06:55:19.286551Z","end":"2026-07-16T06:55:19.510534Z","steps":["trace[1764687080] 'read index received' (duration: 32.356494ms)","trace[1764687080] 'applied index is now lower than readState.Index' (duration: 191.623714ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:55:19.510919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:19.093314Z","time spent":"417.312573ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:55:19.51122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.670064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-16T06:55:19.511273Z","caller":"traceutil/trace.go:171","msg":"trace[1445136804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4526; }","duration":"224.850738ms","start":"2026-07-16T06:55:19.286409Z","end":"2026-07-16T06:55:19.51126Z","steps":["trace[1445136804] 'agreement among raft nodes before linearized reading' (duration: 224.455959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:20.068156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.736436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-07-16T06:55:20.068307Z","caller":"traceutil/trace.go:171","msg":"trace[415158774] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4535; }","duration":"315.897419ms","start":"2026-07-16T06:55:19.752391Z","end":"2026-07-16T06:55:20.068288Z","steps":["trace[415158774] 'range keys from in-memory index tree' (duration: 315.565172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:20.068376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:19.752275Z","time spent":"316.087744ms","remote":"127.0.0.1:54308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-16T06:55:20.068492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.997993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-07-16T06:55:20.068664Z","caller":"traceutil/trace.go:171","msg":"trace[1567455395] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4535; }","duration":"316.188676ms","start":"2026-07-16T06:55:19.752447Z","end":"2026-07-16T06:55:20.068636Z","steps":["trace[1567455395] 'range keys from in-memory index tree' (duration: 315.846058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:20.068731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:19.75244Z","time spent":"316.273978ms","remote":"127.0.0.1:54308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-16T06:55:20.126176Z","caller":"traceutil/trace.go:171","msg":"trace[617114689] linearizableReadLoop","detail":"{readStateIndex:4729; appliedIndex:4728; }","duration":"283.007522ms","start":"2026-07-16T06:55:19.843145Z","end":"2026-07-16T06:55:20.126152Z","steps":["trace[617114689] 'read index received' (duration: 282.87725ms)","trace[617114689] 'applied index is now lower than readState.Index' (duration: 126.792µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:55:20.126494Z","caller":"traceutil/trace.go:171","msg":"trace[853160859] transaction","detail":"{read_only:false; response_revision:4536; number_of_response:1; }","duration":"351.687451ms","start":"2026-07-16T06:55:19.774749Z","end":"2026-07-16T06:55:20.126436Z","steps":["trace[853160859] 'process raft request' (duration: 351.233631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:20.126626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:19.77473Z","time spent":"351.807784ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:55:20.126747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.600556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:55:20.127173Z","caller":"traceutil/trace.go:171","msg":"trace[219216821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4536; }","duration":"284.073987ms","start":"2026-07-16T06:55:19.843082Z","end":"2026-07-16T06:55:20.127156Z","steps":["trace[219216821] 'agreement among raft nodes before linearized reading' (duration: 283.613706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:20.1269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.37418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3198"} {"level":"info","ts":"2026-07-16T06:55:20.127408Z","caller":"traceutil/trace.go:171","msg":"trace[860756397] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4536; }","duration":"243.897243ms","start":"2026-07-16T06:55:19.883474Z","end":"2026-07-16T06:55:20.127371Z","steps":["trace[860756397] 'agreement among raft nodes before linearized reading' (duration: 243.34302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:20.126984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.282779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-75cfb965df-675dg.18c2b2badb5eee31\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-07-16T06:55:20.127543Z","caller":"traceutil/trace.go:171","msg":"trace[277856144] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-75cfb965df-675dg.18c2b2badb5eee31; range_end:; response_count:1; response_revision:4536; }","duration":"102.868501ms","start":"2026-07-16T06:55:20.024655Z","end":"2026-07-16T06:55:20.127524Z","steps":["trace[277856144] 'agreement among raft nodes before linearized reading' (duration: 102.272298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:20.127046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.12217ms","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-07-16T06:55:20.127705Z","caller":"traceutil/trace.go:171","msg":"trace[108854584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4536; }","duration":"104.811976ms","start":"2026-07-16T06:55:20.022883Z","end":"2026-07-16T06:55:20.127695Z","steps":["trace[108854584] 'agreement among raft nodes before linearized reading' (duration: 104.11755ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:20.281548Z","caller":"traceutil/trace.go:171","msg":"trace[295171405] transaction","detail":"{read_only:false; response_revision:4537; number_of_response:1; }","duration":"148.5419ms","start":"2026-07-16T06:55:20.132994Z","end":"2026-07-16T06:55:20.281536Z","steps":["trace[295171405] 'process raft request' (duration: 96.313251ms)","trace[295171405] 'compare' (duration: 51.8379ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:55:20.281947Z","caller":"traceutil/trace.go:171","msg":"trace[891973108] linearizableReadLoop","detail":"{readStateIndex:4731; appliedIndex:4729; }","duration":"146.704168ms","start":"2026-07-16T06:55:20.135106Z","end":"2026-07-16T06:55:20.28181Z","steps":["trace[891973108] 'read index received' (duration: 94.335126ms)","trace[891973108] 'applied index is now lower than readState.Index' (duration: 52.367612ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:55:20.282014Z","caller":"traceutil/trace.go:171","msg":"trace[1253127845] transaction","detail":"{read_only:false; response_revision:4540; number_of_response:1; }","duration":"146.749078ms","start":"2026-07-16T06:55:20.135234Z","end":"2026-07-16T06:55:20.281983Z","steps":["trace[1253127845] 'process raft request' (duration: 146.236347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:20.282093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.970564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:55:20.282153Z","caller":"traceutil/trace.go:171","msg":"trace[705907770] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:4540; }","duration":"147.057087ms","start":"2026-07-16T06:55:20.135079Z","end":"2026-07-16T06:55:20.282136Z","steps":["trace[705907770] 'agreement among raft nodes before linearized reading' (duration: 146.963295ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:20.281573Z","caller":"traceutil/trace.go:171","msg":"trace[1981253637] transaction","detail":"{read_only:false; response_revision:4538; number_of_response:1; }","duration":"147.999248ms","start":"2026-07-16T06:55:20.133523Z","end":"2026-07-16T06:55:20.281522Z","steps":["trace[1981253637] 'process raft request' (duration: 147.888485ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:20.282899Z","caller":"traceutil/trace.go:171","msg":"trace[1938397264] transaction","detail":"{read_only:false; response_revision:4539; number_of_response:1; }","duration":"146.37806ms","start":"2026-07-16T06:55:20.135115Z","end":"2026-07-16T06:55:20.281493Z","steps":["trace[1938397264] 'process raft request' (duration: 146.330899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:20.692994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.417914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-07-16T06:55:20.694227Z","caller":"traceutil/trace.go:171","msg":"trace[1946362505] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4541; }","duration":"409.108393ms","start":"2026-07-16T06:55:20.285094Z","end":"2026-07-16T06:55:20.694203Z","steps":["trace[1946362505] 'process raft request' (duration: 179.408949ms)","trace[1946362505] 'compare' (duration: 228.374834ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:55:20.694328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:20.285077Z","time spent":"409.201145ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:55:20.694561Z","caller":"traceutil/trace.go:171","msg":"trace[679278144] linearizableReadLoop","detail":"{readStateIndex:4735; appliedIndex:4734; }","duration":"407.128226ms","start":"2026-07-16T06:55:20.287409Z","end":"2026-07-16T06:55:20.694537Z","steps":["trace[679278144] 'read index received' (duration: 177.032444ms)","trace[679278144] 'applied index is now lower than readState.Index' (duration: 230.092892ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:55:20.694704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.532999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-07-16T06:55:20.694702Z","caller":"traceutil/trace.go:171","msg":"trace[1202085540] transaction","detail":"{read_only:false; response_revision:4542; number_of_response:1; }","duration":"224.762681ms","start":"2026-07-16T06:55:20.469927Z","end":"2026-07-16T06:55:20.69469Z","steps":["trace[1202085540] 'process raft request' (duration: 224.515625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:20.694803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:20.288316Z","time spent":"406.485923ms","remote":"127.0.0.1:54234","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-16T06:55:20.694771Z","caller":"traceutil/trace.go:171","msg":"trace[199519369] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4541; }","duration":"408.624351ms","start":"2026-07-16T06:55:20.286137Z","end":"2026-07-16T06:55:20.694762Z","steps":["trace[199519369] 'agreement among raft nodes before linearized reading' (duration: 408.473678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:20.695032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.931309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-07-16T06:55:20.695081Z","caller":"traceutil/trace.go:171","msg":"trace[792363387] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4542; }","duration":"409.00964ms","start":"2026-07-16T06:55:20.286058Z","end":"2026-07-16T06:55:20.695068Z","steps":["trace[792363387] 'agreement among raft nodes before linearized reading' (duration: 408.669783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:20.695335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.802515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-16T06:55:20.695368Z","caller":"traceutil/trace.go:171","msg":"trace[1261229533] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4542; }","duration":"185.862466ms","start":"2026-07-16T06:55:20.509498Z","end":"2026-07-16T06:55:20.69536Z","steps":["trace[1261229533] 'agreement among raft nodes before linearized reading' (duration: 185.765214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:20.695572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.594237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-07-16T06:55:20.695599Z","caller":"traceutil/trace.go:171","msg":"trace[1395664031] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4542; }","duration":"410.652718ms","start":"2026-07-16T06:55:20.284938Z","end":"2026-07-16T06:55:20.695591Z","steps":["trace[1395664031] 'agreement among raft nodes before linearized reading' (duration: 410.567495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:20.695621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:20.284923Z","time spent":"410.692698ms","remote":"127.0.0.1:54402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1288,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" "} {"level":"warn","ts":"2026-07-16T06:55:20.695776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.735061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-16T06:55:20.695799Z","caller":"traceutil/trace.go:171","msg":"trace[109929590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4542; }","duration":"177.785971ms","start":"2026-07-16T06:55:20.518006Z","end":"2026-07-16T06:55:20.695792Z","steps":["trace[109929590] 'agreement among raft nodes before linearized reading' (duration: 177.716919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:20.695952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.106449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-16T06:55:20.695977Z","caller":"traceutil/trace.go:171","msg":"trace[1880081672] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4542; }","duration":"178.15133ms","start":"2026-07-16T06:55:20.517818Z","end":"2026-07-16T06:55:20.695969Z","steps":["trace[1880081672] 'agreement among raft nodes before linearized reading' (duration: 178.080369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:20.696163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.907926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-697618e5-906a-4353-9a2c-32f6ef974a1c\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-07-16T06:55:20.696203Z","caller":"traceutil/trace.go:171","msg":"trace[1346053207] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-697618e5-906a-4353-9a2c-32f6ef974a1c; range_end:; response_count:1; response_revision:4542; }","duration":"181.967467ms","start":"2026-07-16T06:55:20.514223Z","end":"2026-07-16T06:55:20.69619Z","steps":["trace[1346053207] 'agreement among raft nodes before linearized reading' (duration: 181.854864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:20.696294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.658382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-16T06:55:20.696323Z","caller":"traceutil/trace.go:171","msg":"trace[793801023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4542; }","duration":"178.724923ms","start":"2026-07-16T06:55:20.517591Z","end":"2026-07-16T06:55:20.696316Z","steps":["trace[793801023] 'agreement among raft nodes before linearized reading' (duration: 178.643971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:20.695037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:20.286127Z","time spent":"408.895598ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":591,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-16T06:55:20.695828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:20.28605Z","time spent":"409.758908ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-16T06:55:24.863079Z","caller":"traceutil/trace.go:171","msg":"trace[1297360619] transaction","detail":"{read_only:false; response_revision:4609; number_of_response:1; }","duration":"108.833497ms","start":"2026-07-16T06:55:24.754216Z","end":"2026-07-16T06:55:24.86305Z","steps":["trace[1297360619] 'process raft request' (duration: 95.507532ms)","trace[1297360619] 'compare' (duration: 12.936645ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:55:24.863378Z","caller":"traceutil/trace.go:171","msg":"trace[1676215659] transaction","detail":"{read_only:false; response_revision:4610; number_of_response:1; }","duration":"108.402027ms","start":"2026-07-16T06:55:24.75494Z","end":"2026-07-16T06:55:24.863342Z","steps":["trace[1676215659] 'process raft request' (duration: 108.028757ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:28.248257Z","caller":"traceutil/trace.go:171","msg":"trace[1555038440] linearizableReadLoop","detail":"{readStateIndex:4872; appliedIndex:4871; }","duration":"114.030872ms","start":"2026-07-16T06:55:28.134201Z","end":"2026-07-16T06:55:28.248231Z","steps":["trace[1555038440] 'read index received' (duration: 113.776797ms)","trace[1555038440] 'applied index is now lower than readState.Index' (duration: 252.605µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:55:28.248292Z","caller":"traceutil/trace.go:171","msg":"trace[1584157991] transaction","detail":"{read_only:false; response_revision:4671; number_of_response:1; }","duration":"115.113467ms","start":"2026-07-16T06:55:28.133154Z","end":"2026-07-16T06:55:28.248268Z","steps":["trace[1584157991] 'process raft request' (duration: 114.910012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:28.248577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.36094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-07-16T06:55:28.248661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.952606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-07-16T06:55:28.248662Z","caller":"traceutil/trace.go:171","msg":"trace[676932105] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4671; }","duration":"114.485533ms","start":"2026-07-16T06:55:28.134165Z","end":"2026-07-16T06:55:28.24865Z","steps":["trace[676932105] 'agreement among raft nodes before linearized reading' (duration: 114.188166ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:28.248691Z","caller":"traceutil/trace.go:171","msg":"trace[1902288569] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4671; }","duration":"112.025986ms","start":"2026-07-16T06:55:28.136657Z","end":"2026-07-16T06:55:28.248683Z","steps":["trace[1902288569] 'agreement among raft nodes before linearized reading' (duration: 111.769941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:32.234746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.56063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:55:32.234819Z","caller":"traceutil/trace.go:171","msg":"trace[1255994064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4719; }","duration":"390.683333ms","start":"2026-07-16T06:55:31.844119Z","end":"2026-07-16T06:55:32.234802Z","steps":["trace[1255994064] 'range keys from in-memory index tree' (duration: 390.472078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:32.234818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.197282ms","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":"warn","ts":"2026-07-16T06:55:32.23485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:31.8441Z","time spent":"390.742784ms","remote":"127.0.0.1:54192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T06:55:32.234872Z","caller":"traceutil/trace.go:171","msg":"trace[471176168] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:4719; }","duration":"228.285755ms","start":"2026-07-16T06:55:32.006575Z","end":"2026-07-16T06:55:32.234861Z","steps":["trace[471176168] 'range keys from in-memory index tree' (duration: 228.050819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:32.235271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.188262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:55:32.235311Z","caller":"traceutil/trace.go:171","msg":"trace[2059339090] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:4719; }","duration":"345.269054ms","start":"2026-07-16T06:55:31.890032Z","end":"2026-07-16T06:55:32.235301Z","steps":["trace[2059339090] 'count revisions from in-memory index tree' (duration: 345.044569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:32.23534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:31.890013Z","time spent":"345.316496ms","remote":"127.0.0.1:54578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-07-16T06:55:32.749744Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2396934702950839614,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-16T06:55:32.830797Z","caller":"traceutil/trace.go:171","msg":"trace[1361663866] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"582.157311ms","start":"2026-07-16T06:55:32.24862Z","end":"2026-07-16T06:55:32.830777Z","steps":["trace[1361663866] 'process raft request' (duration: 582.035049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:32.830924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:32.248603Z","time spent":"582.256292ms","remote":"127.0.0.1:54396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10788,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:55:32.898511Z","caller":"traceutil/trace.go:171","msg":"trace[1324680591] linearizableReadLoop","detail":"{readStateIndex:4926; appliedIndex:4922; }","duration":"649.218867ms","start":"2026-07-16T06:55:32.249271Z","end":"2026-07-16T06:55:32.89849Z","steps":["trace[1324680591] 'read index received' (duration: 581.416924ms)","trace[1324680591] 'applied index is now lower than readState.Index' (duration: 67.801003ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:55:32.898583Z","caller":"traceutil/trace.go:171","msg":"trace[88419464] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"647.850086ms","start":"2026-07-16T06:55:32.250726Z","end":"2026-07-16T06:55:32.898576Z","steps":["trace[88419464] 'process raft request' (duration: 647.736814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:32.89867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:32.250716Z","time spent":"647.883588ms","remote":"127.0.0.1:54522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1622,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:55:32.898657Z","caller":"traceutil/trace.go:171","msg":"trace[566444531] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"648.811429ms","start":"2026-07-16T06:55:32.249793Z","end":"2026-07-16T06:55:32.898604Z","steps":["trace[566444531] 'process raft request' (duration: 648.650076ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:32.898765Z","caller":"traceutil/trace.go:171","msg":"trace[226645597] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"649.538485ms","start":"2026-07-16T06:55:32.249218Z","end":"2026-07-16T06:55:32.898757Z","steps":["trace[226645597] 'process raft request' (duration: 649.098165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:32.898793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:32.2492Z","time spent":"649.579616ms","remote":"127.0.0.1:54522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1414,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:55:32.89884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:32.249782Z","time spent":"648.963733ms","remote":"127.0.0.1:54522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1606,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:55:32.898897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.557977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:55:32.899015Z","caller":"traceutil/trace.go:171","msg":"trace[602036127] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:4723; }","duration":"649.72754ms","start":"2026-07-16T06:55:32.249252Z","end":"2026-07-16T06:55:32.89898Z","steps":["trace[602036127] 'agreement among raft nodes before linearized reading' (duration: 649.428743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:32.899093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:32.249245Z","time spent":"649.817752ms","remote":"127.0.0.1:49954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"info","ts":"2026-07-16T06:55:32.951195Z","caller":"traceutil/trace.go:171","msg":"trace[1406637407] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"115.028644ms","start":"2026-07-16T06:55:32.836139Z","end":"2026-07-16T06:55:32.951168Z","steps":["trace[1406637407] 'process raft request' (duration: 114.830859ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:32.951266Z","caller":"traceutil/trace.go:171","msg":"trace[708257445] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"113.319714ms","start":"2026-07-16T06:55:32.83793Z","end":"2026-07-16T06:55:32.95125Z","steps":["trace[708257445] 'process raft request' (duration: 113.236723ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:32.951384Z","caller":"traceutil/trace.go:171","msg":"trace[105922285] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"113.59952ms","start":"2026-07-16T06:55:32.837678Z","end":"2026-07-16T06:55:32.951278Z","steps":["trace[105922285] 'process raft request' (duration: 113.444256ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:32.951218Z","caller":"traceutil/trace.go:171","msg":"trace[2139839296] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"114.698055ms","start":"2026-07-16T06:55:32.836493Z","end":"2026-07-16T06:55:32.951191Z","steps":["trace[2139839296] 'process raft request' (duration: 114.590133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:32.951752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.592569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-16T06:55:32.951797Z","caller":"traceutil/trace.go:171","msg":"trace[944374005] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4728; }","duration":"174.687712ms","start":"2026-07-16T06:55:32.777102Z","end":"2026-07-16T06:55:32.95179Z","steps":["trace[944374005] 'agreement among raft nodes before linearized reading' (duration: 174.537849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:32.951813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.671201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-16T06:55:32.951892Z","caller":"traceutil/trace.go:171","msg":"trace[1951816905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4728; }","duration":"197.796785ms","start":"2026-07-16T06:55:32.754078Z","end":"2026-07-16T06:55:32.951875Z","steps":["trace[1951816905] 'agreement among raft nodes before linearized reading' (duration: 197.59042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:32.952036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.310691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-16T06:55:32.952078Z","caller":"traceutil/trace.go:171","msg":"trace[309594840] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4728; }","duration":"173.376963ms","start":"2026-07-16T06:55:32.778695Z","end":"2026-07-16T06:55:32.952072Z","steps":["trace[309594840] 'agreement among raft nodes before linearized reading' (duration: 173.301261ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:32.952149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.563308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3198"} {"level":"info","ts":"2026-07-16T06:55:32.95219Z","caller":"traceutil/trace.go:171","msg":"trace[421210207] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4728; }","duration":"333.639788ms","start":"2026-07-16T06:55:32.618539Z","end":"2026-07-16T06:55:32.952179Z","steps":["trace[421210207] 'agreement among raft nodes before linearized reading' (duration: 333.530876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:32.952224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:32.618518Z","time spent":"333.697569ms","remote":"127.0.0.1:47776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3221,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-07-16T06:55:32.952242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.672228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-16T06:55:32.952292Z","caller":"traceutil/trace.go:171","msg":"trace[102221087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4728; }","duration":"173.742841ms","start":"2026-07-16T06:55:32.778543Z","end":"2026-07-16T06:55:32.952286Z","steps":["trace[102221087] 'agreement among raft nodes before linearized reading' (duration: 173.609558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:32.952684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.605465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-16T06:55:32.95274Z","caller":"traceutil/trace.go:171","msg":"trace[4131514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4728; }","duration":"572.704447ms","start":"2026-07-16T06:55:32.380021Z","end":"2026-07-16T06:55:32.952726Z","steps":["trace[4131514] 'agreement among raft nodes before linearized reading' (duration: 572.076873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:32.953057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:32.380002Z","time spent":"573.038685ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-16T06:55:33.202157Z","caller":"traceutil/trace.go:171","msg":"trace[1481950473] linearizableReadLoop","detail":"{readStateIndex:4941; appliedIndex:4939; }","duration":"187.970348ms","start":"2026-07-16T06:55:33.014163Z","end":"2026-07-16T06:55:33.202134Z","steps":["trace[1481950473] 'read index received' (duration: 163.520326ms)","trace[1481950473] 'applied index is now lower than readState.Index' (duration: 24.448852ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:55:33.202295Z","caller":"traceutil/trace.go:171","msg":"trace[558535801] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"192.144252ms","start":"2026-07-16T06:55:33.010133Z","end":"2026-07-16T06:55:33.202278Z","steps":["trace[558535801] 'process raft request' (duration: 191.922047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:33.202504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.317216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-alertmanager.18c2b2b7634c5af5\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-07-16T06:55:33.202555Z","caller":"traceutil/trace.go:171","msg":"trace[1518369185] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-alertmanager.18c2b2b7634c5af5; range_end:; response_count:1; response_revision:4738; }","duration":"188.409048ms","start":"2026-07-16T06:55:33.014134Z","end":"2026-07-16T06:55:33.202543Z","steps":["trace[1518369185] 'agreement among raft nodes before linearized reading' (duration: 188.171863ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:33.202236Z","caller":"traceutil/trace.go:171","msg":"trace[1435083921] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"194.374132ms","start":"2026-07-16T06:55:33.007829Z","end":"2026-07-16T06:55:33.202203Z","steps":["trace[1435083921] 'process raft request' (duration: 169.95129ms)","trace[1435083921] 'compare' (duration: 24.109475ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:55:57.205387Z","caller":"traceutil/trace.go:171","msg":"trace[1364940656] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"111.870844ms","start":"2026-07-16T06:55:57.093488Z","end":"2026-07-16T06:55:57.205359Z","steps":["trace[1364940656] 'process raft request' (duration: 110.792401ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:57.205609Z","caller":"traceutil/trace.go:171","msg":"trace[1098557449] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"112.064037ms","start":"2026-07-16T06:55:57.093528Z","end":"2026-07-16T06:55:57.205592Z","steps":["trace[1098557449] 'process raft request' (duration: 111.75356ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:57.205897Z","caller":"traceutil/trace.go:171","msg":"trace[609854491] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"112.309443ms","start":"2026-07-16T06:55:57.093577Z","end":"2026-07-16T06:55:57.205886Z","steps":["trace[609854491] 'process raft request' (duration: 111.950255ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:57.206213Z","caller":"traceutil/trace.go:171","msg":"trace[937568703] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"107.530228ms","start":"2026-07-16T06:55:57.098669Z","end":"2026-07-16T06:55:57.206199Z","steps":["trace[937568703] 'process raft request' (duration: 107.124469ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:57.206407Z","caller":"traceutil/trace.go:171","msg":"trace[559748020] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"105.180735ms","start":"2026-07-16T06:55:57.101183Z","end":"2026-07-16T06:55:57.206364Z","steps":["trace[559748020] 'process raft request' (duration: 104.841598ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:58:37.348276Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3176} {"level":"info","ts":"2026-07-16T06:58:37.440598Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3176,"took":"90.81144ms","hash":2855404300,"current-db-size-bytes":57892864,"current-db-size":"58 MB","current-db-size-in-use-bytes":31465472,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-16T06:58:37.440659Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2855404300,"revision":3176,"compact-revision":-1} {"level":"info","ts":"2026-07-16T06:59:21.044909Z","caller":"traceutil/trace.go:171","msg":"trace[1874756667] transaction","detail":"{read_only:false; response_revision:6739; number_of_response:1; }","duration":"130.385244ms","start":"2026-07-16T06:59:20.914499Z","end":"2026-07-16T06:59:21.044884Z","steps":["trace[1874756667] 'process raft request' (duration: 130.256671ms)"],"step_count":1}