{"level":"warn","ts":"2026-07-29T00:49:12.809835Z","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-29T00:49:12.810045Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140: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-29T00:49:12.810234Z","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-29T00:49:12.810291Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-07-29T00:49:12.810319Z","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-29T00:49:12.811756Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-07-29T00:49:12.812069Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140: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-29T00:49:12.820861Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.20118ms"} {"level":"info","ts":"2026-07-29T00:49:12.826124Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-07-29T00:49:12.826325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-07-29T00:49:12.826444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-07-29T00:49:12.826479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-29T00:49:12.826491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-07-29T00:49:12.826535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-07-29T00:49:12.83515Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-29T00:49:12.836734Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-29T00:49:12.840625Z","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-29T00:49:12.844268Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-29T00:49:12.844661Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-29T00:49:12.844981Z","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-29T00:49:12.845169Z","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-29T00:49:12.845308Z","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-29T00:49:12.846101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-07-29T00:49:12.84782Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-07-29T00:49:12.849687Z","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-29T00:49:12.849796Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-07-29T00:49:12.849836Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-07-29T00:49:12.849982Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-29T00:49:12.85004Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-29T00:49:13.326777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-07-29T00:49:13.326849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-07-29T00:49:13.326871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-07-29T00:49:13.326886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-07-29T00:49:13.326893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-07-29T00:49:13.326901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-07-29T00:49:13.326911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-07-29T00:49:13.329067Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T00:49:13.331409Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-07-29T00:49:13.331619Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-29T00:49:13.331698Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-29T00:49:13.33348Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T00:49:13.333585Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T00:49:13.333615Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T00:49:13.333667Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-29T00:49:13.333731Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-29T00:49:13.335582Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-29T00:49:13.335848Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-07-29T00:49:14.770306Z","caller":"traceutil/trace.go:171","msg":"trace[1590299379] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"117.56402ms","start":"2026-07-29T00:49:14.652714Z","end":"2026-07-29T00:49:14.770278Z","steps":["trace[1590299379] 'read index received' (duration: 7.541405ms)","trace[1590299379] 'applied index is now lower than readState.Index' (duration: 110.021155ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:49:14.770329Z","caller":"traceutil/trace.go:171","msg":"trace[857943096] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"127.103257ms","start":"2026-07-29T00:49:14.64319Z","end":"2026-07-29T00:49:14.770293Z","steps":["trace[857943096] 'process raft request' (duration: 108.507253ms)","trace[857943096] 'compare' (duration: 18.441367ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:49:14.77051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.76031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-29T00:49:14.770839Z","caller":"traceutil/trace.go:171","msg":"trace[1746368542] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:3; }","duration":"118.109626ms","start":"2026-07-29T00:49:14.652701Z","end":"2026-07-29T00:49:14.770811Z","steps":["trace[1746368542] 'agreement among raft nodes before linearized reading' (duration: 117.674285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:49:21.542182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.509126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:49:21.542299Z","caller":"traceutil/trace.go:171","msg":"trace[1105954105] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:293; }","duration":"217.698595ms","start":"2026-07-29T00:49:21.324572Z","end":"2026-07-29T00:49:21.54227Z","steps":["trace[1105954105] 'range keys from in-memory index tree' (duration: 217.387771ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:49:29.941996Z","caller":"traceutil/trace.go:171","msg":"trace[1565384078] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:384; }","duration":"323.803426ms","start":"2026-07-29T00:49:29.618161Z","end":"2026-07-29T00:49:29.941965Z","steps":["trace[1565384078] 'read index received' (duration: 323.260025ms)","trace[1565384078] 'applied index is now lower than readState.Index' (duration: 541.831µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:49:29.942063Z","caller":"traceutil/trace.go:171","msg":"trace[988274750] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"327.47701ms","start":"2026-07-29T00:49:29.614535Z","end":"2026-07-29T00:49:29.942012Z","steps":["trace[988274750] 'process raft request' (duration: 327.019072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:49:29.942232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.922877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-29T00:49:29.942247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.656099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-29T00:49:29.942266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.163856ms","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-29T00:49:29.942283Z","caller":"traceutil/trace.go:171","msg":"trace[1975854647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"293.030801ms","start":"2026-07-29T00:49:29.64924Z","end":"2026-07-29T00:49:29.94227Z","steps":["trace[1975854647] 'agreement among raft nodes before linearized reading' (duration: 292.932527ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:49:29.942302Z","caller":"traceutil/trace.go:171","msg":"trace[524170290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:371; }","duration":"285.239459ms","start":"2026-07-29T00:49:29.657048Z","end":"2026-07-29T00:49:29.942288Z","steps":["trace[524170290] 'agreement among raft nodes before linearized reading' (duration: 285.179787ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:49:29.942304Z","caller":"traceutil/trace.go:171","msg":"trace[697501329] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:371; }","duration":"203.700271ms","start":"2026-07-29T00:49:29.738578Z","end":"2026-07-29T00:49:29.942278Z","steps":["trace[697501329] 'agreement among raft nodes before linearized reading' (duration: 203.597107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:49:29.942193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.995524ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:49:29.94249Z","caller":"traceutil/trace.go:171","msg":"trace[881282391] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:371; }","duration":"324.314446ms","start":"2026-07-29T00:49:29.618151Z","end":"2026-07-29T00:49:29.942465Z","steps":["trace[881282391] 'agreement among raft nodes before linearized reading' (duration: 323.955002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:49:29.944455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:49:29.614509Z","time spent":"327.665867ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T00:49:30.08212Z","caller":"traceutil/trace.go:171","msg":"trace[1785708874] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"135.509325ms","start":"2026-07-29T00:49:29.946573Z","end":"2026-07-29T00:49:30.082082Z","steps":["trace[1785708874] 'process raft request' (duration: 102.684315ms)","trace[1785708874] 'compare' (duration: 32.283999ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:49:30.664299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.469404ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:49:30.664507Z","caller":"traceutil/trace.go:171","msg":"trace[447981932] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:372; }","duration":"338.699323ms","start":"2026-07-29T00:49:30.325783Z","end":"2026-07-29T00:49:30.664483Z","steps":["trace[447981932] 'range keys from in-memory index tree' (duration: 338.445363ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:49:30.664824Z","caller":"traceutil/trace.go:171","msg":"trace[1866332823] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"717.889825ms","start":"2026-07-29T00:49:29.946896Z","end":"2026-07-29T00:49:30.664786Z","steps":["trace[1866332823] 'process raft request' (duration: 717.320043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:49:30.665019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:49:29.946887Z","time spent":"718.00116ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T00:49:30.665677Z","caller":"traceutil/trace.go:171","msg":"trace[685039295] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"715.067945ms","start":"2026-07-29T00:49:29.950562Z","end":"2026-07-29T00:49:30.66563Z","steps":["trace[685039295] 'process raft request' (duration: 713.820088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:49:30.665999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:49:29.950541Z","time spent":"715.257153ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T00:49:31.38287Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555607475398802794,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-29T00:49:31.883357Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555607475398802794,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-29T00:49:32.228555Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.55197561s","expected-duration":"1s"} {"level":"info","ts":"2026-07-29T00:49:32.229127Z","caller":"traceutil/trace.go:171","msg":"trace[1793595574] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"1.552698248s","start":"2026-07-29T00:49:30.676396Z","end":"2026-07-29T00:49:32.229094Z","steps":["trace[1793595574] 'process raft request' (duration: 1.55250722s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:49:32.22933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:49:30.676362Z","time spent":"1.552858284s","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T00:49:32.242986Z","caller":"traceutil/trace.go:171","msg":"trace[309048056] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:388; }","duration":"1.360171698s","start":"2026-07-29T00:49:30.88279Z","end":"2026-07-29T00:49:32.242961Z","steps":["trace[309048056] 'read index received' (duration: 1.346147943s)","trace[309048056] 'applied index is now lower than readState.Index' (duration: 14.022355ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:49:32.243191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.360384788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-29T00:49:32.243223Z","caller":"traceutil/trace.go:171","msg":"trace[414371881] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:375; }","duration":"1.360494952s","start":"2026-07-29T00:49:30.882721Z","end":"2026-07-29T00:49:32.243216Z","steps":["trace[414371881] 'agreement among raft nodes before linearized reading' (duration: 1.360343926s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:49:32.243254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:49:30.882655Z","time spent":"1.360590465s","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-29T00:49:32.24344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.085589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-29T00:49:32.243507Z","caller":"traceutil/trace.go:171","msg":"trace[1321332993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:375; }","duration":"280.3613ms","start":"2026-07-29T00:49:31.96313Z","end":"2026-07-29T00:49:32.243491Z","steps":["trace[1321332993] 'agreement among raft nodes before linearized reading' (duration: 279.989706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:49:32.243531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.078431ms","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-29T00:49:32.244091Z","caller":"traceutil/trace.go:171","msg":"trace[2109384251] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:375; }","duration":"156.657792ms","start":"2026-07-29T00:49:32.08736Z","end":"2026-07-29T00:49:32.244017Z","steps":["trace[2109384251] 'agreement among raft nodes before linearized reading' (duration: 156.0512ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:49:53.080424Z","caller":"traceutil/trace.go:171","msg":"trace[28775248] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"303.611571ms","start":"2026-07-29T00:49:52.776787Z","end":"2026-07-29T00:49:53.080398Z","steps":["trace[28775248] 'process raft request' (duration: 303.442496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:49:53.080593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:49:52.776761Z","time spent":"303.747265ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T00:49:53.08412Z","caller":"traceutil/trace.go:171","msg":"trace[198838129] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"134.605784ms","start":"2026-07-29T00:49:52.949487Z","end":"2026-07-29T00:49:53.084093Z","steps":["trace[198838129] 'process raft request' (duration: 134.105959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:49:53.337926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.704343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:49:53.338025Z","caller":"traceutil/trace.go:171","msg":"trace[1311978336] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:490; }","duration":"118.893048ms","start":"2026-07-29T00:49:53.219108Z","end":"2026-07-29T00:49:53.338001Z","steps":["trace[1311978336] 'range keys from in-memory index tree' (duration: 118.586989ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:49:53.778802Z","caller":"traceutil/trace.go:171","msg":"trace[1869508148] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"212.903749ms","start":"2026-07-29T00:49:53.565867Z","end":"2026-07-29T00:49:53.778771Z","steps":["trace[1869508148] 'process raft request' (duration: 212.737264ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:49:53.786033Z","caller":"traceutil/trace.go:171","msg":"trace[93712827] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"208.328486ms","start":"2026-07-29T00:49:53.577664Z","end":"2026-07-29T00:49:53.785993Z","steps":["trace[93712827] 'process raft request' (duration: 207.859302ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:49:53.786055Z","caller":"traceutil/trace.go:171","msg":"trace[509979303] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"219.800581ms","start":"2026-07-29T00:49:53.56622Z","end":"2026-07-29T00:49:53.786021Z","steps":["trace[509979303] 'process raft request' (duration: 219.144951ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:49:53.786271Z","caller":"traceutil/trace.go:171","msg":"trace[1446855453] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"219.987276ms","start":"2026-07-29T00:49:53.566268Z","end":"2026-07-29T00:49:53.786255Z","steps":["trace[1446855453] 'process raft request' (duration: 219.209202ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:49:53.786376Z","caller":"traceutil/trace.go:171","msg":"trace[2127964757] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"207.930335ms","start":"2026-07-29T00:49:53.578435Z","end":"2026-07-29T00:49:53.786365Z","steps":["trace[2127964757] 'process raft request' (duration: 207.130181ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:49:53.786463Z","caller":"traceutil/trace.go:171","msg":"trace[1677639384] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"205.7277ms","start":"2026-07-29T00:49:53.580726Z","end":"2026-07-29T00:49:53.786454Z","steps":["trace[1677639384] 'process raft request' (duration: 205.051821ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:49:53.786482Z","caller":"traceutil/trace.go:171","msg":"trace[292165616] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"205.773552ms","start":"2026-07-29T00:49:53.580666Z","end":"2026-07-29T00:49:53.78644Z","steps":["trace[292165616] 'process raft request' (duration: 205.04428ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:49:53.78638Z","caller":"traceutil/trace.go:171","msg":"trace[1587608423] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"205.71597ms","start":"2026-07-29T00:49:53.580646Z","end":"2026-07-29T00:49:53.786362Z","steps":["trace[1587608423] 'process raft request' (duration: 205.019789ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:49:53.786632Z","caller":"traceutil/trace.go:171","msg":"trace[1756759153] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"192.082982ms","start":"2026-07-29T00:49:53.594539Z","end":"2026-07-29T00:49:53.786622Z","steps":["trace[1756759153] 'process raft request' (duration: 191.31499ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:49:53.786778Z","caller":"traceutil/trace.go:171","msg":"trace[1187921766] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"192.084282ms","start":"2026-07-29T00:49:53.594647Z","end":"2026-07-29T00:49:53.786731Z","steps":["trace[1187921766] 'process raft request' (duration: 191.29734ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:49:53.787743Z","caller":"traceutil/trace.go:171","msg":"trace[1272800384] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:510; }","duration":"140.743514ms","start":"2026-07-29T00:49:53.646983Z","end":"2026-07-29T00:49:53.787727Z","steps":["trace[1272800384] 'read index received' (duration: 131.604828ms)","trace[1272800384] 'applied index is now lower than readState.Index' (duration: 9.137416ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:49:53.787866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.867888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:49:53.787803Z","caller":"traceutil/trace.go:171","msg":"trace[1488482245] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"181.03596ms","start":"2026-07-29T00:49:53.606742Z","end":"2026-07-29T00:49:53.787778Z","steps":["trace[1488482245] 'process raft request' (duration: 180.084972ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:49:53.787943Z","caller":"traceutil/trace.go:171","msg":"trace[1593394464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:502; }","duration":"140.963601ms","start":"2026-07-29T00:49:53.646934Z","end":"2026-07-29T00:49:53.787897Z","steps":["trace[1593394464] 'agreement among raft nodes before linearized reading' (duration: 140.856258ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:49:57.60214Z","caller":"traceutil/trace.go:171","msg":"trace[732833475] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"206.891008ms","start":"2026-07-29T00:49:57.395215Z","end":"2026-07-29T00:49:57.602106Z","steps":["trace[732833475] 'process raft request' (duration: 205.806057ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:49:57.767961Z","caller":"traceutil/trace.go:171","msg":"trace[928486317] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"102.24471ms","start":"2026-07-29T00:49:57.665691Z","end":"2026-07-29T00:49:57.767936Z","steps":["trace[928486317] 'process raft request' (duration: 100.594983ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:50:00.330644Z","caller":"traceutil/trace.go:171","msg":"trace[1787336872] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"190.808376ms","start":"2026-07-29T00:50:00.139805Z","end":"2026-07-29T00:50:00.330613Z","steps":["trace[1787336872] 'process raft request' (duration: 189.988934ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:50:00.561283Z","caller":"traceutil/trace.go:171","msg":"trace[1346314374] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"125.30094ms","start":"2026-07-29T00:50:00.43589Z","end":"2026-07-29T00:50:00.561191Z","steps":["trace[1346314374] 'read index received' (duration: 91.57496ms)","trace[1346314374] 'applied index is now lower than readState.Index' (duration: 33.645967ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:50:00.56143Z","caller":"traceutil/trace.go:171","msg":"trace[1091681066] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"249.013535ms","start":"2026-07-29T00:50:00.312343Z","end":"2026-07-29T00:50:00.561356Z","steps":["trace[1091681066] 'process raft request' (duration: 215.097781ms)","trace[1091681066] 'compare' (duration: 32.954508ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:50:00.562612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.561853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-29T00:50:00.562706Z","caller":"traceutil/trace.go:171","msg":"trace[1891566783] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:600; }","duration":"126.717638ms","start":"2026-07-29T00:50:00.435964Z","end":"2026-07-29T00:50:00.562682Z","steps":["trace[1891566783] 'agreement among raft nodes before linearized reading' (duration: 126.154392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:50:00.562926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.613352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-29T00:50:00.563019Z","caller":"traceutil/trace.go:171","msg":"trace[752093779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:600; }","duration":"120.737625ms","start":"2026-07-29T00:50:00.442242Z","end":"2026-07-29T00:50:00.56298Z","steps":["trace[752093779] 'agreement among raft nodes before linearized reading' (duration: 120.492849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:50:00.566973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.062535ms","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-29T00:50:00.567175Z","caller":"traceutil/trace.go:171","msg":"trace[614255554] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:600; }","duration":"131.185998ms","start":"2026-07-29T00:50:00.43585Z","end":"2026-07-29T00:50:00.567036Z","steps":["trace[614255554] 'agreement among raft nodes before linearized reading' (duration: 125.543016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:50:01.474744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.466927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T00:50:01.475073Z","caller":"traceutil/trace.go:171","msg":"trace[735652589] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"905.793365ms","start":"2026-07-29T00:50:00.569258Z","end":"2026-07-29T00:50:01.475051Z","steps":["trace[735652589] 'process raft request' (duration: 187.716562ms)","trace[735652589] 'compare' (duration: 716.829901ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:50:01.475211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:50:00.569236Z","time spent":"905.926218ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T00:50:01.691504Z","caller":"traceutil/trace.go:171","msg":"trace[1134064807] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.119139835s","start":"2026-07-29T00:50:00.572331Z","end":"2026-07-29T00:50:01.691471Z","steps":["trace[1134064807] 'process raft request' (duration: 1.119006621s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:50:01.691715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:50:00.572321Z","time spent":"1.119274188s","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T00:50:01.691963Z","caller":"traceutil/trace.go:171","msg":"trace[1708059058] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.120900441s","start":"2026-07-29T00:50:00.571043Z","end":"2026-07-29T00:50:01.691943Z","steps":["trace[1708059058] 'process raft request' (duration: 1.091004854s)","trace[1708059058] 'compare' (duration: 28.934591ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:50:01.692039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:50:00.571019Z","time spent":"1.120984634s","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T00:50:01.692097Z","caller":"traceutil/trace.go:171","msg":"trace[1332108558] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"1.056459754s","start":"2026-07-29T00:50:00.635629Z","end":"2026-07-29T00:50:01.692088Z","steps":["trace[1332108558] 'process raft request' (duration: 1.055814637s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:50:01.692142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:50:00.635603Z","time spent":"1.056520356s","remote":"127.0.0.1:39730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-29T00:50:01.692252Z","caller":"traceutil/trace.go:171","msg":"trace[1996992609] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:627; }","duration":"1.043819724s","start":"2026-07-29T00:50:00.648424Z","end":"2026-07-29T00:50:01.692243Z","steps":["trace[1996992609] 'read index received' (duration: 108.584059ms)","trace[1996992609] 'applied index is now lower than readState.Index' (duration: 935.234455ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:50:01.737328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088866365s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:50:01.737435Z","caller":"traceutil/trace.go:171","msg":"trace[517888772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"1.0890225s","start":"2026-07-29T00:50:00.648386Z","end":"2026-07-29T00:50:01.737409Z","steps":["trace[517888772] 'agreement among raft nodes before linearized reading' (duration: 1.043929987s)","trace[517888772] 'range keys from in-memory index tree' (duration: 44.939869ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:50:01.73747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.352444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-29T00:50:01.737489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:50:00.648374Z","time spent":"1.089101191s","remote":"127.0.0.1:39224","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-29T00:50:01.737521Z","caller":"traceutil/trace.go:171","msg":"trace[1139332378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"614.444766ms","start":"2026-07-29T00:50:01.12306Z","end":"2026-07-29T00:50:01.737505Z","steps":["trace[1139332378] 'agreement among raft nodes before linearized reading' (duration: 614.250641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:50:01.737558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:50:01.123035Z","time spent":"614.515398ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-29T00:50:02.244696Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555607475398803232,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-29T00:50:02.745573Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555607475398803232,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-29T00:50:03.027883Z","caller":"traceutil/trace.go:171","msg":"trace[1620344835] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"1.285538541s","start":"2026-07-29T00:50:01.742316Z","end":"2026-07-29T00:50:03.027855Z","steps":["trace[1620344835] 'process raft request' (duration: 1.285372827s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:50:03.028062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:50:01.742285Z","time spent":"1.285684935s","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T00:50:03.368776Z","caller":"traceutil/trace.go:171","msg":"trace[849894372] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"1.624807224s","start":"2026-07-29T00:50:01.743936Z","end":"2026-07-29T00:50:03.368743Z","steps":["trace[849894372] 'read index received' (duration: 1.283914769s)","trace[849894372] 'applied index is now lower than readState.Index' (duration: 340.891335ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:50:03.368913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.624946357s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:50:03.368889Z","caller":"traceutil/trace.go:171","msg":"trace[299318896] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"1.231125612s","start":"2026-07-29T00:50:02.137703Z","end":"2026-07-29T00:50:03.368829Z","steps":["trace[299318896] 'process raft request' (duration: 1.213242716s)","trace[299318896] 'compare' (duration: 17.229179ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:50:03.368981Z","caller":"traceutil/trace.go:171","msg":"trace[286154883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"1.625067361s","start":"2026-07-29T00:50:01.743897Z","end":"2026-07-29T00:50:03.368964Z","steps":["trace[286154883] 'agreement among raft nodes before linearized reading' (duration: 1.624950618s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:50:03.369031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:50:01.74388Z","time spent":"1.625140842s","remote":"127.0.0.1:39218","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-29T00:50:03.369168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:50:02.137667Z","time spent":"1.231336858s","remote":"127.0.0.1:39324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T00:50:06.299359Z","caller":"traceutil/trace.go:171","msg":"trace[33190881] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"118.724948ms","start":"2026-07-29T00:50:06.180591Z","end":"2026-07-29T00:50:06.299316Z","steps":["trace[33190881] 'process raft request' (duration: 118.453372ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:50:08.687584Z","caller":"traceutil/trace.go:171","msg":"trace[1084145381] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"225.116007ms","start":"2026-07-29T00:50:08.462423Z","end":"2026-07-29T00:50:08.687539Z","steps":["trace[1084145381] 'process raft request' (duration: 224.598204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:50:19.75569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555607475398803557,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-29T00:50:19.971697Z","caller":"traceutil/trace.go:171","msg":"trace[899080917] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"818.149778ms","start":"2026-07-29T00:50:19.153507Z","end":"2026-07-29T00:50:19.971657Z","steps":["trace[899080917] 'process raft request' (duration: 812.880491ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:50:19.971827Z","caller":"traceutil/trace.go:171","msg":"trace[812291744] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:801; }","duration":"716.846971ms","start":"2026-07-29T00:50:19.254929Z","end":"2026-07-29T00:50:19.971776Z","steps":["trace[812291744] 'read index received' (duration: 708.869335ms)","trace[812291744] 'applied index is now lower than readState.Index' (duration: 7.975666ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:50:19.972577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.396697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-29T00:50:19.972649Z","caller":"traceutil/trace.go:171","msg":"trace[216191526] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:770; }","duration":"378.506889ms","start":"2026-07-29T00:50:19.59413Z","end":"2026-07-29T00:50:19.972637Z","steps":["trace[216191526] 'agreement among raft nodes before linearized reading' (duration: 378.369186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:50:19.972684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:50:19.594113Z","time spent":"378.56401ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-29T00:50:19.971945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:50:19.153488Z","time spent":"818.377413ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T00:50:19.972037Z","caller":"traceutil/trace.go:171","msg":"trace[84479680] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"445.476308ms","start":"2026-07-29T00:50:19.526525Z","end":"2026-07-29T00:50:19.972001Z","steps":["trace[84479680] 'process raft request' (duration: 445.061439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:50:19.972008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.074356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-tsmbs\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-29T00:50:19.973827Z","caller":"traceutil/trace.go:171","msg":"trace[865384901] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-tsmbs; range_end:; response_count:1; response_revision:770; }","duration":"718.830785ms","start":"2026-07-29T00:50:19.254912Z","end":"2026-07-29T00:50:19.973743Z","steps":["trace[865384901] 'agreement among raft nodes before linearized reading' (duration: 716.945943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:50:19.973938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:50:19.254892Z","time spent":"719.003229ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-tsmbs\" "} {"level":"warn","ts":"2026-07-29T00:50:19.974555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:50:19.526497Z","time spent":"446.440479ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T00:50:23.892439Z","caller":"traceutil/trace.go:171","msg":"trace[254398968] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"121.883548ms","start":"2026-07-29T00:50:23.770525Z","end":"2026-07-29T00:50:23.892409Z","steps":["trace[254398968] 'process raft request' (duration: 121.679763ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:50:23.89277Z","caller":"traceutil/trace.go:171","msg":"trace[165768277] linearizableReadLoop","detail":"{readStateIndex:844; appliedIndex:841; }","duration":"121.427068ms","start":"2026-07-29T00:50:23.771299Z","end":"2026-07-29T00:50:23.892726Z","steps":["trace[165768277] 'read index received' (duration: 39.359165ms)","trace[165768277] 'applied index is now lower than readState.Index' (duration: 82.066713ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:50:23.893013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.689104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/envoy-gateway-system/envoy-gateway-78446f96c9\" ","response":"range_response_count:1 size:5114"} {"level":"info","ts":"2026-07-29T00:50:23.89306Z","caller":"traceutil/trace.go:171","msg":"trace[318121536] range","detail":"{range_begin:/registry/replicasets/envoy-gateway-system/envoy-gateway-78446f96c9; range_end:; response_count:1; response_revision:810; }","duration":"121.763085ms","start":"2026-07-29T00:50:23.771284Z","end":"2026-07-29T00:50:23.893047Z","steps":["trace[318121536] 'agreement among raft nodes before linearized reading' (duration: 121.52455ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:50:23.893275Z","caller":"traceutil/trace.go:171","msg":"trace[1860570082] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"122.127453ms","start":"2026-07-29T00:50:23.771123Z","end":"2026-07-29T00:50:23.893251Z","steps":["trace[1860570082] 'process raft request' (duration: 121.226344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:50:24.136938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.085281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/envoy-gateway-system/envoy-gateway-78446f96c9\" ","response":"range_response_count:1 size:5159"} {"level":"info","ts":"2026-07-29T00:50:24.137144Z","caller":"traceutil/trace.go:171","msg":"trace[164811722] range","detail":"{range_begin:/registry/replicasets/envoy-gateway-system/envoy-gateway-78446f96c9; range_end:; response_count:1; response_revision:815; }","duration":"143.312435ms","start":"2026-07-29T00:50:23.993797Z","end":"2026-07-29T00:50:24.137109Z","steps":["trace[164811722] 'agreement among raft nodes before linearized reading' (duration: 142.951558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:50:24.137142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.60164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-29T00:50:24.13726Z","caller":"traceutil/trace.go:171","msg":"trace[862445999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:815; }","duration":"142.760544ms","start":"2026-07-29T00:50:23.994475Z","end":"2026-07-29T00:50:24.137235Z","steps":["trace[862445999] 'agreement among raft nodes before linearized reading' (duration: 142.385136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:50:24.137007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.386597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-07-29T00:50:24.137417Z","caller":"traceutil/trace.go:171","msg":"trace[1603625203] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:815; }","duration":"107.854117ms","start":"2026-07-29T00:50:24.029541Z","end":"2026-07-29T00:50:24.137395Z","steps":["trace[1603625203] 'agreement among raft nodes before linearized reading' (duration: 107.265105ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:50:51.512385Z","caller":"traceutil/trace.go:171","msg":"trace[1454394982] linearizableReadLoop","detail":"{readStateIndex:1213; appliedIndex:1212; }","duration":"391.64751ms","start":"2026-07-29T00:50:51.120701Z","end":"2026-07-29T00:50:51.512349Z","steps":["trace[1454394982] 'read index received' (duration: 391.415436ms)","trace[1454394982] 'applied index is now lower than readState.Index' (duration: 230.864µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:50:51.512556Z","caller":"traceutil/trace.go:171","msg":"trace[1787995015] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"400.341144ms","start":"2026-07-29T00:50:51.112196Z","end":"2026-07-29T00:50:51.512537Z","steps":["trace[1787995015] 'process raft request' (duration: 399.897177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:50:51.512701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:50:51.112161Z","time spent":"400.426375ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T00:50:51.513029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.252641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T00:50:51.513207Z","caller":"traceutil/trace.go:171","msg":"trace[336916412] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1174; }","duration":"392.503465ms","start":"2026-07-29T00:50:51.120634Z","end":"2026-07-29T00:50:51.513137Z","steps":["trace[336916412] 'agreement among raft nodes before linearized reading' (duration: 392.103688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:50:51.513275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:50:51.120609Z","time spent":"392.646277ms","remote":"127.0.0.1:39612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-29T00:50:51.521378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.273282ms","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-29T00:50:51.521502Z","caller":"traceutil/trace.go:171","msg":"trace[2033782247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1174; }","duration":"371.458416ms","start":"2026-07-29T00:50:51.150021Z","end":"2026-07-29T00:50:51.521479Z","steps":["trace[2033782247] 'agreement among raft nodes before linearized reading' (duration: 371.175911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:50:51.521563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:50:51.149999Z","time spent":"371.554717ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-29T00:51:43.084923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555607475398805728,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-29T00:51:43.450461Z","caller":"traceutil/trace.go:171","msg":"trace[820590611] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1924; }","duration":"866.051251ms","start":"2026-07-29T00:51:42.584347Z","end":"2026-07-29T00:51:43.450398Z","steps":["trace[820590611] 'read index received' (duration: 860.239663ms)","trace[820590611] 'applied index is now lower than readState.Index' (duration: 5.809358ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:51:43.45048Z","caller":"traceutil/trace.go:171","msg":"trace[469379419] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"932.732984ms","start":"2026-07-29T00:51:42.517709Z","end":"2026-07-29T00:51:43.450442Z","steps":["trace[469379419] 'process raft request' (duration: 926.948506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:51:43.450683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:51:42.517694Z","time spent":"932.890186ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T00:51:43.450851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.326492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-29T00:51:43.45091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.512208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d98e223d-62a2-42a0-9d03-2fa2cc157b65\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-29T00:51:43.450967Z","caller":"traceutil/trace.go:171","msg":"trace[969495298] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d98e223d-62a2-42a0-9d03-2fa2cc157b65; range_end:; response_count:1; response_revision:1848; }","duration":"866.621759ms","start":"2026-07-29T00:51:42.584326Z","end":"2026-07-29T00:51:43.450947Z","steps":["trace[969495298] 'agreement among raft nodes before linearized reading' (duration: 866.321195ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:51:43.450979Z","caller":"traceutil/trace.go:171","msg":"trace[1727637940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1848; }","duration":"854.476224ms","start":"2026-07-29T00:51:42.596468Z","end":"2026-07-29T00:51:43.450944Z","steps":["trace[1727637940] 'agreement among raft nodes before linearized reading' (duration: 854.219611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:51:43.451017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:51:42.584316Z","time spent":"866.690649ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d98e223d-62a2-42a0-9d03-2fa2cc157b65\" "} {"level":"warn","ts":"2026-07-29T00:51:43.451044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:51:42.596453Z","time spent":"854.574355ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-29T00:51:43.451251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.589463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-29T00:51:43.451296Z","caller":"traceutil/trace.go:171","msg":"trace[889736315] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1848; }","duration":"576.668154ms","start":"2026-07-29T00:51:42.874615Z","end":"2026-07-29T00:51:43.451283Z","steps":["trace[889736315] 'agreement among raft nodes before linearized reading' (duration: 576.554203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:51:43.451332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:51:42.874553Z","time spent":"576.770075ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-07-29T00:51:43.451451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.923109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-29T00:51:43.451496Z","caller":"traceutil/trace.go:171","msg":"trace[994283138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1848; }","duration":"854.98565ms","start":"2026-07-29T00:51:42.596498Z","end":"2026-07-29T00:51:43.451484Z","steps":["trace[994283138] 'agreement among raft nodes before linearized reading' (duration: 854.875258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:51:43.451528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:51:42.596488Z","time spent":"855.03266ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-29T00:51:43.451693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.493843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d98e223d-62a2-42a0-9d03-2fa2cc157b65\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-29T00:51:43.451729Z","caller":"traceutil/trace.go:171","msg":"trace[323641296] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d98e223d-62a2-42a0-9d03-2fa2cc157b65; range_end:; response_count:1; response_revision:1848; }","duration":"752.563313ms","start":"2026-07-29T00:51:42.699156Z","end":"2026-07-29T00:51:43.45172Z","steps":["trace[323641296] 'agreement among raft nodes before linearized reading' (duration: 752.474372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:51:43.451759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:51:42.699133Z","time spent":"752.618935ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d98e223d-62a2-42a0-9d03-2fa2cc157b65\" "} {"level":"warn","ts":"2026-07-29T00:51:43.451923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.290444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:51:43.451959Z","caller":"traceutil/trace.go:171","msg":"trace[579289705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1848; }","duration":"802.347464ms","start":"2026-07-29T00:51:42.649602Z","end":"2026-07-29T00:51:43.45195Z","steps":["trace[579289705] 'agreement among raft nodes before linearized reading' (duration: 802.293593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:51:43.451985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:51:42.649586Z","time spent":"802.393215ms","remote":"127.0.0.1:39218","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-29T00:51:43.74275Z","caller":"traceutil/trace.go:171","msg":"trace[674111507] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"284.302771ms","start":"2026-07-29T00:51:43.458394Z","end":"2026-07-29T00:51:43.742696Z","steps":["trace[674111507] 'process raft request' (duration: 268.471673ms)","trace[674111507] 'compare' (duration: 15.351752ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:51:43.742832Z","caller":"traceutil/trace.go:171","msg":"trace[1242972756] linearizableReadLoop","detail":"{readStateIndex:1929; appliedIndex:1926; }","duration":"260.196826ms","start":"2026-07-29T00:51:43.482604Z","end":"2026-07-29T00:51:43.742801Z","steps":["trace[1242972756] 'read index received' (duration: 244.282228ms)","trace[1242972756] 'applied index is now lower than readState.Index' (duration: 15.913558ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:51:43.742939Z","caller":"traceutil/trace.go:171","msg":"trace[912130035] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"281.262765ms","start":"2026-07-29T00:51:43.461648Z","end":"2026-07-29T00:51:43.74291Z","steps":["trace[912130035] 'process raft request' (duration: 281.082343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:51:43.743082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.462479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-29T00:51:43.74312Z","caller":"traceutil/trace.go:171","msg":"trace[607044790] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"284.155459ms","start":"2026-07-29T00:51:43.458957Z","end":"2026-07-29T00:51:43.743112Z","steps":["trace[607044790] 'process raft request' (duration: 283.646904ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:51:43.743148Z","caller":"traceutil/trace.go:171","msg":"trace[623785015] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1852; }","duration":"260.60013ms","start":"2026-07-29T00:51:43.482534Z","end":"2026-07-29T00:51:43.743134Z","steps":["trace[623785015] 'agreement among raft nodes before linearized reading' (duration: 260.398027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:51:43.7575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.363282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-29T00:51:43.757559Z","caller":"traceutil/trace.go:171","msg":"trace[1458649308] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1853; }","duration":"224.518214ms","start":"2026-07-29T00:51:43.533022Z","end":"2026-07-29T00:51:43.75754Z","steps":["trace[1458649308] 'agreement among raft nodes before linearized reading' (duration: 224.267021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:51:43.757641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.198697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:51:43.757735Z","caller":"traceutil/trace.go:171","msg":"trace[716535231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1853; }","duration":"107.351448ms","start":"2026-07-29T00:51:43.650364Z","end":"2026-07-29T00:51:43.757716Z","steps":["trace[716535231] 'agreement among raft nodes before linearized reading' (duration: 107.200586ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:51:43.973352Z","caller":"traceutil/trace.go:171","msg":"trace[1542801836] linearizableReadLoop","detail":"{readStateIndex:1931; appliedIndex:1930; }","duration":"150.326076ms","start":"2026-07-29T00:51:43.823002Z","end":"2026-07-29T00:51:43.973328Z","steps":["trace[1542801836] 'read index received' (duration: 81.954869ms)","trace[1542801836] 'applied index is now lower than readState.Index' (duration: 68.369607ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:51:43.973555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.542319ms","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-29T00:51:43.973589Z","caller":"traceutil/trace.go:171","msg":"trace[1775535408] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1854; }","duration":"150.64418ms","start":"2026-07-29T00:51:43.822936Z","end":"2026-07-29T00:51:43.97358Z","steps":["trace[1775535408] 'agreement among raft nodes before linearized reading' (duration: 150.477518ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:51:43.973478Z","caller":"traceutil/trace.go:171","msg":"trace[990889575] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"210.969363ms","start":"2026-07-29T00:51:43.762447Z","end":"2026-07-29T00:51:43.973417Z","steps":["trace[990889575] 'process raft request' (duration: 142.488295ms)","trace[990889575] 'compare' (duration: 68.195865ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:51:44.443461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.65339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-07-29T00:51:44.443562Z","caller":"traceutil/trace.go:171","msg":"trace[1311612380] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1856; }","duration":"268.891753ms","start":"2026-07-29T00:51:44.174648Z","end":"2026-07-29T00:51:44.44354Z","steps":["trace[1311612380] 'range keys from in-memory index tree' (duration: 268.365887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:51:44.443495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.64283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T00:51:44.443831Z","caller":"traceutil/trace.go:171","msg":"trace[656831241] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1856; }","duration":"150.052476ms","start":"2026-07-29T00:51:44.29376Z","end":"2026-07-29T00:51:44.443812Z","steps":["trace[656831241] 'count revisions from in-memory index tree' (duration: 149.343646ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:51:52.160203Z","caller":"traceutil/trace.go:171","msg":"trace[569512941] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"136.524877ms","start":"2026-07-29T00:51:52.023652Z","end":"2026-07-29T00:51:52.160177Z","steps":["trace[569512941] 'process raft request' (duration: 136.321755ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:51:52.207707Z","caller":"traceutil/trace.go:171","msg":"trace[1583835403] linearizableReadLoop","detail":"{readStateIndex:2016; appliedIndex:2014; }","duration":"146.130086ms","start":"2026-07-29T00:51:52.061553Z","end":"2026-07-29T00:51:52.207683Z","steps":["trace[1583835403] 'read index received' (duration: 98.461156ms)","trace[1583835403] 'applied index is now lower than readState.Index' (duration: 47.66737ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:51:52.207816Z","caller":"traceutil/trace.go:171","msg":"trace[29101863] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"182.034292ms","start":"2026-07-29T00:51:52.025736Z","end":"2026-07-29T00:51:52.20777Z","steps":["trace[29101863] 'process raft request' (duration: 181.798929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:51:52.207875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.317338ms","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-29T00:51:52.207914Z","caller":"traceutil/trace.go:171","msg":"trace[687910389] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1936; }","duration":"146.371839ms","start":"2026-07-29T00:51:52.061533Z","end":"2026-07-29T00:51:52.207905Z","steps":["trace[687910389] 'agreement among raft nodes before linearized reading' (duration: 146.238647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:18.852787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.658496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T00:52:18.852933Z","caller":"traceutil/trace.go:171","msg":"trace[690699874] linearizableReadLoop","detail":"{readStateIndex:2184; appliedIndex:2183; }","duration":"327.306892ms","start":"2026-07-29T00:52:18.525604Z","end":"2026-07-29T00:52:18.852911Z","steps":["trace[690699874] 'read index received' (duration: 59.411µs)","trace[690699874] 'applied index is now lower than readState.Index' (duration: 327.245561ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:52:18.853067Z","caller":"traceutil/trace.go:171","msg":"trace[1690384777] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"481.32955ms","start":"2026-07-29T00:52:18.371636Z","end":"2026-07-29T00:52:18.852966Z","steps":["trace[1690384777] 'process raft request' (duration: 65.36457ms)","trace[1690384777] 'compare' (duration: 414.941949ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:52:18.853145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.487824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T00:52:18.853239Z","caller":"traceutil/trace.go:171","msg":"trace[2128502485] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2099; }","duration":"327.672416ms","start":"2026-07-29T00:52:18.52554Z","end":"2026-07-29T00:52:18.853212Z","steps":["trace[2128502485] 'agreement among raft nodes before linearized reading' (duration: 327.459084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:18.853261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:52:18.3716Z","time spent":"481.557942ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T00:52:18.853287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:52:18.525513Z","time spent":"327.764137ms","remote":"127.0.0.1:39724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":3,"response size":30,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-29T00:52:18.86875Z","caller":"traceutil/trace.go:171","msg":"trace[1829545987] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"225.392728ms","start":"2026-07-29T00:52:18.643317Z","end":"2026-07-29T00:52:18.86871Z","steps":["trace[1829545987] 'process raft request' (duration: 225.192956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:18.868798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.620508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"warn","ts":"2026-07-29T00:52:18.868814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.899533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-29T00:52:18.86892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.604796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-29T00:52:18.868932Z","caller":"traceutil/trace.go:171","msg":"trace[1253666154] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2100; }","duration":"295.744789ms","start":"2026-07-29T00:52:18.573095Z","end":"2026-07-29T00:52:18.86884Z","steps":["trace[1253666154] 'agreement among raft nodes before linearized reading' (duration: 295.510677ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:52:18.868984Z","caller":"traceutil/trace.go:171","msg":"trace[1478498972] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2100; }","duration":"206.708756ms","start":"2026-07-29T00:52:18.662261Z","end":"2026-07-29T00:52:18.86897Z","steps":["trace[1478498972] 'agreement among raft nodes before linearized reading' (duration: 206.565955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:18.869322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.714116ms","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":"info","ts":"2026-07-29T00:52:18.869422Z","caller":"traceutil/trace.go:171","msg":"trace[837716623] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2100; }","duration":"245.059659ms","start":"2026-07-29T00:52:18.624345Z","end":"2026-07-29T00:52:18.869405Z","steps":["trace[837716623] 'agreement among raft nodes before linearized reading' (duration: 244.557094ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:52:18.870027Z","caller":"traceutil/trace.go:171","msg":"trace[776349111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2100; }","duration":"185.018334ms","start":"2026-07-29T00:52:18.683838Z","end":"2026-07-29T00:52:18.868856Z","steps":["trace[776349111] 'agreement among raft nodes before linearized reading' (duration: 184.778132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:19.106383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.67592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T00:52:19.106649Z","caller":"traceutil/trace.go:171","msg":"trace[1461431685] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"231.882402ms","start":"2026-07-29T00:52:18.874725Z","end":"2026-07-29T00:52:19.106607Z","steps":["trace[1461431685] 'process raft request' (duration: 77.13566ms)","trace[1461431685] 'compare' (duration: 153.371857ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:52:19.106706Z","caller":"traceutil/trace.go:171","msg":"trace[650210664] linearizableReadLoop","detail":"{readStateIndex:2188; appliedIndex:2185; }","duration":"188.460008ms","start":"2026-07-29T00:52:18.918229Z","end":"2026-07-29T00:52:19.106689Z","steps":["trace[650210664] 'read index received' (duration: 3.044142ms)","trace[650210664] 'applied index is now lower than readState.Index' (duration: 185.414406ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:52:19.106897Z","caller":"traceutil/trace.go:171","msg":"trace[1752091942] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"222.165643ms","start":"2026-07-29T00:52:18.884722Z","end":"2026-07-29T00:52:19.106888Z","steps":["trace[1752091942] 'process raft request' (duration: 221.823609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:19.109107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.895622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:52:19.109296Z","caller":"traceutil/trace.go:171","msg":"trace[1797907877] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2103; }","duration":"191.019363ms","start":"2026-07-29T00:52:18.918206Z","end":"2026-07-29T00:52:19.109225Z","steps":["trace[1797907877] 'agreement among raft nodes before linearized reading' (duration: 188.69921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:20.52157Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555607475398806341,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-29T00:52:20.797021Z","caller":"traceutil/trace.go:171","msg":"trace[1989250966] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"779.524431ms","start":"2026-07-29T00:52:20.01747Z","end":"2026-07-29T00:52:20.796994Z","steps":["trace[1989250966] 'process raft request' (duration: 779.287478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:20.797185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:52:20.017436Z","time spent":"779.661552ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T00:52:21.164154Z","caller":"traceutil/trace.go:171","msg":"trace[193129525] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2190; }","duration":"1.143129674s","start":"2026-07-29T00:52:20.020995Z","end":"2026-07-29T00:52:21.164125Z","steps":["trace[193129525] 'read index received' (duration: 775.752722ms)","trace[193129525] 'applied index is now lower than readState.Index' (duration: 367.375912ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:52:21.164286Z","caller":"traceutil/trace.go:171","msg":"trace[6228182] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"888.310892ms","start":"2026-07-29T00:52:20.275961Z","end":"2026-07-29T00:52:21.164272Z","steps":["trace[6228182] 'process raft request' (duration: 793.625901ms)","trace[6228182] 'compare' (duration: 94.138466ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:52:21.164396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:52:20.2759Z","time spent":"888.410233ms","remote":"127.0.0.1:39518","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-29T00:52:21.164606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048533762s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-29T00:52:21.16469Z","caller":"traceutil/trace.go:171","msg":"trace[1558533994] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2107; }","duration":"1.048667704s","start":"2026-07-29T00:52:20.115998Z","end":"2026-07-29T00:52:21.164666Z","steps":["trace[1558533994] 'agreement among raft nodes before linearized reading' (duration: 1.048405991s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:21.164738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:52:20.115981Z","time spent":"1.048747694s","remote":"127.0.0.1:36124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-29T00:52:21.164841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.956527ms","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-29T00:52:21.164884Z","caller":"traceutil/trace.go:171","msg":"trace[445656589] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2107; }","duration":"308.000348ms","start":"2026-07-29T00:52:20.856872Z","end":"2026-07-29T00:52:21.164872Z","steps":["trace[445656589] 'agreement among raft nodes before linearized reading' (duration: 307.862786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:21.164916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:52:20.856839Z","time spent":"308.068599ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-29T00:52:21.165056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.672896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c69c7584783736\" ","response":"range_response_count:1 size:846"} {"level":"warn","ts":"2026-07-29T00:52:21.165089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144103675s","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-29T00:52:21.165094Z","caller":"traceutil/trace.go:171","msg":"trace[700893595] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18c69c7584783736; range_end:; response_count:1; response_revision:2107; }","duration":"313.767567ms","start":"2026-07-29T00:52:20.851315Z","end":"2026-07-29T00:52:21.165083Z","steps":["trace[700893595] 'agreement among raft nodes before linearized reading' (duration: 313.667775ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:52:21.165122Z","caller":"traceutil/trace.go:171","msg":"trace[1182587897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2107; }","duration":"1.144179156s","start":"2026-07-29T00:52:20.020932Z","end":"2026-07-29T00:52:21.165111Z","steps":["trace[1182587897] 'agreement among raft nodes before linearized reading' (duration: 1.144094555s)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:21.165138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:52:20.851291Z","time spent":"313.838077ms","remote":"127.0.0.1:39304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c69c7584783736\" "} {"level":"warn","ts":"2026-07-29T00:52:21.165344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.121399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T00:52:21.165378Z","caller":"traceutil/trace.go:171","msg":"trace[1197870003] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2107; }","duration":"596.197071ms","start":"2026-07-29T00:52:20.569168Z","end":"2026-07-29T00:52:21.165365Z","steps":["trace[1197870003] 'agreement among raft nodes before linearized reading' (duration: 596.12508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:21.165442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.231869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-07-29T00:52:21.165525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:52:20.569147Z","time spent":"596.364692ms","remote":"127.0.0.1:39330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":25,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-07-29T00:52:21.16553Z","caller":"traceutil/trace.go:171","msg":"trace[328924527] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2107; }","duration":"240.36091ms","start":"2026-07-29T00:52:20.925138Z","end":"2026-07-29T00:52:21.165498Z","steps":["trace[328924527] 'agreement among raft nodes before linearized reading' (duration: 240.145118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:21.165712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.698462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T00:52:21.165747Z","caller":"traceutil/trace.go:171","msg":"trace[218798425] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2107; }","duration":"687.771642ms","start":"2026-07-29T00:52:20.477963Z","end":"2026-07-29T00:52:21.165735Z","steps":["trace[218798425] 'agreement among raft nodes before linearized reading' (duration: 687.707432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:21.165773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:52:20.477944Z","time spent":"687.821302ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-07-29T00:52:21.165886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.350771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-29T00:52:21.165965Z","caller":"traceutil/trace.go:171","msg":"trace[1667284603] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2107; }","duration":"813.469621ms","start":"2026-07-29T00:52:20.352481Z","end":"2026-07-29T00:52:21.165951Z","steps":["trace[1667284603] 'agreement among raft nodes before linearized reading' (duration: 813.32292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:21.165999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:52:20.352459Z","time spent":"813.530962ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-29T00:52:21.165152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:52:20.020901Z","time spent":"1.144245156s","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-29T00:52:21.922895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.003591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T00:52:21.9232Z","caller":"traceutil/trace.go:171","msg":"trace[419510089] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"753.014417ms","start":"2026-07-29T00:52:21.170147Z","end":"2026-07-29T00:52:21.923161Z","steps":["trace[419510089] 'process raft request' (duration: 467.502671ms)","trace[419510089] 'compare' (duration: 284.556946ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:52:21.92328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:52:21.170127Z","time spent":"753.119258ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T00:52:21.924396Z","caller":"traceutil/trace.go:171","msg":"trace[381063841] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"753.563882ms","start":"2026-07-29T00:52:21.170772Z","end":"2026-07-29T00:52:21.924336Z","steps":["trace[381063841] 'process raft request' (duration: 753.182417ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:52:21.924441Z","caller":"traceutil/trace.go:171","msg":"trace[931261147] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"753.499781ms","start":"2026-07-29T00:52:21.170921Z","end":"2026-07-29T00:52:21.924421Z","steps":["trace[931261147] 'process raft request' (duration: 753.292309ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:52:21.924428Z","caller":"traceutil/trace.go:171","msg":"trace[340881979] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"752.957166ms","start":"2026-07-29T00:52:21.171434Z","end":"2026-07-29T00:52:21.924392Z","steps":["trace[340881979] 'process raft request' (duration: 752.855035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:21.924533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:52:21.170904Z","time spent":"753.583712ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T00:52:21.924687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:52:21.171424Z","time spent":"753.115647ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T00:52:21.924711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:52:21.170747Z","time spent":"753.724444ms","remote":"127.0.0.1:39304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T00:52:21.924718Z","caller":"traceutil/trace.go:171","msg":"trace[1968117497] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"752.965516ms","start":"2026-07-29T00:52:21.171729Z","end":"2026-07-29T00:52:21.924695Z","steps":["trace[1968117497] 'process raft request' (duration: 752.614532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:21.924839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:52:21.171722Z","time spent":"753.053937ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T00:52:21.924917Z","caller":"traceutil/trace.go:171","msg":"trace[1699051640] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"752.34148ms","start":"2026-07-29T00:52:21.17256Z","end":"2026-07-29T00:52:21.924902Z","steps":["trace[1699051640] 'process raft request' (duration: 752.046127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:21.925008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:52:21.172545Z","time spent":"752.40397ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T00:52:21.9569Z","caller":"traceutil/trace.go:171","msg":"trace[240614327] linearizableReadLoop","detail":"{readStateIndex:2198; appliedIndex:2192; }","duration":"704.270342ms","start":"2026-07-29T00:52:21.252604Z","end":"2026-07-29T00:52:21.956874Z","steps":["trace[240614327] 'read index received' (duration: 385.063475ms)","trace[240614327] 'applied index is now lower than readState.Index' (duration: 319.205067ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:52:21.957071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.454063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-29T00:52:21.957328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.488999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-29T00:52:21.957352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.034657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-29T00:52:21.957313Z","caller":"traceutil/trace.go:171","msg":"trace[42371542] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:2113; }","duration":"704.744216ms","start":"2026-07-29T00:52:21.252554Z","end":"2026-07-29T00:52:21.957299Z","steps":["trace[42371542] 'agreement among raft nodes before linearized reading' (duration: 704.449753ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:52:21.957389Z","caller":"traceutil/trace.go:171","msg":"trace[865108625] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:2113; }","duration":"248.600861ms","start":"2026-07-29T00:52:21.708773Z","end":"2026-07-29T00:52:21.957374Z","steps":["trace[865108625] 'agreement among raft nodes before linearized reading' (duration: 248.50982ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:52:21.957419Z","caller":"traceutil/trace.go:171","msg":"trace[1853738953] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2113; }","duration":"188.147698ms","start":"2026-07-29T00:52:21.769255Z","end":"2026-07-29T00:52:21.957403Z","steps":["trace[1853738953] 'agreement among raft nodes before linearized reading' (duration: 187.999746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:21.957432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:52:21.25254Z","time spent":"704.875807ms","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-07-29T00:52:21.957352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.838661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-29T00:52:21.957509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.224825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:52:21.957566Z","caller":"traceutil/trace.go:171","msg":"trace[1001090304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2113; }","duration":"237.089063ms","start":"2026-07-29T00:52:21.720461Z","end":"2026-07-29T00:52:21.95755Z","steps":["trace[1001090304] 'agreement among raft nodes before linearized reading' (duration: 236.618509ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:52:21.957621Z","caller":"traceutil/trace.go:171","msg":"trace[663341992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2113; }","duration":"308.423668ms","start":"2026-07-29T00:52:21.649175Z","end":"2026-07-29T00:52:21.957598Z","steps":["trace[663341992] 'agreement among raft nodes before linearized reading' (duration: 308.195016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:21.957678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:52:21.649145Z","time spent":"308.523799ms","remote":"127.0.0.1:39218","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-29T00:52:21.957829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.269052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-07-29T00:52:21.957885Z","caller":"traceutil/trace.go:171","msg":"trace[1864106907] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2113; }","duration":"344.378343ms","start":"2026-07-29T00:52:21.613491Z","end":"2026-07-29T00:52:21.95787Z","steps":["trace[1864106907] 'agreement among raft nodes before linearized reading' (duration: 343.584834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:52:21.957958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:52:21.613464Z","time spent":"344.484793ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12406,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-29T00:53:13.5243Z","caller":"traceutil/trace.go:171","msg":"trace[995329238] transaction","detail":"{read_only:false; response_revision:2437; number_of_response:1; }","duration":"155.823391ms","start":"2026-07-29T00:53:13.368449Z","end":"2026-07-29T00:53:13.524273Z","steps":["trace[995329238] 'process raft request' (duration: 155.637759ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:53:22.521364Z","caller":"traceutil/trace.go:171","msg":"trace[417264777] linearizableReadLoop","detail":"{readStateIndex:2632; appliedIndex:2631; }","duration":"235.284968ms","start":"2026-07-29T00:53:22.286055Z","end":"2026-07-29T00:53:22.52134Z","steps":["trace[417264777] 'read index received' (duration: 235.037496ms)","trace[417264777] 'applied index is now lower than readState.Index' (duration: 246.622µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:53:22.521858Z","caller":"traceutil/trace.go:171","msg":"trace[1522718936] transaction","detail":"{read_only:false; response_revision:2532; number_of_response:1; }","duration":"248.424175ms","start":"2026-07-29T00:53:22.27342Z","end":"2026-07-29T00:53:22.521844Z","steps":["trace[1522718936] 'process raft request' (duration: 247.726249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:53:22.522287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.212666ms","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-29T00:53:22.522372Z","caller":"traceutil/trace.go:171","msg":"trace[1905853438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2532; }","duration":"236.327527ms","start":"2026-07-29T00:53:22.28603Z","end":"2026-07-29T00:53:22.522357Z","steps":["trace[1905853438] 'agreement among raft nodes before linearized reading' (duration: 236.148106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:53:22.522706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.001726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-29T00:53:22.522772Z","caller":"traceutil/trace.go:171","msg":"trace[1646428173] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2532; }","duration":"235.081767ms","start":"2026-07-29T00:53:22.287679Z","end":"2026-07-29T00:53:22.522761Z","steps":["trace[1646428173] 'agreement among raft nodes before linearized reading' (duration: 234.952394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:53:22.523245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.217001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-29T00:53:22.523374Z","caller":"traceutil/trace.go:171","msg":"trace[1965804873] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2532; }","duration":"206.319901ms","start":"2026-07-29T00:53:22.316997Z","end":"2026-07-29T00:53:22.523317Z","steps":["trace[1965804873] 'agreement among raft nodes before linearized reading' (duration: 206.17823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:53:22.526802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.860545ms","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-29T00:53:22.526933Z","caller":"traceutil/trace.go:171","msg":"trace[56516155] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2532; }","duration":"235.055417ms","start":"2026-07-29T00:53:22.291846Z","end":"2026-07-29T00:53:22.526901Z","steps":["trace[56516155] 'agreement among raft nodes before linearized reading' (duration: 234.658794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:53:22.527306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.130393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-29T00:53:22.527348Z","caller":"traceutil/trace.go:171","msg":"trace[184994] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2532; }","duration":"211.196785ms","start":"2026-07-29T00:53:22.31614Z","end":"2026-07-29T00:53:22.527336Z","steps":["trace[184994] 'agreement among raft nodes before linearized reading' (duration: 211.097294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:53:22.527532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.65725ms","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-29T00:53:22.527572Z","caller":"traceutil/trace.go:171","msg":"trace[794666188] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2532; }","duration":"218.721702ms","start":"2026-07-29T00:53:22.30884Z","end":"2026-07-29T00:53:22.527562Z","steps":["trace[794666188] 'agreement among raft nodes before linearized reading' (duration: 218.623601ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:53:23.334083Z","caller":"traceutil/trace.go:171","msg":"trace[1251476862] transaction","detail":"{read_only:false; response_revision:2539; number_of_response:1; }","duration":"273.718957ms","start":"2026-07-29T00:53:23.060336Z","end":"2026-07-29T00:53:23.334055Z","steps":["trace[1251476862] 'process raft request' (duration: 273.253643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:53:55.294254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.746071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T00:53:55.294421Z","caller":"traceutil/trace.go:171","msg":"trace[689387540] linearizableReadLoop","detail":"{readStateIndex:2822; appliedIndex:2821; }","duration":"430.711195ms","start":"2026-07-29T00:53:54.863686Z","end":"2026-07-29T00:53:55.294397Z","steps":["trace[689387540] 'read index received' (duration: 80.203µs)","trace[689387540] 'applied index is now lower than readState.Index' (duration: 430.625352ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:53:55.294646Z","caller":"traceutil/trace.go:171","msg":"trace[1980896684] transaction","detail":"{read_only:false; response_revision:2715; number_of_response:1; }","duration":"447.294611ms","start":"2026-07-29T00:53:54.847315Z","end":"2026-07-29T00:53:55.294609Z","steps":["trace[1980896684] 'compare' (duration: 437.536053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:53:55.294841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:53:54.847285Z","time spent":"447.440926ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T00:53:55.294977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.301239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-29T00:53:55.295061Z","caller":"traceutil/trace.go:171","msg":"trace[381634821] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2715; }","duration":"431.439586ms","start":"2026-07-29T00:53:54.863607Z","end":"2026-07-29T00:53:55.295046Z","steps":["trace[381634821] 'agreement among raft nodes before linearized reading' (duration: 431.091321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:53:55.295144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:53:54.863567Z","time spent":"431.56584ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-29T00:53:55.48647Z","caller":"traceutil/trace.go:171","msg":"trace[271754252] linearizableReadLoop","detail":"{readStateIndex:2823; appliedIndex:2822; }","duration":"180.82332ms","start":"2026-07-29T00:53:55.305622Z","end":"2026-07-29T00:53:55.486445Z","steps":["trace[271754252] 'read index received' (duration: 171.715781ms)","trace[271754252] 'applied index is now lower than readState.Index' (duration: 9.106069ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:53:55.486904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.277869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-29T00:53:55.486959Z","caller":"traceutil/trace.go:171","msg":"trace[1420089910] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2716; }","duration":"181.393494ms","start":"2026-07-29T00:53:55.305548Z","end":"2026-07-29T00:53:55.486942Z","steps":["trace[1420089910] 'agreement among raft nodes before linearized reading' (duration: 181.230067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:53:55.487056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.923396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T00:53:55.486537Z","caller":"traceutil/trace.go:171","msg":"trace[1885905775] transaction","detail":"{read_only:false; response_revision:2716; number_of_response:1; }","duration":"431.024454ms","start":"2026-07-29T00:53:55.055478Z","end":"2026-07-29T00:53:55.486503Z","steps":["trace[1885905775] 'process raft request' (duration: 421.858283ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:53:55.487273Z","caller":"traceutil/trace.go:171","msg":"trace[1565138053] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2716; }","duration":"170.24915ms","start":"2026-07-29T00:53:55.316995Z","end":"2026-07-29T00:53:55.487244Z","steps":["trace[1565138053] 'agreement among raft nodes before linearized reading' (duration: 169.899746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:53:55.487714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:53:55.055441Z","time spent":"431.924193ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9267,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T00:53:56.166962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.532471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-07-29T00:53:56.167048Z","caller":"traceutil/trace.go:171","msg":"trace[993509880] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2717; }","duration":"579.641386ms","start":"2026-07-29T00:53:55.587386Z","end":"2026-07-29T00:53:56.167027Z","steps":["trace[993509880] 'range keys from in-memory index tree' (duration: 579.365745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:53:56.167091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:53:55.58738Z","time spent":"579.700628ms","remote":"127.0.0.1:36124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5706,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-29T00:53:56.167191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.12002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T00:53:56.167299Z","caller":"traceutil/trace.go:171","msg":"trace[1266130067] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2717; }","duration":"524.291227ms","start":"2026-07-29T00:53:55.642985Z","end":"2026-07-29T00:53:56.167276Z","steps":["trace[1266130067] 'count revisions from in-memory index tree' (duration: 524.053347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:53:56.167345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:53:55.642972Z","time spent":"524.36265ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-07-29T00:53:56.166963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.110159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:53:56.167432Z","caller":"traceutil/trace.go:171","msg":"trace[1254060371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2717; }","duration":"518.60263ms","start":"2026-07-29T00:53:55.648807Z","end":"2026-07-29T00:53:56.167409Z","steps":["trace[1254060371] 'range keys from in-memory index tree' (duration: 518.058527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:53:56.167501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:53:55.6488Z","time spent":"518.682943ms","remote":"127.0.0.1:39218","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-29T00:53:56.168248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.084718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T00:53:56.168509Z","caller":"traceutil/trace.go:171","msg":"trace[240365509] linearizableReadLoop","detail":"{readStateIndex:2825; appliedIndex:2824; }","duration":"261.455676ms","start":"2026-07-29T00:53:55.907037Z","end":"2026-07-29T00:53:56.168493Z","steps":["trace[240365509] 'read index received' (duration: 95.592617ms)","trace[240365509] 'applied index is now lower than readState.Index' (duration: 165.860989ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:53:56.168806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.75683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T00:53:56.168802Z","caller":"traceutil/trace.go:171","msg":"trace[501334695] transaction","detail":"{read_only:false; response_revision:2718; number_of_response:1; }","duration":"393.840748ms","start":"2026-07-29T00:53:55.774904Z","end":"2026-07-29T00:53:56.168745Z","steps":["trace[501334695] 'process raft request' (duration: 227.701457ms)","trace[501334695] 'compare' (duration: 164.339746ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:53:56.168854Z","caller":"traceutil/trace.go:171","msg":"trace[1087432551] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2718; }","duration":"261.809782ms","start":"2026-07-29T00:53:55.907032Z","end":"2026-07-29T00:53:56.168842Z","steps":["trace[1087432551] 'agreement among raft nodes before linearized reading' (duration: 261.712888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:53:56.169014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:53:55.774883Z","time spent":"394.013874ms","remote":"127.0.0.1:39518","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-29T00:53:56.169584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.867095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c69c8954b10c75\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-07-29T00:53:56.169674Z","caller":"traceutil/trace.go:171","msg":"trace[1199750329] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c69c8954b10c75; range_end:; response_count:1; response_revision:2718; }","duration":"222.011501ms","start":"2026-07-29T00:53:55.947652Z","end":"2026-07-29T00:53:56.169663Z","steps":["trace[1199750329] 'agreement among raft nodes before linearized reading' (duration: 221.861034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:54:01.473666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.446936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-29T00:54:01.473753Z","caller":"traceutil/trace.go:171","msg":"trace[1570912145] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2749; }","duration":"297.609613ms","start":"2026-07-29T00:54:01.176123Z","end":"2026-07-29T00:54:01.473733Z","steps":["trace[1570912145] 'range keys from in-memory index tree' (duration: 297.226056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:54:01.473812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.832159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-29T00:54:01.473899Z","caller":"traceutil/trace.go:171","msg":"trace[2131470631] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2749; }","duration":"150.959614ms","start":"2026-07-29T00:54:01.322921Z","end":"2026-07-29T00:54:01.47388Z","steps":["trace[2131470631] 'range keys from in-memory index tree' (duration: 150.670021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:54:01.474179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.750477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:54:01.47427Z","caller":"traceutil/trace.go:171","msg":"trace[940389122] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2749; }","duration":"155.392812ms","start":"2026-07-29T00:54:01.318854Z","end":"2026-07-29T00:54:01.474247Z","steps":["trace[940389122] 'count revisions from in-memory index tree' (duration: 154.578099ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:57:58.757142Z","caller":"traceutil/trace.go:171","msg":"trace[1022725033] transaction","detail":"{read_only:false; response_revision:4498; number_of_response:1; }","duration":"139.709657ms","start":"2026-07-29T00:57:58.617347Z","end":"2026-07-29T00:57:58.757057Z","steps":["trace[1022725033] 'process raft request' (duration: 139.519984ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:57:58.767889Z","caller":"traceutil/trace.go:171","msg":"trace[396501534] transaction","detail":"{read_only:false; response_revision:4499; number_of_response:1; }","duration":"149.520643ms","start":"2026-07-29T00:57:58.618348Z","end":"2026-07-29T00:57:58.767868Z","steps":["trace[396501534] 'process raft request' (duration: 148.761919ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:57:58.769886Z","caller":"traceutil/trace.go:171","msg":"trace[510147003] transaction","detail":"{read_only:false; response_revision:4500; number_of_response:1; }","duration":"137.763893ms","start":"2026-07-29T00:57:58.632085Z","end":"2026-07-29T00:57:58.769849Z","steps":["trace[510147003] 'process raft request' (duration: 135.554617ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:57:58.770352Z","caller":"traceutil/trace.go:171","msg":"trace[1276169976] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4500; }","duration":"102.54648ms","start":"2026-07-29T00:57:58.667788Z","end":"2026-07-29T00:57:58.770334Z","steps":["trace[1276169976] 'process raft request' (duration: 101.880608ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:04.917457Z","caller":"traceutil/trace.go:171","msg":"trace[645155702] linearizableReadLoop","detail":"{readStateIndex:4800; appliedIndex:4799; }","duration":"196.924962ms","start":"2026-07-29T00:58:04.720501Z","end":"2026-07-29T00:58:04.917426Z","steps":["trace[645155702] 'read index received' (duration: 196.377473ms)","trace[645155702] 'applied index is now lower than readState.Index' (duration: 545.949µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:58:04.91757Z","caller":"traceutil/trace.go:171","msg":"trace[154134821] transaction","detail":"{read_only:false; response_revision:4613; number_of_response:1; }","duration":"198.828564ms","start":"2026-07-29T00:58:04.718714Z","end":"2026-07-29T00:58:04.917543Z","steps":["trace[154134821] 'process raft request' (duration: 198.326026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:58:04.917751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.229377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-operator-7d58f4b66c-npqtq\" ","response":"range_response_count:1 size:5047"} {"level":"info","ts":"2026-07-29T00:58:04.917804Z","caller":"traceutil/trace.go:171","msg":"trace[741222886] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-operator-7d58f4b66c-npqtq; range_end:; response_count:1; response_revision:4613; }","duration":"197.335419ms","start":"2026-07-29T00:58:04.720453Z","end":"2026-07-29T00:58:04.917788Z","steps":["trace[741222886] 'agreement among raft nodes before linearized reading' (duration: 197.126605ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:04.919203Z","caller":"traceutil/trace.go:171","msg":"trace[1026441004] transaction","detail":"{read_only:false; response_revision:4614; number_of_response:1; }","duration":"131.130756ms","start":"2026-07-29T00:58:04.788038Z","end":"2026-07-29T00:58:04.919169Z","steps":["trace[1026441004] 'process raft request' (duration: 130.872401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:58:04.919272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.767754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-07-29T00:58:04.919401Z","caller":"traceutil/trace.go:171","msg":"trace[383916283] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4614; }","duration":"138.951027ms","start":"2026-07-29T00:58:04.780429Z","end":"2026-07-29T00:58:04.91938Z","steps":["trace[383916283] 'agreement among raft nodes before linearized reading' (duration: 138.656892ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:06.020664Z","caller":"traceutil/trace.go:171","msg":"trace[1418336228] linearizableReadLoop","detail":"{readStateIndex:4838; appliedIndex:4837; }","duration":"188.042408ms","start":"2026-07-29T00:58:05.832592Z","end":"2026-07-29T00:58:06.020635Z","steps":["trace[1418336228] 'read index received' (duration: 187.716503ms)","trace[1418336228] 'applied index is now lower than readState.Index' (duration: 324.385µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:58:06.020816Z","caller":"traceutil/trace.go:171","msg":"trace[342006083] transaction","detail":"{read_only:false; response_revision:4651; number_of_response:1; }","duration":"284.829868ms","start":"2026-07-29T00:58:05.735925Z","end":"2026-07-29T00:58:06.020755Z","steps":["trace[342006083] 'process raft request' (duration: 284.484682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:58:06.02185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.222507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2362739"} {"level":"info","ts":"2026-07-29T00:58:06.021902Z","caller":"traceutil/trace.go:171","msg":"trace[2017330598] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4651; }","duration":"189.335318ms","start":"2026-07-29T00:58:05.832553Z","end":"2026-07-29T00:58:06.021889Z","steps":["trace[2017330598] 'agreement among raft nodes before linearized reading' (duration: 188.19658ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:06.354942Z","caller":"traceutil/trace.go:171","msg":"trace[1136719407] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"221.727512ms","start":"2026-07-29T00:58:06.13319Z","end":"2026-07-29T00:58:06.354918Z","steps":["trace[1136719407] 'process raft request' (duration: 221.469338ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:06.354938Z","caller":"traceutil/trace.go:171","msg":"trace[723773359] linearizableReadLoop","detail":"{readStateIndex:4839; appliedIndex:4838; }","duration":"217.34667ms","start":"2026-07-29T00:58:06.137557Z","end":"2026-07-29T00:58:06.354904Z","steps":["trace[723773359] 'read index received' (duration: 217.060355ms)","trace[723773359] 'applied index is now lower than readState.Index' (duration: 284.565µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:58:06.355295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.693305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-39fbd138-e48d-4078-a509-b9eccda229dc\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-07-29T00:58:06.355522Z","caller":"traceutil/trace.go:171","msg":"trace[1193336438] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-39fbd138-e48d-4078-a509-b9eccda229dc; range_end:; response_count:1; response_revision:4652; }","duration":"217.99119ms","start":"2026-07-29T00:58:06.13751Z","end":"2026-07-29T00:58:06.355501Z","steps":["trace[1193336438] 'agreement among raft nodes before linearized reading' (duration: 217.527803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:58:06.426618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.175739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-29T00:58:06.42672Z","caller":"traceutil/trace.go:171","msg":"trace[2090707788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4653; }","duration":"116.337892ms","start":"2026-07-29T00:58:06.310361Z","end":"2026-07-29T00:58:06.426699Z","steps":["trace[2090707788] 'agreement among raft nodes before linearized reading' (duration: 116.016327ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:06.427078Z","caller":"traceutil/trace.go:171","msg":"trace[458675953] transaction","detail":"{read_only:false; response_revision:4653; number_of_response:1; }","duration":"163.672178ms","start":"2026-07-29T00:58:06.263356Z","end":"2026-07-29T00:58:06.427029Z","steps":["trace[458675953] 'process raft request' (duration: 103.027561ms)","trace[458675953] 'compare' (duration: 59.779583ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:58:06.427153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.219237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T00:58:06.427196Z","caller":"traceutil/trace.go:171","msg":"trace[609947527] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4653; }","duration":"223.300018ms","start":"2026-07-29T00:58:06.203884Z","end":"2026-07-29T00:58:06.427184Z","steps":["trace[609947527] 'agreement among raft nodes before linearized reading' (duration: 223.209286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:58:06.427277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.011345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-29T00:58:06.427347Z","caller":"traceutil/trace.go:171","msg":"trace[2004459649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4653; }","duration":"143.138066ms","start":"2026-07-29T00:58:06.284193Z","end":"2026-07-29T00:58:06.427331Z","steps":["trace[2004459649] 'agreement among raft nodes before linearized reading' (duration: 142.910472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:58:06.427538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.213351ms","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-29T00:58:06.427577Z","caller":"traceutil/trace.go:171","msg":"trace[1667322904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4653; }","duration":"130.305293ms","start":"2026-07-29T00:58:06.297261Z","end":"2026-07-29T00:58:06.427566Z","steps":["trace[1667322904] 'agreement among raft nodes before linearized reading' (duration: 130.186891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:58:06.42771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.341261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:58:06.427745Z","caller":"traceutil/trace.go:171","msg":"trace[948761300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4653; }","duration":"134.410122ms","start":"2026-07-29T00:58:06.293325Z","end":"2026-07-29T00:58:06.427735Z","steps":["trace[948761300] 'agreement among raft nodes before linearized reading' (duration: 134.359681ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:09.417416Z","caller":"traceutil/trace.go:171","msg":"trace[1571135087] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"166.930546ms","start":"2026-07-29T00:58:09.250458Z","end":"2026-07-29T00:58:09.417389Z","steps":["trace[1571135087] 'process raft request' (duration: 166.711022ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:09.560289Z","caller":"traceutil/trace.go:171","msg":"trace[919399743] linearizableReadLoop","detail":"{readStateIndex:4913; appliedIndex:4911; }","duration":"189.288164ms","start":"2026-07-29T00:58:09.370969Z","end":"2026-07-29T00:58:09.560257Z","steps":["trace[919399743] 'read index received' (duration: 46.262893ms)","trace[919399743] 'applied index is now lower than readState.Index' (duration: 143.023581ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:58:09.560358Z","caller":"traceutil/trace.go:171","msg":"trace[842499060] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"200.177814ms","start":"2026-07-29T00:58:09.360132Z","end":"2026-07-29T00:58:09.56031Z","steps":["trace[842499060] 'process raft request' (duration: 198.344494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:58:09.561547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.553535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-39fbd138-e48d-4078-a509-b9eccda229dc\" ","response":"range_response_count:1 size:4066"} {"level":"info","ts":"2026-07-29T00:58:09.561691Z","caller":"traceutil/trace.go:171","msg":"trace[289727450] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-39fbd138-e48d-4078-a509-b9eccda229dc; range_end:; response_count:1; response_revision:4722; }","duration":"190.720288ms","start":"2026-07-29T00:58:09.370916Z","end":"2026-07-29T00:58:09.561636Z","steps":["trace[289727450] 'agreement among raft nodes before linearized reading' (duration: 189.500918ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:14.234169Z","caller":"traceutil/trace.go:171","msg":"trace[1835769093] linearizableReadLoop","detail":"{readStateIndex:5021; appliedIndex:5020; }","duration":"334.585477ms","start":"2026-07-29T00:58:13.899556Z","end":"2026-07-29T00:58:14.234141Z","steps":["trace[1835769093] 'read index received' (duration: 334.398694ms)","trace[1835769093] 'applied index is now lower than readState.Index' (duration: 185.272µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:58:14.234357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.786869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bf6757f7-7bbe-4794-a615-5b353d9b014d\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-07-29T00:58:14.234336Z","caller":"traceutil/trace.go:171","msg":"trace[2012184075] transaction","detail":"{read_only:false; response_revision:4828; number_of_response:1; }","duration":"391.507789ms","start":"2026-07-29T00:58:13.84279Z","end":"2026-07-29T00:58:14.234298Z","steps":["trace[2012184075] 'process raft request' (duration: 391.164484ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:14.234398Z","caller":"traceutil/trace.go:171","msg":"trace[1035714101] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bf6757f7-7bbe-4794-a615-5b353d9b014d; range_end:; response_count:1; response_revision:4828; }","duration":"334.892751ms","start":"2026-07-29T00:58:13.899494Z","end":"2026-07-29T00:58:14.234386Z","steps":["trace[1035714101] 'agreement among raft nodes before linearized reading' (duration: 334.79524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:58:14.234439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:58:13.899468Z","time spent":"334.959952ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3786,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bf6757f7-7bbe-4794-a615-5b353d9b014d\" "} {"level":"warn","ts":"2026-07-29T00:58:14.234507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.968071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-07-29T00:58:14.234549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:58:13.842766Z","time spent":"391.655691ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3748,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T00:58:14.234599Z","caller":"traceutil/trace.go:171","msg":"trace[519945902] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4828; }","duration":"115.116382ms","start":"2026-07-29T00:58:14.11946Z","end":"2026-07-29T00:58:14.234576Z","steps":["trace[519945902] 'agreement among raft nodes before linearized reading' (duration: 114.865548ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:14.533808Z","caller":"traceutil/trace.go:171","msg":"trace[1992556444] transaction","detail":"{read_only:false; response_revision:4829; number_of_response:1; }","duration":"291.66896ms","start":"2026-07-29T00:58:14.242101Z","end":"2026-07-29T00:58:14.53377Z","steps":["trace[1992556444] 'process raft request' (duration: 209.212501ms)","trace[1992556444] 'compare' (duration: 82.164805ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:58:15.00535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.70895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-29T00:58:15.005468Z","caller":"traceutil/trace.go:171","msg":"trace[2010824602] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4836; }","duration":"118.858232ms","start":"2026-07-29T00:58:14.886583Z","end":"2026-07-29T00:58:15.005442Z","steps":["trace[2010824602] 'range keys from in-memory index tree' (duration: 118.415186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:58:17.03658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.589437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-29T00:58:17.036732Z","caller":"traceutil/trace.go:171","msg":"trace[2070090916] linearizableReadLoop","detail":"{readStateIndex:5054; appliedIndex:5053; }","duration":"169.375219ms","start":"2026-07-29T00:58:16.867334Z","end":"2026-07-29T00:58:17.036709Z","steps":["trace[2070090916] 'read index received' (duration: 46.490647ms)","trace[2070090916] 'applied index is now lower than readState.Index' (duration: 122.882372ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:58:17.036742Z","caller":"traceutil/trace.go:171","msg":"trace[566132853] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"188.850786ms","start":"2026-07-29T00:58:16.847856Z","end":"2026-07-29T00:58:17.036707Z","steps":["trace[566132853] 'process raft request' (duration: 66.240369ms)","trace[566132853] 'compare' (duration: 122.229561ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:58:17.037089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.747005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-07-29T00:58:17.037145Z","caller":"traceutil/trace.go:171","msg":"trace[1801223956] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4860; }","duration":"169.844236ms","start":"2026-07-29T00:58:16.867283Z","end":"2026-07-29T00:58:17.037127Z","steps":["trace[1801223956] 'agreement among raft nodes before linearized reading' (duration: 169.513551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:58:17.04355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.244105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-29T00:58:17.043598Z","caller":"traceutil/trace.go:171","msg":"trace[1955984429] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4861; }","duration":"105.335986ms","start":"2026-07-29T00:58:16.938247Z","end":"2026-07-29T00:58:17.043583Z","steps":["trace[1955984429] 'agreement among raft nodes before linearized reading' (duration: 105.168773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:58:19.397641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.892261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-29T00:58:19.397722Z","caller":"traceutil/trace.go:171","msg":"trace[2049204175] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4909; }","duration":"273.026893ms","start":"2026-07-29T00:58:19.124676Z","end":"2026-07-29T00:58:19.397703Z","steps":["trace[2049204175] 'range keys from in-memory index tree' (duration: 272.641817ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:21.046421Z","caller":"traceutil/trace.go:171","msg":"trace[1897197745] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"332.451828ms","start":"2026-07-29T00:58:20.71394Z","end":"2026-07-29T00:58:21.046392Z","steps":["trace[1897197745] 'process raft request' (duration: 332.188623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:58:21.046588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:58:20.713873Z","time spent":"332.623121ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T00:58:30.089494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.150947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T00:58:30.090227Z","caller":"traceutil/trace.go:171","msg":"trace[1149238729] linearizableReadLoop","detail":"{readStateIndex:5224; appliedIndex:5223; }","duration":"441.533772ms","start":"2026-07-29T00:58:29.648672Z","end":"2026-07-29T00:58:30.090205Z","steps":["trace[1149238729] 'read index received' (duration: 331.604264ms)","trace[1149238729] 'applied index is now lower than readState.Index' (duration: 109.927758ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:58:30.090332Z","caller":"traceutil/trace.go:171","msg":"trace[1543770067] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"527.675524ms","start":"2026-07-29T00:58:29.562625Z","end":"2026-07-29T00:58:30.0903Z","steps":["trace[1543770067] 'process raft request' (duration: 417.620813ms)","trace[1543770067] 'compare' (duration: 107.922977ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:58:30.090371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.716875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:58:30.090416Z","caller":"traceutil/trace.go:171","msg":"trace[1086924420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5024; }","duration":"441.802016ms","start":"2026-07-29T00:58:29.648597Z","end":"2026-07-29T00:58:30.090399Z","steps":["trace[1086924420] 'agreement among raft nodes before linearized reading' (duration: 441.717895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:58:30.09046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:58:29.648571Z","time spent":"441.881397ms","remote":"127.0.0.1:39218","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-29T00:58:30.090465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:58:29.562598Z","time spent":"527.792715ms","remote":"127.0.0.1:39518","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-29T00:58:30.591168Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555607475398813742,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-29T00:58:30.762917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.285909ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:58:30.763045Z","caller":"traceutil/trace.go:171","msg":"trace[1990116073] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5024; }","duration":"436.431961ms","start":"2026-07-29T00:58:30.326593Z","end":"2026-07-29T00:58:30.763025Z","steps":["trace[1990116073] 'range keys from in-memory index tree' (duration: 436.263559ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:30.763389Z","caller":"traceutil/trace.go:171","msg":"trace[235025021] linearizableReadLoop","detail":"{readStateIndex:5225; appliedIndex:5224; }","duration":"673.080225ms","start":"2026-07-29T00:58:30.090259Z","end":"2026-07-29T00:58:30.763339Z","steps":["trace[235025021] 'read index received' (duration: 646.475392ms)","trace[235025021] 'applied index is now lower than readState.Index' (duration: 26.603703ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:58:30.763704Z","caller":"traceutil/trace.go:171","msg":"trace[1153840413] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"685.848108ms","start":"2026-07-29T00:58:30.077842Z","end":"2026-07-29T00:58:30.76369Z","steps":["trace[1153840413] 'process raft request' (duration: 658.941801ms)","trace[1153840413] 'compare' (duration: 26.088105ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:58:30.763788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:58:30.077802Z","time spent":"685.925919ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T00:58:30.764264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.93977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-29T00:58:30.764333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.467101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-07-29T00:58:30.764382Z","caller":"traceutil/trace.go:171","msg":"trace[1404531156] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5025; }","duration":"771.542392ms","start":"2026-07-29T00:58:29.992828Z","end":"2026-07-29T00:58:30.76437Z","steps":["trace[1404531156] 'agreement among raft nodes before linearized reading' (duration: 771.385848ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:30.764373Z","caller":"traceutil/trace.go:171","msg":"trace[1749160429] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5025; }","duration":"872.127265ms","start":"2026-07-29T00:58:29.892224Z","end":"2026-07-29T00:58:30.764351Z","steps":["trace[1749160429] 'agreement among raft nodes before linearized reading' (duration: 871.90766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:58:30.764405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:58:29.992806Z","time spent":"771.593312ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-07-29T00:58:30.76442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.71243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-29T00:58:30.764431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.734984ms","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-29T00:58:30.764436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:58:29.892197Z","time spent":"872.226826ms","remote":"127.0.0.1:36124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-07-29T00:58:30.764504Z","caller":"traceutil/trace.go:171","msg":"trace[267905202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5025; }","duration":"672.850272ms","start":"2026-07-29T00:58:30.09163Z","end":"2026-07-29T00:58:30.764481Z","steps":["trace[267905202] 'agreement among raft nodes before linearized reading' (duration: 672.704879ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:30.764505Z","caller":"traceutil/trace.go:171","msg":"trace[815881387] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5025; }","duration":"705.861126ms","start":"2026-07-29T00:58:30.058625Z","end":"2026-07-29T00:58:30.764486Z","steps":["trace[815881387] 'agreement among raft nodes before linearized reading' (duration: 705.58612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:58:30.764547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:58:30.091607Z","time spent":"672.931023ms","remote":"127.0.0.1:39224","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-29T00:58:30.764547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:58:30.058595Z","time spent":"705.943007ms","remote":"127.0.0.1:39388","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-29T00:58:30.764523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.905655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-29T00:58:30.764634Z","caller":"traceutil/trace.go:171","msg":"trace[1262428218] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5025; }","duration":"682.054798ms","start":"2026-07-29T00:58:30.082547Z","end":"2026-07-29T00:58:30.764602Z","steps":["trace[1262428218] 'agreement among raft nodes before linearized reading' (duration: 681.687842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:58:30.764688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:58:30.082535Z","time spent":"682.14055ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-07-29T00:58:30.764807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.522834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-29T00:58:30.764842Z","caller":"traceutil/trace.go:171","msg":"trace[1637020732] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5025; }","duration":"143.587884ms","start":"2026-07-29T00:58:30.621243Z","end":"2026-07-29T00:58:30.764831Z","steps":["trace[1637020732] 'agreement among raft nodes before linearized reading' (duration: 143.483332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:58:30.765044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.342846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T00:58:30.765214Z","caller":"traceutil/trace.go:171","msg":"trace[1515136992] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:5025; }","duration":"266.60294ms","start":"2026-07-29T00:58:30.498557Z","end":"2026-07-29T00:58:30.76516Z","steps":["trace[1515136992] 'agreement among raft nodes before linearized reading' (duration: 266.308066ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:30.904263Z","caller":"traceutil/trace.go:171","msg":"trace[1306138224] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"132.71746ms","start":"2026-07-29T00:58:30.77151Z","end":"2026-07-29T00:58:30.904227Z","steps":["trace[1306138224] 'process raft request' (duration: 123.650646ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:30.904776Z","caller":"traceutil/trace.go:171","msg":"trace[1365849380] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"132.717951ms","start":"2026-07-29T00:58:30.772025Z","end":"2026-07-29T00:58:30.904743Z","steps":["trace[1365849380] 'process raft request' (duration: 132.151891ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:30.905525Z","caller":"traceutil/trace.go:171","msg":"trace[1128458316] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"132.129301ms","start":"2026-07-29T00:58:30.773379Z","end":"2026-07-29T00:58:30.905509Z","steps":["trace[1128458316] 'process raft request' (duration: 131.267428ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:30.905737Z","caller":"traceutil/trace.go:171","msg":"trace[886721466] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"131.512762ms","start":"2026-07-29T00:58:30.774078Z","end":"2026-07-29T00:58:30.905591Z","steps":["trace[886721466] 'process raft request' (duration: 131.271328ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:30.906383Z","caller":"traceutil/trace.go:171","msg":"trace[1180363914] transaction","detail":"{read_only:false; response_revision:5031; number_of_response:1; }","duration":"131.960449ms","start":"2026-07-29T00:58:30.774409Z","end":"2026-07-29T00:58:30.90637Z","steps":["trace[1180363914] 'process raft request' (duration: 131.131096ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:30.906737Z","caller":"traceutil/trace.go:171","msg":"trace[1487187226] linearizableReadLoop","detail":"{readStateIndex:5228; appliedIndex:5226; }","duration":"133.826458ms","start":"2026-07-29T00:58:30.772894Z","end":"2026-07-29T00:58:30.906721Z","steps":["trace[1487187226] 'read index received' (duration: 46.610771ms)","trace[1487187226] 'applied index is now lower than readState.Index' (duration: 87.213697ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:58:30.906857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.9369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:58:30.9069Z","caller":"traceutil/trace.go:171","msg":"trace[1597706257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5032; }","duration":"134.015771ms","start":"2026-07-29T00:58:30.772873Z","end":"2026-07-29T00:58:30.906889Z","steps":["trace[1597706257] 'agreement among raft nodes before linearized reading' (duration: 133.903469ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:30.907082Z","caller":"traceutil/trace.go:171","msg":"trace[562930994] transaction","detail":"{read_only:false; response_revision:5032; number_of_response:1; }","duration":"129.912665ms","start":"2026-07-29T00:58:30.777158Z","end":"2026-07-29T00:58:30.90707Z","steps":["trace[562930994] 'process raft request' (duration: 128.978411ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:31.042758Z","caller":"traceutil/trace.go:171","msg":"trace[17242704] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"127.070719ms","start":"2026-07-29T00:58:30.915658Z","end":"2026-07-29T00:58:31.042729Z","steps":["trace[17242704] 'process raft request' (duration: 126.997138ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:31.043282Z","caller":"traceutil/trace.go:171","msg":"trace[1483466564] transaction","detail":"{read_only:false; response_revision:5034; number_of_response:1; }","duration":"128.030855ms","start":"2026-07-29T00:58:30.915204Z","end":"2026-07-29T00:58:31.043235Z","steps":["trace[1483466564] 'process raft request' (duration: 119.798445ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:31.043407Z","caller":"traceutil/trace.go:171","msg":"trace[635431778] transaction","detail":"{read_only:false; response_revision:5035; number_of_response:1; }","duration":"127.73562ms","start":"2026-07-29T00:58:30.915652Z","end":"2026-07-29T00:58:31.043388Z","steps":["trace[635431778] 'process raft request' (duration: 126.959688ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:31.28251Z","caller":"traceutil/trace.go:171","msg":"trace[920260739] transaction","detail":"{read_only:false; response_revision:5038; number_of_response:1; }","duration":"143.705462ms","start":"2026-07-29T00:58:31.138779Z","end":"2026-07-29T00:58:31.282484Z","steps":["trace[920260739] 'process raft request' (duration: 143.655661ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:31.282688Z","caller":"traceutil/trace.go:171","msg":"trace[1852611503] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"182.796993ms","start":"2026-07-29T00:58:31.099824Z","end":"2026-07-29T00:58:31.282621Z","steps":["trace[1852611503] 'process raft request' (duration: 108.917379ms)","trace[1852611503] 'compare' (duration: 73.502517ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:59:12.281388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.519907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:59:12.281511Z","caller":"traceutil/trace.go:171","msg":"trace[746797961] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:5388; }","duration":"123.74872ms","start":"2026-07-29T00:59:12.157716Z","end":"2026-07-29T00:59:12.281464Z","steps":["trace[746797961] 'count revisions from in-memory index tree' (duration: 123.428976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:59:12.282404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.946033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2362739"} {"level":"info","ts":"2026-07-29T00:59:12.282499Z","caller":"traceutil/trace.go:171","msg":"trace[1273557978] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5388; }","duration":"108.097726ms","start":"2026-07-29T00:59:12.17437Z","end":"2026-07-29T00:59:12.282468Z","steps":["trace[1273557978] 'range keys from in-memory index tree' (duration: 106.795387ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:59:13.486096Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2811} {"level":"info","ts":"2026-07-29T00:59:13.706541Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2811,"took":"219.072873ms","hash":2278678608,"current-db-size-bytes":53452800,"current-db-size":"54 MB","current-db-size-in-use-bytes":28704768,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-29T00:59:13.706619Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2278678608,"revision":2811,"compact-revision":-1} {"level":"info","ts":"2026-07-29T01:04:13.49659Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5396} {"level":"info","ts":"2026-07-29T01:04:13.578033Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5396,"took":"79.476008ms","hash":3840060442,"current-db-size-bytes":53452800,"current-db-size":"54 MB","current-db-size-in-use-bytes":18604032,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-07-29T01:04:13.578107Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3840060442,"revision":5396,"compact-revision":2811}