{"level":"warn","ts":"2026-07-02T05:06:10.641733Z","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-02T05:06:10.641839Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.85: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.85:2380","--initial-cluster=instance=https://199.19.213.85:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.85:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.85: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-02T05:06:10.642062Z","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-02T05:06:10.642083Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.85:2380"]} {"level":"info","ts":"2026-07-02T05:06:10.642147Z","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-02T05:06:10.643225Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.85:2379"]} {"level":"info","ts":"2026-07-02T05:06:10.643393Z","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.85:2380"],"listen-peer-urls":["https://199.19.213.85:2380"],"advertise-client-urls":["https://199.19.213.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.85: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-02T05:06:10.650604Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.205882ms"} {"level":"info","ts":"2026-07-02T05:06:10.657015Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a0c678c4e560a44","cluster-id":"dbaaf49260b7a32b"} {"level":"info","ts":"2026-07-02T05:06:10.657175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T05:06:10.65724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became follower at term 0"} {"level":"info","ts":"2026-07-02T05:06:10.657562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a0c678c4e560a44 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T05:06:10.657644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became follower at term 1"} {"level":"info","ts":"2026-07-02T05:06:10.657853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 switched to configuration voters=(7641596520048233028)"} {"level":"warn","ts":"2026-07-02T05:06:10.668172Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T05:06:10.670093Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T05:06:10.671472Z","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-02T05:06:10.675546Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a0c678c4e560a44","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T05:06:10.675764Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a0c678c4e560a44","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T05:06:10.676726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 switched to configuration voters=(7641596520048233028)"} {"level":"info","ts":"2026-07-02T05:06:10.678321Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dbaaf49260b7a32b","local-member-id":"6a0c678c4e560a44","added-peer-id":"6a0c678c4e560a44","added-peer-peer-urls":["https://199.19.213.85:2380"]} {"level":"info","ts":"2026-07-02T05:06:10.678313Z","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-02T05:06:10.678402Z","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-02T05:06:10.678422Z","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-02T05:06:10.686766Z","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-02T05:06:10.687072Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a0c678c4e560a44","initial-advertise-peer-urls":["https://199.19.213.85:2380"],"listen-peer-urls":["https://199.19.213.85:2380"],"advertise-client-urls":["https://199.19.213.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T05:06:10.687107Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T05:06:10.687144Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.85:2380"} {"level":"info","ts":"2026-07-02T05:06:10.68717Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.85:2380"} {"level":"info","ts":"2026-07-02T05:06:10.858993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T05:06:10.859092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T05:06:10.859126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 received MsgPreVoteResp from 6a0c678c4e560a44 at term 1"} {"level":"info","ts":"2026-07-02T05:06:10.859141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became candidate at term 2"} {"level":"info","ts":"2026-07-02T05:06:10.859152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 received MsgVoteResp from 6a0c678c4e560a44 at term 2"} {"level":"info","ts":"2026-07-02T05:06:10.859163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became leader at term 2"} {"level":"info","ts":"2026-07-02T05:06:10.859176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a0c678c4e560a44 elected leader 6a0c678c4e560a44 at term 2"} {"level":"info","ts":"2026-07-02T05:06:10.859899Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:06:10.860846Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:06:10.860835Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a0c678c4e560a44","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.85:2379]}","request-path":"/0/members/6a0c678c4e560a44/attributes","cluster-id":"dbaaf49260b7a32b","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T05:06:10.860882Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:06:10.86124Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T05:06:10.86132Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T05:06:10.864193Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T05:06:10.865226Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.85:2379"} {"level":"info","ts":"2026-07-02T05:06:10.86937Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dbaaf49260b7a32b","local-member-id":"6a0c678c4e560a44","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:06:10.869718Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:06:10.86985Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:06:13.588143Z","caller":"traceutil/trace.go:171","msg":"trace[805805056] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"107.164876ms","start":"2026-07-02T05:06:13.480952Z","end":"2026-07-02T05:06:13.588117Z","steps":["trace[805805056] 'read index received' (duration: 106.936473ms)","trace[805805056] 'applied index is now lower than readState.Index' (duration: 227.183µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:06:13.588226Z","caller":"traceutil/trace.go:171","msg":"trace[1980504202] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"108.801367ms","start":"2026-07-02T05:06:13.479364Z","end":"2026-07-02T05:06:13.588166Z","steps":["trace[1980504202] 'process raft request' (duration: 108.606294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:06:13.58842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.435889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T05:06:13.588536Z","caller":"traceutil/trace.go:171","msg":"trace[1099805019] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:72; }","duration":"107.624361ms","start":"2026-07-02T05:06:13.480892Z","end":"2026-07-02T05:06:13.588516Z","steps":["trace[1099805019] 'agreement among raft nodes before linearized reading' (duration: 107.375978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:06:14.063768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.960292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-02T05:06:14.063905Z","caller":"traceutil/trace.go:171","msg":"trace[413793611] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"468.909363ms","start":"2026-07-02T05:06:13.594953Z","end":"2026-07-02T05:06:14.063863Z","steps":["trace[413793611] 'read index received' (duration: 257.097567ms)","trace[413793611] 'applied index is now lower than readState.Index' (duration: 211.810366ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:06:14.064003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.055264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T05:06:14.06404Z","caller":"traceutil/trace.go:171","msg":"trace[589833593] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:73; }","duration":"469.117175ms","start":"2026-07-02T05:06:13.594911Z","end":"2026-07-02T05:06:14.064028Z","steps":["trace[589833593] 'agreement among raft nodes before linearized reading' (duration: 469.026964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:06:14.064082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:06:13.594896Z","time spent":"469.176605ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"info","ts":"2026-07-02T05:06:14.064101Z","caller":"traceutil/trace.go:171","msg":"trace[465085275] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"470.671545ms","start":"2026-07-02T05:06:13.593313Z","end":"2026-07-02T05:06:14.063985Z","steps":["trace[465085275] 'process raft request' (duration: 258.697958ms)","trace[465085275] 'compare' (duration: 206.86262ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:06:14.064327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:06:13.593299Z","time spent":"470.951018ms","remote":"127.0.0.1:48794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:07:01.042771Z","caller":"traceutil/trace.go:171","msg":"trace[853041828] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"103.031628ms","start":"2026-07-02T05:07:00.939714Z","end":"2026-07-02T05:07:01.042745Z","steps":["trace[853041828] 'process raft request' (duration: 102.640863ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:07:01.384579Z","caller":"traceutil/trace.go:171","msg":"trace[1386763993] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"131.891481ms","start":"2026-07-02T05:07:01.252662Z","end":"2026-07-02T05:07:01.384554Z","steps":["trace[1386763993] 'process raft request' (duration: 131.716879ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:07:01.390413Z","caller":"traceutil/trace.go:171","msg":"trace[1542005188] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"133.320138ms","start":"2026-07-02T05:07:01.257022Z","end":"2026-07-02T05:07:01.390342Z","steps":["trace[1542005188] 'process raft request' (duration: 133.101776ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:07:01.390683Z","caller":"traceutil/trace.go:171","msg":"trace[2064490454] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"125.170185ms","start":"2026-07-02T05:07:01.265499Z","end":"2026-07-02T05:07:01.390669Z","steps":["trace[2064490454] 'process raft request' (duration: 124.8482ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:07:01.390888Z","caller":"traceutil/trace.go:171","msg":"trace[1242811952] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"120.067625ms","start":"2026-07-02T05:07:01.270802Z","end":"2026-07-02T05:07:01.39087Z","steps":["trace[1242811952] 'process raft request' (duration: 119.594019ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:07:01.390951Z","caller":"traceutil/trace.go:171","msg":"trace[1735242318] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"131.572259ms","start":"2026-07-02T05:07:01.259369Z","end":"2026-07-02T05:07:01.390941Z","steps":["trace[1735242318] 'process raft request' (duration: 130.85963ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:07:01.390939Z","caller":"traceutil/trace.go:171","msg":"trace[284778278] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"128.465382ms","start":"2026-07-02T05:07:01.262465Z","end":"2026-07-02T05:07:01.39093Z","steps":["trace[284778278] 'process raft request' (duration: 127.845545ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:07:01.391083Z","caller":"traceutil/trace.go:171","msg":"trace[265581379] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"112.111002ms","start":"2026-07-02T05:07:01.278964Z","end":"2026-07-02T05:07:01.391075Z","steps":["trace[265581379] 'process raft request' (duration: 111.549975ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:07:01.391046Z","caller":"traceutil/trace.go:171","msg":"trace[27542238] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"119.459788ms","start":"2026-07-02T05:07:01.271575Z","end":"2026-07-02T05:07:01.391035Z","steps":["trace[27542238] 'process raft request' (duration: 118.89835ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:07:01.390897Z","caller":"traceutil/trace.go:171","msg":"trace[108786673] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"129.343993ms","start":"2026-07-02T05:07:01.261536Z","end":"2026-07-02T05:07:01.39088Z","steps":["trace[108786673] 'process raft request' (duration: 128.733615ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:07:01.391052Z","caller":"traceutil/trace.go:171","msg":"trace[1344247623] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"109.240749ms","start":"2026-07-02T05:07:01.281803Z","end":"2026-07-02T05:07:01.391044Z","steps":["trace[1344247623] 'process raft request' (duration: 108.793863ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:07:07.335929Z","caller":"traceutil/trace.go:171","msg":"trace[255270664] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"105.017115ms","start":"2026-07-02T05:07:07.230885Z","end":"2026-07-02T05:07:07.335902Z","steps":["trace[255270664] 'process raft request' (duration: 103.519461ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:07:07.754219Z","caller":"traceutil/trace.go:171","msg":"trace[2039356820] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"266.795013ms","start":"2026-07-02T05:07:07.487384Z","end":"2026-07-02T05:07:07.754179Z","steps":["trace[2039356820] 'read index received' (duration: 196.150326ms)","trace[2039356820] 'applied index is now lower than readState.Index' (duration: 70.643497ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:07:07.754354Z","caller":"traceutil/trace.go:171","msg":"trace[620745923] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"293.013255ms","start":"2026-07-02T05:07:07.46129Z","end":"2026-07-02T05:07:07.754303Z","steps":["trace[620745923] 'process raft request' (duration: 222.322845ms)","trace[620745923] 'compare' (duration: 70.38104ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:07:07.754454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.039502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:07:07.754504Z","caller":"traceutil/trace.go:171","msg":"trace[310882178] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:619; }","duration":"267.145954ms","start":"2026-07-02T05:07:07.48734Z","end":"2026-07-02T05:07:07.754486Z","steps":["trace[310882178] 'agreement among raft nodes before linearized reading' (duration: 267.036801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:07:07.754717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.000977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5mcq6\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-02T05:07:07.754764Z","caller":"traceutil/trace.go:171","msg":"trace[520592540] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5mcq6; range_end:; response_count:1; response_revision:619; }","duration":"162.10616ms","start":"2026-07-02T05:07:07.592642Z","end":"2026-07-02T05:07:07.754748Z","steps":["trace[520592540] 'agreement among raft nodes before linearized reading' (duration: 161.910334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:07:08.419533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.571164ms","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-02T05:07:08.419617Z","caller":"traceutil/trace.go:171","msg":"trace[1833339891] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:620; }","duration":"273.702028ms","start":"2026-07-02T05:07:08.145894Z","end":"2026-07-02T05:07:08.419596Z","steps":["trace[1833339891] 'range keys from in-memory index tree' (duration: 273.44185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:07:08.419508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.000001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:07:08.419845Z","caller":"traceutil/trace.go:171","msg":"trace[1858250969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"357.32098ms","start":"2026-07-02T05:07:08.062406Z","end":"2026-07-02T05:07:08.419727Z","steps":["trace[1858250969] 'range keys from in-memory index tree' (duration: 356.894238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:07:08.419977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:07:08.062379Z","time spent":"357.556507ms","remote":"127.0.0.1:48468","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-02T05:07:08.43392Z","caller":"traceutil/trace.go:171","msg":"trace[2086294300] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"284.724204ms","start":"2026-07-02T05:07:08.149139Z","end":"2026-07-02T05:07:08.433863Z","steps":["trace[2086294300] 'process raft request' (duration: 284.066724ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:07:20.640169Z","caller":"traceutil/trace.go:171","msg":"trace[1645179520] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"126.994512ms","start":"2026-07-02T05:07:20.513128Z","end":"2026-07-02T05:07:20.640123Z","steps":["trace[1645179520] 'process raft request' (duration: 122.648619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:37.70416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.488629ms","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-02T05:08:37.704273Z","caller":"traceutil/trace.go:171","msg":"trace[856056988] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1826; }","duration":"143.645011ms","start":"2026-07-02T05:08:37.560602Z","end":"2026-07-02T05:08:37.704247Z","steps":["trace[856056988] 'range keys from in-memory index tree' (duration: 143.321806ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:08:37.949904Z","caller":"traceutil/trace.go:171","msg":"trace[703092627] linearizableReadLoop","detail":"{readStateIndex:1891; appliedIndex:1890; }","duration":"239.771933ms","start":"2026-07-02T05:08:37.710097Z","end":"2026-07-02T05:08:37.949869Z","steps":["trace[703092627] 'read index received' (duration: 239.480767ms)","trace[703092627] 'applied index is now lower than readState.Index' (duration: 290.156µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:08:37.949943Z","caller":"traceutil/trace.go:171","msg":"trace[1363745618] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"240.960802ms","start":"2026-07-02T05:08:37.708947Z","end":"2026-07-02T05:08:37.949908Z","steps":["trace[1363745618] 'process raft request' (duration: 240.717978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:37.950108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.997966ms","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-02T05:08:37.950146Z","caller":"traceutil/trace.go:171","msg":"trace[1876739702] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1827; }","duration":"240.078657ms","start":"2026-07-02T05:08:37.710056Z","end":"2026-07-02T05:08:37.950135Z","steps":["trace[1876739702] 'agreement among raft nodes before linearized reading' (duration: 239.921895ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:08:38.231059Z","caller":"traceutil/trace.go:171","msg":"trace[1876706644] linearizableReadLoop","detail":"{readStateIndex:1892; appliedIndex:1891; }","duration":"170.426798ms","start":"2026-07-02T05:08:38.060604Z","end":"2026-07-02T05:08:38.231031Z","steps":["trace[1876706644] 'read index received' (duration: 167.291365ms)","trace[1876706644] 'applied index is now lower than readState.Index' (duration: 3.133963ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:08:38.231152Z","caller":"traceutil/trace.go:171","msg":"trace[418609363] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"272.222803ms","start":"2026-07-02T05:08:37.958867Z","end":"2026-07-02T05:08:38.23109Z","steps":["trace[418609363] 'process raft request' (duration: 270.944881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:38.23139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.701273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:08:38.231467Z","caller":"traceutil/trace.go:171","msg":"trace[1006763793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1828; }","duration":"170.908546ms","start":"2026-07-02T05:08:38.060538Z","end":"2026-07-02T05:08:38.231447Z","steps":["trace[1006763793] 'agreement among raft nodes before linearized reading' (duration: 170.662632ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:08:38.44496Z","caller":"traceutil/trace.go:171","msg":"trace[1568973536] linearizableReadLoop","detail":"{readStateIndex:1893; appliedIndex:1892; }","duration":"213.632452ms","start":"2026-07-02T05:08:38.231284Z","end":"2026-07-02T05:08:38.444917Z","steps":["trace[1568973536] 'read index received' (duration: 140.634551ms)","trace[1568973536] 'applied index is now lower than readState.Index' (duration: 72.996101ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:08:38.445045Z","caller":"traceutil/trace.go:171","msg":"trace[1936446587] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"287.930606ms","start":"2026-07-02T05:08:38.15707Z","end":"2026-07-02T05:08:38.445001Z","steps":["trace[1936446587] 'process raft request' (duration: 214.905124ms)","trace[1936446587] 'compare' (duration: 72.760057ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:08:38.44538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.221073ms","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-02T05:08:38.445444Z","caller":"traceutil/trace.go:171","msg":"trace[1481227574] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1829; }","duration":"331.343635ms","start":"2026-07-02T05:08:38.11408Z","end":"2026-07-02T05:08:38.445423Z","steps":["trace[1481227574] 'agreement among raft nodes before linearized reading' (duration: 331.06106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:38.445491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:38.114066Z","time spent":"331.413666ms","remote":"127.0.0.1:33582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4614,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-02T05:08:38.445478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.787801ms","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-02T05:08:38.445586Z","caller":"traceutil/trace.go:171","msg":"trace[1643089198] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1829; }","duration":"328.968294ms","start":"2026-07-02T05:08:38.116597Z","end":"2026-07-02T05:08:38.445565Z","steps":["trace[1643089198] 'agreement among raft nodes before linearized reading' (duration: 328.424954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:38.445597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.935251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:08:38.445673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:38.116584Z","time spent":"329.071636ms","remote":"127.0.0.1:48702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-02T05:08:38.445754Z","caller":"traceutil/trace.go:171","msg":"trace[515971668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1829; }","duration":"213.150504ms","start":"2026-07-02T05:08:38.232575Z","end":"2026-07-02T05:08:38.445726Z","steps":["trace[515971668] 'agreement among raft nodes before linearized reading' (duration: 212.800969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:38.445836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.320904ms","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-02T05:08:38.445895Z","caller":"traceutil/trace.go:171","msg":"trace[1948756283] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1829; }","duration":"211.425465ms","start":"2026-07-02T05:08:38.234454Z","end":"2026-07-02T05:08:38.44588Z","steps":["trace[1948756283] 'agreement among raft nodes before linearized reading' (duration: 211.208851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:38.446055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.237083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:9032"} {"level":"info","ts":"2026-07-02T05:08:38.446087Z","caller":"traceutil/trace.go:171","msg":"trace[1691383345] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1829; }","duration":"351.301834ms","start":"2026-07-02T05:08:38.094776Z","end":"2026-07-02T05:08:38.446078Z","steps":["trace[1691383345] 'agreement among raft nodes before linearized reading' (duration: 351.215393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:38.446114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:38.094755Z","time spent":"351.351165ms","remote":"127.0.0.1:48642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":9055,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"warn","ts":"2026-07-02T05:08:38.447684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.725742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d27b56e7-30f5-419e-8569-a0ff17203f8f\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-02T05:08:38.447758Z","caller":"traceutil/trace.go:171","msg":"trace[462539028] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d27b56e7-30f5-419e-8569-a0ff17203f8f; range_end:; response_count:1; response_revision:1829; }","duration":"197.828713ms","start":"2026-07-02T05:08:38.249915Z","end":"2026-07-02T05:08:38.447744Z","steps":["trace[462539028] 'agreement among raft nodes before linearized reading' (duration: 195.482664ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:09:10.563639Z","caller":"traceutil/trace.go:171","msg":"trace[581295947] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"147.6581ms","start":"2026-07-02T05:09:10.415954Z","end":"2026-07-02T05:09:10.563612Z","steps":["trace[581295947] 'process raft request' (duration: 147.468618ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:09:10.880519Z","caller":"traceutil/trace.go:171","msg":"trace[1700760594] linearizableReadLoop","detail":"{readStateIndex:2176; appliedIndex:2175; }","duration":"138.169694ms","start":"2026-07-02T05:09:10.742325Z","end":"2026-07-02T05:09:10.880495Z","steps":["trace[1700760594] 'read index received' (duration: 137.954791ms)","trace[1700760594] 'applied index is now lower than readState.Index' (duration: 213.473µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:09:10.880563Z","caller":"traceutil/trace.go:171","msg":"trace[1816515519] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"152.283313ms","start":"2026-07-02T05:09:10.728238Z","end":"2026-07-02T05:09:10.880521Z","steps":["trace[1816515519] 'process raft request' (duration: 152.054779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:10.880792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.453199ms","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-02T05:09:10.880831Z","caller":"traceutil/trace.go:171","msg":"trace[488242140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2098; }","duration":"138.569381ms","start":"2026-07-02T05:09:10.74225Z","end":"2026-07-02T05:09:10.88082Z","steps":["trace[488242140] 'agreement among raft nodes before linearized reading' (duration: 138.395387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:10.881013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.764016ms","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-02T05:09:10.881041Z","caller":"traceutil/trace.go:171","msg":"trace[1912726896] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2098; }","duration":"102.844457ms","start":"2026-07-02T05:09:10.778186Z","end":"2026-07-02T05:09:10.881031Z","steps":["trace[1912726896] 'agreement among raft nodes before linearized reading' (duration: 102.761536ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:03.788395Z","caller":"traceutil/trace.go:171","msg":"trace[2127251153] linearizableReadLoop","detail":"{readStateIndex:2665; appliedIndex:2664; }","duration":"273.216219ms","start":"2026-07-02T05:10:03.51515Z","end":"2026-07-02T05:10:03.788366Z","steps":["trace[2127251153] 'read index received' (duration: 273.018156ms)","trace[2127251153] 'applied index is now lower than readState.Index' (duration: 196.863µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:10:03.788416Z","caller":"traceutil/trace.go:171","msg":"trace[452911606] transaction","detail":"{read_only:false; response_revision:2562; number_of_response:1; }","duration":"308.102166ms","start":"2026-07-02T05:10:03.480291Z","end":"2026-07-02T05:10:03.788393Z","steps":["trace[452911606] 'process raft request' (duration: 307.870953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:03.788621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.451363ms","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-02T05:10:03.788651Z","caller":"traceutil/trace.go:171","msg":"trace[92160469] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2562; }","duration":"273.545974ms","start":"2026-07-02T05:10:03.515099Z","end":"2026-07-02T05:10:03.788645Z","steps":["trace[92160469] 'agreement among raft nodes before linearized reading' (duration: 273.373201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:03.788965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:03.480273Z","time spent":"308.266348ms","remote":"127.0.0.1:48560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:10:03.790531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.538208ms","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-02T05:10:03.790618Z","caller":"traceutil/trace.go:171","msg":"trace[1903054679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2563; }","duration":"274.640649ms","start":"2026-07-02T05:10:03.515961Z","end":"2026-07-02T05:10:03.790602Z","steps":["trace[1903054679] 'agreement among raft nodes before linearized reading' (duration: 274.355755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:03.791214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.145176ms","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-02T05:10:03.79127Z","caller":"traceutil/trace.go:171","msg":"trace[1872373331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2563; }","duration":"275.222587ms","start":"2026-07-02T05:10:03.516033Z","end":"2026-07-02T05:10:03.791256Z","steps":["trace[1872373331] 'agreement among raft nodes before linearized reading' (duration: 275.092555ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:30.110128Z","caller":"traceutil/trace.go:171","msg":"trace[82406171] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"147.569681ms","start":"2026-07-02T05:10:29.962513Z","end":"2026-07-02T05:10:30.110082Z","steps":["trace[82406171] 'process raft request' (duration: 96.643723ms)","trace[82406171] 'compare' (duration: 50.35517ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:10:30.273381Z","caller":"traceutil/trace.go:171","msg":"trace[1797571575] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"127.122626ms","start":"2026-07-02T05:10:30.146213Z","end":"2026-07-02T05:10:30.273336Z","steps":["trace[1797571575] 'process raft request' (duration: 126.805592ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:30.380035Z","caller":"traceutil/trace.go:171","msg":"trace[1179888242] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"100.806681ms","start":"2026-07-02T05:10:30.279191Z","end":"2026-07-02T05:10:30.379998Z","steps":["trace[1179888242] 'process raft request' (duration: 62.421877ms)","trace[1179888242] 'compare' (duration: 38.13622ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:10:30.796328Z","caller":"traceutil/trace.go:171","msg":"trace[1936312516] transaction","detail":"{read_only:false; response_revision:2749; number_of_response:1; }","duration":"166.572575ms","start":"2026-07-02T05:10:30.629721Z","end":"2026-07-02T05:10:30.796293Z","steps":["trace[1936312516] 'process raft request' (duration: 166.370782ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:30.924171Z","caller":"traceutil/trace.go:171","msg":"trace[1315435709] transaction","detail":"{read_only:false; response_revision:2750; number_of_response:1; }","duration":"292.644216ms","start":"2026-07-02T05:10:30.631496Z","end":"2026-07-02T05:10:30.92414Z","steps":["trace[1315435709] 'process raft request' (duration: 292.493304ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:56.693917Z","caller":"traceutil/trace.go:171","msg":"trace[324748310] transaction","detail":"{read_only:false; response_revision:4277; number_of_response:1; }","duration":"167.032602ms","start":"2026-07-02T05:12:56.526852Z","end":"2026-07-02T05:12:56.693884Z","steps":["trace[324748310] 'process raft request' (duration: 166.250067ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:56.694227Z","caller":"traceutil/trace.go:171","msg":"trace[385337720] transaction","detail":"{read_only:false; response_revision:4279; number_of_response:1; }","duration":"164.310141ms","start":"2026-07-02T05:12:56.529878Z","end":"2026-07-02T05:12:56.694188Z","steps":["trace[385337720] 'process raft request' (duration: 164.149978ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:56.694287Z","caller":"traceutil/trace.go:171","msg":"trace[885895326] transaction","detail":"{read_only:false; response_revision:4278; number_of_response:1; }","duration":"164.460785ms","start":"2026-07-02T05:12:56.529782Z","end":"2026-07-02T05:12:56.694242Z","steps":["trace[885895326] 'process raft request' (duration: 163.540078ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:56.704431Z","caller":"traceutil/trace.go:171","msg":"trace[579985573] linearizableReadLoop","detail":"{readStateIndex:4477; appliedIndex:4473; }","duration":"119.441999ms","start":"2026-07-02T05:12:56.58497Z","end":"2026-07-02T05:12:56.704412Z","steps":["trace[579985573] 'read index received' (duration: 107.987203ms)","trace[579985573] 'applied index is now lower than readState.Index' (duration: 11.453926ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:12:56.704722Z","caller":"traceutil/trace.go:171","msg":"trace[33822221] transaction","detail":"{read_only:false; response_revision:4280; number_of_response:1; }","duration":"123.084296ms","start":"2026-07-02T05:12:56.581628Z","end":"2026-07-02T05:12:56.704713Z","steps":["trace[33822221] 'process raft request' (duration: 122.635038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:56.704966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.96988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-07-02T05:12:56.704994Z","caller":"traceutil/trace.go:171","msg":"trace[713189009] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4280; }","duration":"120.058991ms","start":"2026-07-02T05:12:56.584927Z","end":"2026-07-02T05:12:56.704986Z","steps":["trace[713189009] 'agreement among raft nodes before linearized reading' (duration: 119.924818ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:01.937267Z","caller":"traceutil/trace.go:171","msg":"trace[1605223932] transaction","detail":"{read_only:false; response_revision:4436; number_of_response:1; }","duration":"129.303751ms","start":"2026-07-02T05:13:01.807928Z","end":"2026-07-02T05:13:01.937232Z","steps":["trace[1605223932] 'process raft request' (duration: 129.086656ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:01.943636Z","caller":"traceutil/trace.go:171","msg":"trace[1618722566] linearizableReadLoop","detail":"{readStateIndex:4639; appliedIndex:4637; }","duration":"107.374856ms","start":"2026-07-02T05:13:01.836234Z","end":"2026-07-02T05:13:01.943609Z","steps":["trace[1618722566] 'read index received' (duration: 100.760928ms)","trace[1618722566] 'applied index is now lower than readState.Index' (duration: 6.612448ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:13:01.944054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.778323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-07-02T05:13:01.944146Z","caller":"traceutil/trace.go:171","msg":"trace[41104481] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4437; }","duration":"107.953786ms","start":"2026-07-02T05:13:01.836171Z","end":"2026-07-02T05:13:01.944125Z","steps":["trace[41104481] 'agreement among raft nodes before linearized reading' (duration: 107.637981ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:01.944137Z","caller":"traceutil/trace.go:171","msg":"trace[1465546540] transaction","detail":"{read_only:false; response_revision:4437; number_of_response:1; }","duration":"135.551792ms","start":"2026-07-02T05:13:01.808252Z","end":"2026-07-02T05:13:01.943804Z","steps":["trace[1465546540] 'process raft request' (duration: 135.152975ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:02.57985Z","caller":"traceutil/trace.go:171","msg":"trace[244885876] transaction","detail":"{read_only:false; response_revision:4451; number_of_response:1; }","duration":"182.677922ms","start":"2026-07-02T05:13:02.397141Z","end":"2026-07-02T05:13:02.579819Z","steps":["trace[244885876] 'process raft request' (duration: 181.916418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:07.951575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.671137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7581"} {"level":"info","ts":"2026-07-02T05:13:07.951688Z","caller":"traceutil/trace.go:171","msg":"trace[1589087440] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4564; }","duration":"235.85713ms","start":"2026-07-02T05:13:07.715802Z","end":"2026-07-02T05:13:07.951659Z","steps":["trace[1589087440] 'range keys from in-memory index tree' (duration: 234.827733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:07.951196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.457703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"} {"level":"info","ts":"2026-07-02T05:13:07.952065Z","caller":"traceutil/trace.go:171","msg":"trace[920117205] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4564; }","duration":"120.44017ms","start":"2026-07-02T05:13:07.831584Z","end":"2026-07-02T05:13:07.952024Z","steps":["trace[920117205] 'range keys from in-memory index tree' (duration: 119.189357ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:08.06589Z","caller":"traceutil/trace.go:171","msg":"trace[1540342445] linearizableReadLoop","detail":"{readStateIndex:4770; appliedIndex:4769; }","duration":"105.771678ms","start":"2026-07-02T05:13:07.960088Z","end":"2026-07-02T05:13:08.065859Z","steps":["trace[1540342445] 'read index received' (duration: 105.374061ms)","trace[1540342445] 'applied index is now lower than readState.Index' (duration: 395.897µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:08.065907Z","caller":"traceutil/trace.go:171","msg":"trace[154053812] transaction","detail":"{read_only:false; response_revision:4565; number_of_response:1; }","duration":"107.900816ms","start":"2026-07-02T05:13:07.957977Z","end":"2026-07-02T05:13:08.065877Z","steps":["trace[154053812] 'process raft request' (duration: 107.54873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:08.066304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.202026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"} {"level":"info","ts":"2026-07-02T05:13:08.066469Z","caller":"traceutil/trace.go:171","msg":"trace[1925364472] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4565; }","duration":"106.311507ms","start":"2026-07-02T05:13:07.960061Z","end":"2026-07-02T05:13:08.066373Z","steps":["trace[1925364472] 'agreement among raft nodes before linearized reading' (duration: 105.88483ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:08.166202Z","caller":"traceutil/trace.go:171","msg":"trace[785508838] transaction","detail":"{read_only:false; response_revision:4566; number_of_response:1; }","duration":"200.028058ms","start":"2026-07-02T05:13:07.966145Z","end":"2026-07-02T05:13:08.166173Z","steps":["trace[785508838] 'process raft request' (duration: 191.610699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:08.680081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.114161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:13:08.680268Z","caller":"traceutil/trace.go:171","msg":"trace[1318220938] linearizableReadLoop","detail":"{readStateIndex:4778; appliedIndex:4777; }","duration":"183.556477ms","start":"2026-07-02T05:13:08.496696Z","end":"2026-07-02T05:13:08.680253Z","steps":["trace[1318220938] 'read index received' (duration: 71.542µs)","trace[1318220938] 'applied index is now lower than readState.Index' (duration: 183.484065ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:08.681299Z","caller":"traceutil/trace.go:171","msg":"trace[559488990] transaction","detail":"{read_only:false; response_revision:4572; number_of_response:1; }","duration":"326.723326ms","start":"2026-07-02T05:13:08.354544Z","end":"2026-07-02T05:13:08.681267Z","steps":["trace[559488990] 'process raft request' (duration: 86.323722ms)","trace[559488990] 'compare' (duration: 238.795636ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:13:08.681493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:08.354519Z","time spent":"326.859178ms","remote":"127.0.0.1:48890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:13:08.681502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.784598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:13:08.681568Z","caller":"traceutil/trace.go:171","msg":"trace[7243207] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:4573; }","duration":"184.926151ms","start":"2026-07-02T05:13:08.496625Z","end":"2026-07-02T05:13:08.681552Z","steps":["trace[7243207] 'agreement among raft nodes before linearized reading' (duration: 184.723318ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:08.681845Z","caller":"traceutil/trace.go:171","msg":"trace[201585777] transaction","detail":"{read_only:false; response_revision:4573; number_of_response:1; }","duration":"178.415716ms","start":"2026-07-02T05:13:08.503401Z","end":"2026-07-02T05:13:08.681817Z","steps":["trace[201585777] 'process raft request' (duration: 176.789867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:08.966687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.733432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:13:08.967448Z","caller":"traceutil/trace.go:171","msg":"trace[236313323] transaction","detail":"{read_only:false; response_revision:4577; number_of_response:1; }","duration":"240.871383ms","start":"2026-07-02T05:13:08.726531Z","end":"2026-07-02T05:13:08.967402Z","steps":["trace[236313323] 'process raft request' (duration: 53.282555ms)","trace[236313323] 'compare' (duration: 186.548579ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:08.969627Z","caller":"traceutil/trace.go:171","msg":"trace[692282701] transaction","detail":"{read_only:false; response_revision:4579; number_of_response:1; }","duration":"242.240558ms","start":"2026-07-02T05:13:08.72736Z","end":"2026-07-02T05:13:08.9696Z","steps":["trace[692282701] 'process raft request' (duration: 242.132136ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:08.969878Z","caller":"traceutil/trace.go:171","msg":"trace[1894329129] linearizableReadLoop","detail":"{readStateIndex:4786; appliedIndex:4782; }","duration":"241.924331ms","start":"2026-07-02T05:13:08.727923Z","end":"2026-07-02T05:13:08.969847Z","steps":["trace[1894329129] 'read index received' (duration: 51.851729ms)","trace[1894329129] 'applied index is now lower than readState.Index' (duration: 190.071182ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:08.969972Z","caller":"traceutil/trace.go:171","msg":"trace[567413835] transaction","detail":"{read_only:false; response_revision:4578; number_of_response:1; }","duration":"242.639185ms","start":"2026-07-02T05:13:08.72732Z","end":"2026-07-02T05:13:08.969959Z","steps":["trace[567413835] 'process raft request' (duration: 242.027604ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:08.970139Z","caller":"traceutil/trace.go:171","msg":"trace[1923695807] transaction","detail":"{read_only:false; response_revision:4580; number_of_response:1; }","duration":"242.627815ms","start":"2026-07-02T05:13:08.727497Z","end":"2026-07-02T05:13:08.970125Z","steps":["trace[1923695807] 'process raft request' (duration: 242.055025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:08.970562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.620744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4830"} {"level":"warn","ts":"2026-07-02T05:13:08.97057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.451556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-02T05:13:08.970617Z","caller":"traceutil/trace.go:171","msg":"trace[386590698] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4580; }","duration":"156.566688ms","start":"2026-07-02T05:13:08.814038Z","end":"2026-07-02T05:13:08.970604Z","steps":["trace[386590698] 'agreement among raft nodes before linearized reading' (duration: 156.410455ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:08.97062Z","caller":"traceutil/trace.go:171","msg":"trace[1650648921] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4580; }","duration":"242.696815ms","start":"2026-07-02T05:13:08.727895Z","end":"2026-07-02T05:13:08.970592Z","steps":["trace[1650648921] 'agreement among raft nodes before linearized reading' (duration: 242.521662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:08.971123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.735076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:13:08.971156Z","caller":"traceutil/trace.go:171","msg":"trace[877687977] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:4580; }","duration":"127.795057ms","start":"2026-07-02T05:13:08.843351Z","end":"2026-07-02T05:13:08.971146Z","steps":["trace[877687977] 'agreement among raft nodes before linearized reading' (duration: 127.737646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:08.97168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.90207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-02T05:13:08.971762Z","caller":"traceutil/trace.go:171","msg":"trace[585920166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4580; }","duration":"154.066883ms","start":"2026-07-02T05:13:08.817674Z","end":"2026-07-02T05:13:08.97174Z","steps":["trace[585920166] 'agreement among raft nodes before linearized reading' (duration: 153.9271ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:14.006493Z","caller":"traceutil/trace.go:171","msg":"trace[1907599453] transaction","detail":"{read_only:false; response_revision:4655; number_of_response:1; }","duration":"166.810698ms","start":"2026-07-02T05:13:13.839648Z","end":"2026-07-02T05:13:14.006458Z","steps":["trace[1907599453] 'process raft request' (duration: 166.441411ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:16.32146Z","caller":"traceutil/trace.go:171","msg":"trace[984106305] transaction","detail":"{read_only:false; response_revision:4701; number_of_response:1; }","duration":"127.439134ms","start":"2026-07-02T05:13:16.193986Z","end":"2026-07-02T05:13:16.321425Z","steps":["trace[984106305] 'process raft request' (duration: 127.237221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:16.644204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.609476ms","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-02T05:13:16.644277Z","caller":"traceutil/trace.go:171","msg":"trace[574011655] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4702; }","duration":"261.721637ms","start":"2026-07-02T05:13:16.382536Z","end":"2026-07-02T05:13:16.644257Z","steps":["trace[574011655] 'range keys from in-memory index tree' (duration: 261.455882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:21.365924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155667101s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-02T05:13:21.367098Z","caller":"traceutil/trace.go:171","msg":"trace[726873509] linearizableReadLoop","detail":"{readStateIndex:4951; appliedIndex:4949; }","duration":"1.023623099s","start":"2026-07-02T05:13:20.343448Z","end":"2026-07-02T05:13:21.367072Z","steps":["trace[726873509] 'read index received' (duration: 392.53986ms)","trace[726873509] 'applied index is now lower than readState.Index' (duration: 631.082299ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:13:21.367506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024047636s","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-02T05:13:21.367594Z","caller":"traceutil/trace.go:171","msg":"trace[240596886] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4737; }","duration":"1.024191309s","start":"2026-07-02T05:13:20.343384Z","end":"2026-07-02T05:13:21.367575Z","steps":["trace[240596886] 'agreement among raft nodes before linearized reading' (duration: 1.023985605s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:21.36762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.228471ms","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-02T05:13:21.367679Z","caller":"traceutil/trace.go:171","msg":"trace[2079712365] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"1.034817764s","start":"2026-07-02T05:13:20.332851Z","end":"2026-07-02T05:13:21.367669Z","steps":["trace[2079712365] 'process raft request' (duration: 1.033227047s)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:21.36771Z","caller":"traceutil/trace.go:171","msg":"trace[753636529] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4737; }","duration":"670.369483ms","start":"2026-07-02T05:13:20.697295Z","end":"2026-07-02T05:13:21.367665Z","steps":["trace[753636529] 'agreement among raft nodes before linearized reading' (duration: 670.097429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:21.36777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:20.332825Z","time spent":"1.034884356s","remote":"127.0.0.1:48702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:13:21.367758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:20.697272Z","time spent":"670.474965ms","remote":"127.0.0.1:48560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-02T05:13:21.367884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.853839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:13:21.367983Z","caller":"traceutil/trace.go:171","msg":"trace[1413978723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4737; }","duration":"306.997272ms","start":"2026-07-02T05:13:21.060966Z","end":"2026-07-02T05:13:21.367963Z","steps":["trace[1413978723] 'agreement among raft nodes before linearized reading' (duration: 306.837579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:21.368032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:21.060945Z","time spent":"307.079373ms","remote":"127.0.0.1:48468","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-02T05:13:21.36815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.300537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-07-02T05:13:21.368157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.655672ms","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-02T05:13:21.368217Z","caller":"traceutil/trace.go:171","msg":"trace[1567384773] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4737; }","duration":"253.4026ms","start":"2026-07-02T05:13:21.114797Z","end":"2026-07-02T05:13:21.3682Z","steps":["trace[1567384773] 'agreement among raft nodes before linearized reading' (duration: 253.182125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:21.368283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.574849ms","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-02T05:13:21.368323Z","caller":"traceutil/trace.go:171","msg":"trace[1489881796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4737; }","duration":"293.63521ms","start":"2026-07-02T05:13:21.074677Z","end":"2026-07-02T05:13:21.368312Z","steps":["trace[1489881796] 'agreement among raft nodes before linearized reading' (duration: 293.534068ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:21.36823Z","caller":"traceutil/trace.go:171","msg":"trace[550751386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4737; }","duration":"315.750475ms","start":"2026-07-02T05:13:21.052467Z","end":"2026-07-02T05:13:21.368218Z","steps":["trace[550751386] 'agreement among raft nodes before linearized reading' (duration: 315.577851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:21.368423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:21.052454Z","time spent":"315.954989ms","remote":"127.0.0.1:48702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T05:13:21.368379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.448584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10515"} {"level":"info","ts":"2026-07-02T05:13:21.368675Z","caller":"traceutil/trace.go:171","msg":"trace[220779259] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4737; }","duration":"612.805131ms","start":"2026-07-02T05:13:20.755856Z","end":"2026-07-02T05:13:21.368661Z","steps":["trace[220779259] 'agreement among raft nodes before linearized reading' (duration: 612.395054ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:21.368713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:20.755835Z","time spent":"612.866771ms","remote":"127.0.0.1:48642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10538,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-07-02T05:13:21.368733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.438331ms","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-02T05:13:21.368767Z","caller":"traceutil/trace.go:171","msg":"trace[1430911462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4737; }","duration":"299.496942ms","start":"2026-07-02T05:13:21.069261Z","end":"2026-07-02T05:13:21.368758Z","steps":["trace[1430911462] 'agreement among raft nodes before linearized reading' (duration: 299.393921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:21.368881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.275816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-07-02T05:13:21.368963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.142899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-02T05:13:21.367633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:20.343363Z","time spent":"1.024261939s","remote":"127.0.0.1:48702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-02T05:13:21.369034Z","caller":"traceutil/trace.go:171","msg":"trace[1410813149] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:4737; }","duration":"406.26501ms","start":"2026-07-02T05:13:20.962758Z","end":"2026-07-02T05:13:21.369023Z","steps":["trace[1410813149] 'agreement among raft nodes before linearized reading' (duration: 406.107717ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:21.368982Z","caller":"traceutil/trace.go:171","msg":"trace[669843153] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4737; }","duration":"524.403409ms","start":"2026-07-02T05:13:20.844559Z","end":"2026-07-02T05:13:21.368962Z","steps":["trace[669843153] 'agreement among raft nodes before linearized reading' (duration: 523.573154ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:21.369071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:20.962732Z","time spent":"406.328651ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":26,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:13:21.369188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:20.844546Z","time spent":"524.627712ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-07-02T05:13:21.369228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.504242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T05:13:21.369283Z","caller":"traceutil/trace.go:171","msg":"trace[1101545719] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:4737; }","duration":"442.560013ms","start":"2026-07-02T05:13:20.92671Z","end":"2026-07-02T05:13:21.36927Z","steps":["trace[1101545719] 'agreement among raft nodes before linearized reading' (duration: 442.470161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:21.369318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:20.926655Z","time spent":"442.655495ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":10,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-07-02T05:13:21.597347Z","caller":"traceutil/trace.go:171","msg":"trace[672719990] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"223.222325ms","start":"2026-07-02T05:13:21.374089Z","end":"2026-07-02T05:13:21.597311Z","steps":["trace[672719990] 'process raft request' (duration: 167.856862ms)","trace[672719990] 'compare' (duration: 55.20917ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:21.786225Z","caller":"traceutil/trace.go:171","msg":"trace[1986426744] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"411.833187ms","start":"2026-07-02T05:13:21.374366Z","end":"2026-07-02T05:13:21.786199Z","steps":["trace[1986426744] 'process raft request' (duration: 411.701664ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:21.786384Z","caller":"traceutil/trace.go:171","msg":"trace[903528391] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"411.835246ms","start":"2026-07-02T05:13:21.374533Z","end":"2026-07-02T05:13:21.786368Z","steps":["trace[903528391] 'process raft request' (duration: 411.624973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:21.786476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:21.374525Z","time spent":"411.894717ms","remote":"127.0.0.1:48702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:13:21.786615Z","caller":"traceutil/trace.go:171","msg":"trace[559515104] linearizableReadLoop","detail":"{readStateIndex:4960; appliedIndex:4952; }","duration":"124.772321ms","start":"2026-07-02T05:13:21.661747Z","end":"2026-07-02T05:13:21.786519Z","steps":["trace[559515104] 'read index received' (duration: 39.272223ms)","trace[559515104] 'applied index is now lower than readState.Index' (duration: 85.498988ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:21.786656Z","caller":"traceutil/trace.go:171","msg":"trace[1638765645] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"409.351133ms","start":"2026-07-02T05:13:21.37729Z","end":"2026-07-02T05:13:21.786641Z","steps":["trace[1638765645] 'process raft request' (duration: 409.093289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:21.786718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:21.377282Z","time spent":"409.403854ms","remote":"127.0.0.1:48560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:13:21.78681Z","caller":"traceutil/trace.go:171","msg":"trace[1137384382] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"410.377012ms","start":"2026-07-02T05:13:21.376422Z","end":"2026-07-02T05:13:21.786799Z","steps":["trace[1137384382] 'process raft request' (duration: 409.843493ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:21.786607Z","caller":"traceutil/trace.go:171","msg":"trace[1421109473] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"412.302304ms","start":"2026-07-02T05:13:21.374279Z","end":"2026-07-02T05:13:21.786582Z","steps":["trace[1421109473] 'process raft request' (duration: 326.794826ms)","trace[1421109473] 'compare' (duration: 84.779026ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:21.786841Z","caller":"traceutil/trace.go:171","msg":"trace[426886025] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"410.215738ms","start":"2026-07-02T05:13:21.376581Z","end":"2026-07-02T05:13:21.786796Z","steps":["trace[426886025] 'process raft request' (duration: 409.75537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:21.786933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:21.374258Z","time spent":"412.603181ms","remote":"127.0.0.1:48702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:13:21.787057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:21.376408Z","time spent":"410.446804ms","remote":"127.0.0.1:48702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:13:21.787075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:21.376572Z","time spent":"410.373392ms","remote":"127.0.0.1:48560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:13:21.787137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.341352ms","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-02T05:13:21.787355Z","caller":"traceutil/trace.go:171","msg":"trace[1002590884] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4746; }","duration":"125.597156ms","start":"2026-07-02T05:13:21.661653Z","end":"2026-07-02T05:13:21.78725Z","steps":["trace[1002590884] 'agreement among raft nodes before linearized reading' (duration: 125.117637ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:21.787148Z","caller":"traceutil/trace.go:171","msg":"trace[401831732] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"408.884735ms","start":"2026-07-02T05:13:21.378252Z","end":"2026-07-02T05:13:21.787137Z","steps":["trace[401831732] 'process raft request' (duration: 408.176942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:21.787552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:21.378246Z","time spent":"409.261961ms","remote":"127.0.0.1:48642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10793,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:13:21.78716Z","caller":"traceutil/trace.go:171","msg":"trace[368236260] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"351.793162ms","start":"2026-07-02T05:13:21.435353Z","end":"2026-07-02T05:13:21.787146Z","steps":["trace[368236260] 'process raft request' (duration: 351.12319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:21.787748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:21.435335Z","time spent":"352.382493ms","remote":"127.0.0.1:48702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:13:21.788966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:21.374344Z","time spent":"411.956738ms","remote":"127.0.0.1:48702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:13:22.272078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.828371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:13:22.272164Z","caller":"traceutil/trace.go:171","msg":"trace[1529347896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4753; }","duration":"211.975574ms","start":"2026-07-02T05:13:22.060165Z","end":"2026-07-02T05:13:22.27214Z","steps":["trace[1529347896] 'range keys from in-memory index tree' (duration: 211.74121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:22.272157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.879966ms","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-02T05:13:22.272267Z","caller":"traceutil/trace.go:171","msg":"trace[2102888328] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4753; }","duration":"337.067229ms","start":"2026-07-02T05:13:21.935174Z","end":"2026-07-02T05:13:22.272242Z","steps":["trace[2102888328] 'range keys from in-memory index tree' (duration: 336.506159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:22.272223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.449318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:13:22.272328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:21.935152Z","time spent":"337.164811ms","remote":"127.0.0.1:53616","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":"info","ts":"2026-07-02T05:13:22.272347Z","caller":"traceutil/trace.go:171","msg":"trace[247335969] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:4753; }","duration":"183.634951ms","start":"2026-07-02T05:13:22.088691Z","end":"2026-07-02T05:13:22.272326Z","steps":["trace[247335969] 'count revisions from in-memory index tree' (duration: 183.384297ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:45.05834Z","caller":"traceutil/trace.go:171","msg":"trace[742865458] linearizableReadLoop","detail":"{readStateIndex:5172; appliedIndex:5171; }","duration":"127.024313ms","start":"2026-07-02T05:13:44.931281Z","end":"2026-07-02T05:13:45.058305Z","steps":["trace[742865458] 'read index received' (duration: 87.035979ms)","trace[742865458] 'applied index is now lower than readState.Index' (duration: 39.942373ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:45.058358Z","caller":"traceutil/trace.go:171","msg":"trace[538396402] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4948; }","duration":"155.361542ms","start":"2026-07-02T05:13:44.90294Z","end":"2026-07-02T05:13:45.058302Z","steps":["trace[538396402] 'process raft request' (duration: 115.445529ms)","trace[538396402] 'compare' (duration: 39.74542ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:13:45.058505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.193586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T05:13:45.05861Z","caller":"traceutil/trace.go:171","msg":"trace[1369490697] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:4948; }","duration":"127.338909ms","start":"2026-07-02T05:13:44.931234Z","end":"2026-07-02T05:13:45.058573Z","steps":["trace[1369490697] 'agreement among raft nodes before linearized reading' (duration: 127.176816ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:46.026322Z","caller":"traceutil/trace.go:171","msg":"trace[658082199] linearizableReadLoop","detail":"{readStateIndex:5180; appliedIndex:5179; }","duration":"138.664198ms","start":"2026-07-02T05:13:45.887626Z","end":"2026-07-02T05:13:46.02629Z","steps":["trace[658082199] 'read index received' (duration: 65.036686ms)","trace[658082199] 'applied index is now lower than readState.Index' (duration: 73.626332ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:46.026464Z","caller":"traceutil/trace.go:171","msg":"trace[203452908] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"139.228968ms","start":"2026-07-02T05:13:45.887223Z","end":"2026-07-02T05:13:46.026452Z","steps":["trace[203452908] 'process raft request' (duration: 65.495314ms)","trace[203452908] 'compare' (duration: 73.281096ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:13:46.027382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.730596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-07-02T05:13:46.027571Z","caller":"traceutil/trace.go:171","msg":"trace[1219733182] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4955; }","duration":"139.866608ms","start":"2026-07-02T05:13:45.887607Z","end":"2026-07-02T05:13:46.027474Z","steps":["trace[1219733182] 'agreement among raft nodes before linearized reading' (duration: 139.681455ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:46.213443Z","caller":"traceutil/trace.go:171","msg":"trace[581187166] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"103.34135ms","start":"2026-07-02T05:13:46.110054Z","end":"2026-07-02T05:13:46.213395Z","steps":["trace[581187166] 'process raft request' (duration: 54.744252ms)","trace[581187166] 'compare' (duration: 48.385004ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:46.213992Z","caller":"traceutil/trace.go:171","msg":"trace[1158183049] linearizableReadLoop","detail":"{readStateIndex:5187; appliedIndex:5186; }","duration":"103.321781ms","start":"2026-07-02T05:13:46.110639Z","end":"2026-07-02T05:13:46.213961Z","steps":["trace[1158183049] 'read index received' (duration: 54.175213ms)","trace[1158183049] 'applied index is now lower than readState.Index' (duration: 49.144718ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:13:46.214343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.678706ms","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-02T05:13:46.214412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.768927ms","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-02T05:13:46.214456Z","caller":"traceutil/trace.go:171","msg":"trace[1034488783] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4964; }","duration":"103.826159ms","start":"2026-07-02T05:13:46.110592Z","end":"2026-07-02T05:13:46.214418Z","steps":["trace[1034488783] 'agreement among raft nodes before linearized reading' (duration: 103.601885ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:46.214466Z","caller":"traceutil/trace.go:171","msg":"trace[2014454972] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4964; }","duration":"100.861319ms","start":"2026-07-02T05:13:46.113589Z","end":"2026-07-02T05:13:46.21445Z","steps":["trace[2014454972] 'agreement among raft nodes before linearized reading' (duration: 100.645575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:46.486268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.138284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-07-02T05:13:46.486368Z","caller":"traceutil/trace.go:171","msg":"trace[383055165] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5001; }","duration":"129.263236ms","start":"2026-07-02T05:13:46.357084Z","end":"2026-07-02T05:13:46.486347Z","steps":["trace[383055165] 'agreement among raft nodes before linearized reading' (duration: 128.90239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:46.487518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.266806ms","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:4725"} {"level":"info","ts":"2026-07-02T05:13:46.487605Z","caller":"traceutil/trace.go:171","msg":"trace[1472145146] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5001; }","duration":"126.376207ms","start":"2026-07-02T05:13:46.361212Z","end":"2026-07-02T05:13:46.487588Z","steps":["trace[1472145146] 'agreement among raft nodes before linearized reading' (duration: 124.620978ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:46.671953Z","caller":"traceutil/trace.go:171","msg":"trace[411941134] linearizableReadLoop","detail":"{readStateIndex:5239; appliedIndex:5238; }","duration":"125.145677ms","start":"2026-07-02T05:13:46.546783Z","end":"2026-07-02T05:13:46.671929Z","steps":["trace[411941134] 'read index received' (duration: 73.108081ms)","trace[411941134] 'applied index is now lower than readState.Index' (duration: 52.036616ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:46.672078Z","caller":"traceutil/trace.go:171","msg":"trace[124075242] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"127.126542ms","start":"2026-07-02T05:13:46.544941Z","end":"2026-07-02T05:13:46.672067Z","steps":["trace[124075242] 'process raft request' (duration: 75.086575ms)","trace[124075242] 'compare' (duration: 51.747532ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:13:46.672484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.677016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-07-02T05:13:46.672524Z","caller":"traceutil/trace.go:171","msg":"trace[7189643] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5014; }","duration":"125.740987ms","start":"2026-07-02T05:13:46.546768Z","end":"2026-07-02T05:13:46.672508Z","steps":["trace[7189643] 'agreement among raft nodes before linearized reading' (duration: 125.593244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:46.672516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.142477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-07-02T05:13:46.672614Z","caller":"traceutil/trace.go:171","msg":"trace[733308070] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:5014; }","duration":"125.271599ms","start":"2026-07-02T05:13:46.54732Z","end":"2026-07-02T05:13:46.672591Z","steps":["trace[733308070] 'agreement among raft nodes before linearized reading' (duration: 124.945384ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:52.098587Z","caller":"traceutil/trace.go:171","msg":"trace[2024578052] linearizableReadLoop","detail":"{readStateIndex:5315; appliedIndex:5314; }","duration":"301.111599ms","start":"2026-07-02T05:13:51.797443Z","end":"2026-07-02T05:13:52.098555Z","steps":["trace[2024578052] 'read index received' (duration: 300.865585ms)","trace[2024578052] 'applied index is now lower than readState.Index' (duration: 244.824µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:52.098691Z","caller":"traceutil/trace.go:171","msg":"trace[239614151] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"334.203144ms","start":"2026-07-02T05:13:51.764437Z","end":"2026-07-02T05:13:52.09864Z","steps":["trace[239614151] 'process raft request' (duration: 333.93518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:52.09894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:51.764406Z","time spent":"334.400157ms","remote":"127.0.0.1:48702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:13:52.098878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.354553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:13:52.099037Z","caller":"traceutil/trace.go:171","msg":"trace[484658170] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:5088; }","duration":"301.620417ms","start":"2026-07-02T05:13:51.797393Z","end":"2026-07-02T05:13:52.099013Z","steps":["trace[484658170] 'agreement among raft nodes before linearized reading' (duration: 301.339112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:52.099148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:51.797372Z","time spent":"301.75715ms","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:14:01.481343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.97426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T05:14:01.481509Z","caller":"traceutil/trace.go:171","msg":"trace[2013145162] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:5157; }","duration":"142.252214ms","start":"2026-07-02T05:14:01.339225Z","end":"2026-07-02T05:14:01.481478Z","steps":["trace[2013145162] 'count revisions from in-memory index tree' (duration: 141.707496ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:15:28.972296Z","caller":"traceutil/trace.go:171","msg":"trace[1748869140] transaction","detail":"{read_only:false; response_revision:5858; number_of_response:1; }","duration":"113.672852ms","start":"2026-07-02T05:15:28.858568Z","end":"2026-07-02T05:15:28.972241Z","steps":["trace[1748869140] 'process raft request' (duration: 29.499574ms)","trace[1748869140] 'compare' (duration: 83.976345ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:16:11.342473Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3103} {"level":"info","ts":"2026-07-02T05:16:11.522907Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3103,"took":"176.712735ms","hash":3746785875,"current-db-size-bytes":58683392,"current-db-size":"59 MB","current-db-size-in-use-bytes":31023104,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T05:16:11.522993Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3746785875,"revision":3103,"compact-revision":-1} {"level":"info","ts":"2026-07-02T05:17:19.101075Z","caller":"traceutil/trace.go:171","msg":"trace[608289334] transaction","detail":"{read_only:false; response_revision:6826; number_of_response:1; }","duration":"128.982831ms","start":"2026-07-02T05:17:18.972059Z","end":"2026-07-02T05:17:19.101042Z","steps":["trace[608289334] 'process raft request' (duration: 82.899122ms)","trace[608289334] 'compare' (duration: 45.960418ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:21:11.349584Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6162} {"level":"info","ts":"2026-07-02T05:21:11.441397Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6162,"took":"90.397462ms","hash":1655451183,"current-db-size-bytes":58683392,"current-db-size":"59 MB","current-db-size-in-use-bytes":18325504,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-02T05:21:11.441456Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1655451183,"revision":6162,"compact-revision":3103}