{"level":"warn","ts":"2026-07-22T03:46:50.214051Z","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-22T03:46:50.214151Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-22T03:46:50.215691Z","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-22T03:46:50.215782Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-07-22T03:46:50.21588Z","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-22T03:46:50.216792Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-07-22T03:46:50.217031Z","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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-22T03:46:50.221043Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.633715ms"} {"level":"info","ts":"2026-07-22T03:46:50.227083Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-07-22T03:46:50.227192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-07-22T03:46:50.227276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-07-22T03:46:50.227303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-22T03:46:50.227349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-07-22T03:46:50.227429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-07-22T03:46:50.234984Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-22T03:46:50.238586Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-22T03:46:50.239743Z","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-22T03:46:50.242379Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-22T03:46:50.242652Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-22T03:46:50.242839Z","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-22T03:46:50.242969Z","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-22T03:46:50.243065Z","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-22T03:46:50.247449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-07-22T03:46:50.247588Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-07-22T03:46:50.249083Z","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-22T03:46:50.249325Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-07-22T03:46:50.249406Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-07-22T03:46:50.250073Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-22T03:46:50.250108Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-22T03:46:51.227873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-07-22T03:46:51.22807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-07-22T03:46:51.228291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-07-22T03:46:51.228408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-07-22T03:46:51.228532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-07-22T03:46:51.228655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-07-22T03:46:51.228736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-07-22T03:46:51.229975Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T03:46:51.229963Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T03:46:51.230508Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T03:46:51.230848Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T03:46:51.230944Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T03:46:51.230983Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T03:46:51.231873Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-07-22T03:46:51.229951Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-07-22T03:46:51.2326Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-22T03:46:51.232829Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-22T03:46:51.233902Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-22T03:46:53.658635Z","caller":"traceutil/trace.go:171","msg":"trace[809081156] transaction","detail":"{read_only:false; response_revision:123; number_of_response:1; }","duration":"109.502719ms","start":"2026-07-22T03:46:53.549094Z","end":"2026-07-22T03:46:53.658597Z","steps":["trace[809081156] 'process raft request' (duration: 107.666542ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:47:04.355889Z","caller":"traceutil/trace.go:171","msg":"trace[2001176375] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"110.783871ms","start":"2026-07-22T03:47:04.245067Z","end":"2026-07-22T03:47:04.355851Z","steps":["trace[2001176375] 'process raft request' (duration: 110.605452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:47:05.032815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.423793ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-22T03:47:05.032955Z","caller":"traceutil/trace.go:171","msg":"trace[1207309836] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:415; }","duration":"422.7346ms","start":"2026-07-22T03:47:04.610204Z","end":"2026-07-22T03:47:05.032938Z","steps":["trace[1207309836] 'read index received' (duration: 72.985µs)","trace[1207309836] 'applied index is now lower than readState.Index' (duration: 422.659655ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T03:47:05.032989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:47:04.357294Z","time spent":"675.689052ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-22T03:47:05.033104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.581586ms","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-22T03:47:05.033155Z","caller":"traceutil/trace.go:171","msg":"trace[447097134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:402; }","duration":"155.676592ms","start":"2026-07-22T03:47:04.877463Z","end":"2026-07-22T03:47:05.033139Z","steps":["trace[447097134] 'agreement among raft nodes before linearized reading' (duration: 155.588817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:47:05.033086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.872327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-07-22T03:47:05.033266Z","caller":"traceutil/trace.go:171","msg":"trace[192179689] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:402; }","duration":"423.061026ms","start":"2026-07-22T03:47:04.610197Z","end":"2026-07-22T03:47:05.033258Z","steps":["trace[192179689] 'agreement among raft nodes before linearized reading' (duration: 422.824974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:47:05.033296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:47:04.610141Z","time spent":"423.145551ms","remote":"127.0.0.1:58942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6988,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-07-22T03:47:45.083353Z","caller":"traceutil/trace.go:171","msg":"trace[397782255] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"146.762564ms","start":"2026-07-22T03:47:44.936571Z","end":"2026-07-22T03:47:45.083334Z","steps":["trace[397782255] 'read index received' (duration: 146.484505ms)","trace[397782255] 'applied index is now lower than readState.Index' (duration: 277.369µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T03:47:45.083443Z","caller":"traceutil/trace.go:171","msg":"trace[812871224] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"237.867191ms","start":"2026-07-22T03:47:44.84554Z","end":"2026-07-22T03:47:45.083407Z","steps":["trace[812871224] 'process raft request' (duration: 237.600682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:47:45.083528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.93884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-07-22T03:47:45.083554Z","caller":"traceutil/trace.go:171","msg":"trace[447733697] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:640; }","duration":"147.023123ms","start":"2026-07-22T03:47:44.936523Z","end":"2026-07-22T03:47:45.083546Z","steps":["trace[447733697] 'agreement among raft nodes before linearized reading' (duration: 146.891809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:47:45.32771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.399472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:20866"} {"level":"warn","ts":"2026-07-22T03:47:45.327723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.872888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-22T03:47:45.327813Z","caller":"traceutil/trace.go:171","msg":"trace[64013967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"108.039934ms","start":"2026-07-22T03:47:45.219752Z","end":"2026-07-22T03:47:45.327792Z","steps":["trace[64013967] 'range keys from in-memory index tree' (duration: 107.739393ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:47:45.327812Z","caller":"traceutil/trace.go:171","msg":"trace[341682125] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:640; }","duration":"209.500325ms","start":"2026-07-22T03:47:45.118278Z","end":"2026-07-22T03:47:45.327778Z","steps":["trace[341682125] 'range keys from in-memory index tree' (duration: 209.187534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:47:45.327915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.14507ms","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-22T03:47:45.328017Z","caller":"traceutil/trace.go:171","msg":"trace[1569469372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"100.279994ms","start":"2026-07-22T03:47:45.227713Z","end":"2026-07-22T03:47:45.327993Z","steps":["trace[1569469372] 'range keys from in-memory index tree' (duration: 100.001476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:47:46.653285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.579395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-22T03:47:46.653457Z","caller":"traceutil/trace.go:171","msg":"trace[1954665240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:646; }","duration":"112.854954ms","start":"2026-07-22T03:47:46.540576Z","end":"2026-07-22T03:47:46.653431Z","steps":["trace[1954665240] 'range keys from in-memory index tree' (duration: 112.263575ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:47:53.384732Z","caller":"traceutil/trace.go:171","msg":"trace[424399593] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"247.577666ms","start":"2026-07-22T03:47:53.13706Z","end":"2026-07-22T03:47:53.384638Z","steps":["trace[424399593] 'process raft request' (duration: 246.759371ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:48:08.823867Z","caller":"traceutil/trace.go:171","msg":"trace[274517782] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"100.523689ms","start":"2026-07-22T03:48:08.723311Z","end":"2026-07-22T03:48:08.823835Z","steps":["trace[274517782] 'process raft request' (duration: 100.228661ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:48:59.828242Z","caller":"traceutil/trace.go:171","msg":"trace[724600561] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1451; }","duration":"125.549603ms","start":"2026-07-22T03:48:59.702668Z","end":"2026-07-22T03:48:59.828218Z","steps":["trace[724600561] 'read index received' (duration: 125.283337ms)","trace[724600561] 'applied index is now lower than readState.Index' (duration: 265.186µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T03:48:59.82835Z","caller":"traceutil/trace.go:171","msg":"trace[1893711565] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"126.606023ms","start":"2026-07-22T03:48:59.701706Z","end":"2026-07-22T03:48:59.828312Z","steps":["trace[1893711565] 'process raft request' (duration: 126.344268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:48:59.828485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.797148ms","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-22T03:48:59.828523Z","caller":"traceutil/trace.go:171","msg":"trace[859250477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1400; }","duration":"125.873419ms","start":"2026-07-22T03:48:59.702636Z","end":"2026-07-22T03:48:59.82851Z","steps":["trace[859250477] 'agreement among raft nodes before linearized reading' (duration: 125.715746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:48:59.828557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.141965ms","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-22T03:48:59.828644Z","caller":"traceutil/trace.go:171","msg":"trace[2138614213] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1400; }","duration":"124.255258ms","start":"2026-07-22T03:48:59.704366Z","end":"2026-07-22T03:48:59.828621Z","steps":["trace[2138614213] 'agreement among raft nodes before linearized reading' (duration: 124.039294ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:48:59.93338Z","caller":"traceutil/trace.go:171","msg":"trace[781707128] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"100.544011ms","start":"2026-07-22T03:48:59.832802Z","end":"2026-07-22T03:48:59.933346Z","steps":["trace[781707128] 'process raft request' (duration: 100.425809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:49:06.066148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.998098ms","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-22T03:49:06.066221Z","caller":"traceutil/trace.go:171","msg":"trace[1118304623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1520; }","duration":"114.12056ms","start":"2026-07-22T03:49:05.952084Z","end":"2026-07-22T03:49:06.066204Z","steps":["trace[1118304623] 'range keys from in-memory index tree' (duration: 113.855916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:49:06.066384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.673022ms","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-22T03:49:06.066479Z","caller":"traceutil/trace.go:171","msg":"trace[1888082848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1520; }","duration":"113.818744ms","start":"2026-07-22T03:49:05.952639Z","end":"2026-07-22T03:49:06.066458Z","steps":["trace[1888082848] 'range keys from in-memory index tree' (duration: 113.474328ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:49:29.878129Z","caller":"traceutil/trace.go:171","msg":"trace[238192263] linearizableReadLoop","detail":"{readStateIndex:2007; appliedIndex:2006; }","duration":"141.842233ms","start":"2026-07-22T03:49:29.736258Z","end":"2026-07-22T03:49:29.8781Z","steps":["trace[238192263] 'read index received' (duration: 58.594016ms)","trace[238192263] 'applied index is now lower than readState.Index' (duration: 83.246637ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T03:49:29.87833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.047786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-067d67e0-b5a1-482e-98ad-93e247056191\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-22T03:49:29.87838Z","caller":"traceutil/trace.go:171","msg":"trace[1491924987] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-067d67e0-b5a1-482e-98ad-93e247056191; range_end:; response_count:1; response_revision:1927; }","duration":"142.140428ms","start":"2026-07-22T03:49:29.736226Z","end":"2026-07-22T03:49:29.878366Z","steps":["trace[1491924987] 'agreement among raft nodes before linearized reading' (duration: 141.976626ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:49:30.248191Z","caller":"traceutil/trace.go:171","msg":"trace[1008460297] linearizableReadLoop","detail":"{readStateIndex:2008; appliedIndex:2007; }","duration":"186.197141ms","start":"2026-07-22T03:49:30.061973Z","end":"2026-07-22T03:49:30.248171Z","steps":["trace[1008460297] 'read index received' (duration: 185.968408ms)","trace[1008460297] 'applied index is now lower than readState.Index' (duration: 227.893µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T03:49:30.248447Z","caller":"traceutil/trace.go:171","msg":"trace[525848221] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"285.35892ms","start":"2026-07-22T03:49:29.963075Z","end":"2026-07-22T03:49:30.248434Z","steps":["trace[525848221] 'process raft request' (duration: 284.940673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:49:30.248642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.672169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T03:49:30.248682Z","caller":"traceutil/trace.go:171","msg":"trace[1313961457] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1928; }","duration":"186.763071ms","start":"2026-07-22T03:49:30.061908Z","end":"2026-07-22T03:49:30.248671Z","steps":["trace[1313961457] 'agreement among raft nodes before linearized reading' (duration: 186.69269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:49:30.248916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.444008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T03:49:30.249022Z","caller":"traceutil/trace.go:171","msg":"trace[1768540875] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1928; }","duration":"144.603671ms","start":"2026-07-22T03:49:30.104397Z","end":"2026-07-22T03:49:30.249Z","steps":["trace[1768540875] 'agreement among raft nodes before linearized reading' (duration: 144.388276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:49:30.249317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.790331ms","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-22T03:49:30.249371Z","caller":"traceutil/trace.go:171","msg":"trace[1168690797] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1928; }","duration":"139.892622ms","start":"2026-07-22T03:49:30.109463Z","end":"2026-07-22T03:49:30.249356Z","steps":["trace[1168690797] 'agreement among raft nodes before linearized reading' (duration: 139.74462ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:49:30.750803Z","caller":"traceutil/trace.go:171","msg":"trace[104989784] linearizableReadLoop","detail":"{readStateIndex:2012; appliedIndex:2011; }","duration":"104.538255ms","start":"2026-07-22T03:49:30.646227Z","end":"2026-07-22T03:49:30.750765Z","steps":["trace[104989784] 'read index received' (duration: 96.950889ms)","trace[104989784] 'applied index is now lower than readState.Index' (duration: 7.586266ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T03:49:30.750964Z","caller":"traceutil/trace.go:171","msg":"trace[1558963501] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"136.778212ms","start":"2026-07-22T03:49:30.614172Z","end":"2026-07-22T03:49:30.75095Z","steps":["trace[1558963501] 'process raft request' (duration: 129.095074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:49:30.751295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.039423ms","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-22T03:49:30.751342Z","caller":"traceutil/trace.go:171","msg":"trace[1744870597] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1932; }","duration":"105.134344ms","start":"2026-07-22T03:49:30.646192Z","end":"2026-07-22T03:49:30.751326Z","steps":["trace[1744870597] 'agreement among raft nodes before linearized reading' (duration: 104.925001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:49:31.170771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.839358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-067d67e0-b5a1-482e-98ad-93e247056191\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-07-22T03:49:31.170925Z","caller":"traceutil/trace.go:171","msg":"trace[1866193572] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-067d67e0-b5a1-482e-98ad-93e247056191; range_end:; response_count:1; response_revision:1934; }","duration":"288.070012ms","start":"2026-07-22T03:49:30.882832Z","end":"2026-07-22T03:49:31.170902Z","steps":["trace[1866193572] 'range keys from in-memory index tree' (duration: 287.688636ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:49:31.172073Z","caller":"traceutil/trace.go:171","msg":"trace[975469401] linearizableReadLoop","detail":"{readStateIndex:2015; appliedIndex:2014; }","duration":"109.211216ms","start":"2026-07-22T03:49:31.062823Z","end":"2026-07-22T03:49:31.172034Z","steps":["trace[975469401] 'read index received' (duration: 91.672356ms)","trace[975469401] 'applied index is now lower than readState.Index' (duration: 17.53726ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T03:49:31.172164Z","caller":"traceutil/trace.go:171","msg":"trace[1408139416] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"116.574168ms","start":"2026-07-22T03:49:31.055558Z","end":"2026-07-22T03:49:31.172132Z","steps":["trace[1408139416] 'process raft request' (duration: 98.921496ms)","trace[1408139416] 'compare' (duration: 16.767717ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T03:49:31.172261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.421959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T03:49:31.172409Z","caller":"traceutil/trace.go:171","msg":"trace[12654112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1935; }","duration":"109.627284ms","start":"2026-07-22T03:49:31.062767Z","end":"2026-07-22T03:49:31.172395Z","steps":["trace[12654112] 'agreement among raft nodes before linearized reading' (duration: 109.43054ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:50:03.963035Z","caller":"traceutil/trace.go:171","msg":"trace[719097341] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"194.378157ms","start":"2026-07-22T03:50:03.768619Z","end":"2026-07-22T03:50:03.962997Z","steps":["trace[719097341] 'process raft request' (duration: 194.148714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:50:04.718384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.346598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T03:50:04.718464Z","caller":"traceutil/trace.go:171","msg":"trace[1340947968] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2167; }","duration":"101.47441ms","start":"2026-07-22T03:50:04.616972Z","end":"2026-07-22T03:50:04.718447Z","steps":["trace[1340947968] 'count revisions from in-memory index tree' (duration: 101.183055ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:50:05.271273Z","caller":"traceutil/trace.go:171","msg":"trace[1742759899] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"332.459976ms","start":"2026-07-22T03:50:04.938782Z","end":"2026-07-22T03:50:05.271242Z","steps":["trace[1742759899] 'process raft request' (duration: 332.257043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:50:05.272017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:50:04.938753Z","time spent":"332.699539ms","remote":"127.0.0.1:59034","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-22T03:50:05.451494Z","caller":"traceutil/trace.go:171","msg":"trace[330635050] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"510.10437ms","start":"2026-07-22T03:50:04.941374Z","end":"2026-07-22T03:50:05.451478Z","steps":["trace[330635050] 'process raft request' (duration: 487.498729ms)","trace[330635050] 'compare' (duration: 22.290126ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T03:50:05.451594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:50:04.941349Z","time spent":"510.191441ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T03:50:05.451754Z","caller":"traceutil/trace.go:171","msg":"trace[1957045154] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"168.169021ms","start":"2026-07-22T03:50:05.283553Z","end":"2026-07-22T03:50:05.451722Z","steps":["trace[1957045154] 'process raft request' (duration: 168.026009ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:50:05.452113Z","caller":"traceutil/trace.go:171","msg":"trace[653225205] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"336.21226ms","start":"2026-07-22T03:50:05.115856Z","end":"2026-07-22T03:50:05.452068Z","steps":["trace[653225205] 'process raft request' (duration: 335.54029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:50:05.45228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:50:05.115826Z","time spent":"336.377553ms","remote":"127.0.0.1:58864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":819,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T03:50:05.452264Z","caller":"traceutil/trace.go:171","msg":"trace[876851884] linearizableReadLoop","detail":"{readStateIndex:2258; appliedIndex:2256; }","duration":"472.171437ms","start":"2026-07-22T03:50:04.980069Z","end":"2026-07-22T03:50:05.45224Z","steps":["trace[876851884] 'read index received' (duration: 291.041791ms)","trace[876851884] 'applied index is now lower than readState.Index' (duration: 181.127396ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T03:50:05.452559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.418141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T03:50:05.452632Z","caller":"traceutil/trace.go:171","msg":"trace[859807732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2172; }","duration":"389.535672ms","start":"2026-07-22T03:50:05.063078Z","end":"2026-07-22T03:50:05.452614Z","steps":["trace[859807732] 'agreement among raft nodes before linearized reading' (duration: 389.417491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:50:05.4527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:50:05.063063Z","time spent":"389.625173ms","remote":"127.0.0.1:58786","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-22T03:50:05.452802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.672165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-07-22T03:50:05.452939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.875918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T03:50:05.452952Z","caller":"traceutil/trace.go:171","msg":"trace[719929058] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2172; }","duration":"472.846469ms","start":"2026-07-22T03:50:04.980064Z","end":"2026-07-22T03:50:05.452911Z","steps":["trace[719929058] 'agreement among raft nodes before linearized reading' (duration: 472.2895ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:50:05.452999Z","caller":"traceutil/trace.go:171","msg":"trace[824078843] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2172; }","duration":"375.973909ms","start":"2026-07-22T03:50:05.077012Z","end":"2026-07-22T03:50:05.452986Z","steps":["trace[824078843] 'agreement among raft nodes before linearized reading' (duration: 375.875457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:50:05.453025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:50:04.980031Z","time spent":"472.98193ms","remote":"127.0.0.1:42124","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-22T03:50:05.45305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:50:05.076991Z","time spent":"376.047209ms","remote":"127.0.0.1:58928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-07-22T03:50:05.792671Z","caller":"traceutil/trace.go:171","msg":"trace[2129796992] linearizableReadLoop","detail":"{readStateIndex:2262; appliedIndex:2261; }","duration":"328.412333ms","start":"2026-07-22T03:50:05.46422Z","end":"2026-07-22T03:50:05.792633Z","steps":["trace[2129796992] 'read index received' (duration: 234.959216ms)","trace[2129796992] 'applied index is now lower than readState.Index' (duration: 93.451527ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T03:50:05.792679Z","caller":"traceutil/trace.go:171","msg":"trace[1845091315] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"333.781104ms","start":"2026-07-22T03:50:05.458856Z","end":"2026-07-22T03:50:05.792637Z","steps":["trace[1845091315] 'process raft request' (duration: 240.285546ms)","trace[1845091315] 'compare' (duration: 93.219584ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T03:50:05.792879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:50:05.458848Z","time spent":"333.926226ms","remote":"127.0.0.1:59034","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-22T03:50:05.793093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.830329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T03:50:05.793258Z","caller":"traceutil/trace.go:171","msg":"trace[1737762426] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2175; }","duration":"328.999702ms","start":"2026-07-22T03:50:05.464205Z","end":"2026-07-22T03:50:05.793205Z","steps":["trace[1737762426] 'agreement among raft nodes before linearized reading' (duration: 328.522455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:50:05.793344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:50:05.464198Z","time spent":"329.107033ms","remote":"127.0.0.1:58556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-22T03:51:20.160306Z","caller":"traceutil/trace.go:171","msg":"trace[94539168] transaction","detail":"{read_only:false; response_revision:2665; number_of_response:1; }","duration":"519.293767ms","start":"2026-07-22T03:51:19.640996Z","end":"2026-07-22T03:51:20.16029Z","steps":["trace[94539168] 'process raft request' (duration: 519.174222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:51:20.160457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:51:19.64097Z","time spent":"519.420883ms","remote":"127.0.0.1:59034","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-22T03:51:20.160731Z","caller":"traceutil/trace.go:171","msg":"trace[968757352] linearizableReadLoop","detail":"{readStateIndex:2771; appliedIndex:2769; }","duration":"367.98121ms","start":"2026-07-22T03:51:19.792741Z","end":"2026-07-22T03:51:20.160722Z","steps":["trace[968757352] 'read index received' (duration: 367.388583ms)","trace[968757352] 'applied index is now lower than readState.Index' (duration: 592.087µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T03:51:20.160942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.801411ms","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-22T03:51:20.160965Z","caller":"traceutil/trace.go:171","msg":"trace[147343199] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2666; }","duration":"351.866464ms","start":"2026-07-22T03:51:19.809091Z","end":"2026-07-22T03:51:20.160958Z","steps":["trace[147343199] 'agreement among raft nodes before linearized reading' (duration: 351.790481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:51:20.160983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:51:19.809074Z","time spent":"351.905546ms","remote":"127.0.0.1:59034","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":"info","ts":"2026-07-22T03:51:20.161024Z","caller":"traceutil/trace.go:171","msg":"trace[1004673809] transaction","detail":"{read_only:false; response_revision:2666; number_of_response:1; }","duration":"457.667704ms","start":"2026-07-22T03:51:19.703337Z","end":"2026-07-22T03:51:20.161005Z","steps":["trace[1004673809] 'process raft request' (duration: 457.33587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:51:20.16112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:51:19.703325Z","time spent":"457.735888ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T03:51:20.160955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.193259ms","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-22T03:51:20.16121Z","caller":"traceutil/trace.go:171","msg":"trace[1004063575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2666; }","duration":"368.487092ms","start":"2026-07-22T03:51:19.792715Z","end":"2026-07-22T03:51:20.161202Z","steps":["trace[1004063575] 'agreement among raft nodes before linearized reading' (duration: 368.107065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:51:20.161231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:51:19.792704Z","time spent":"368.520965ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-22T03:51:54.918715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.416795ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T03:51:54.918881Z","caller":"traceutil/trace.go:171","msg":"trace[1145384492] transaction","detail":"{read_only:false; response_revision:2854; number_of_response:1; }","duration":"552.619317ms","start":"2026-07-22T03:51:54.366244Z","end":"2026-07-22T03:51:54.918863Z","steps":["trace[1145384492] 'process raft request' (duration: 552.558585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:51:54.918948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:51:54.366228Z","time spent":"552.688809ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T03:51:54.919153Z","caller":"traceutil/trace.go:171","msg":"trace[44913802] transaction","detail":"{read_only:false; response_revision:2853; number_of_response:1; }","duration":"605.799586ms","start":"2026-07-22T03:51:54.313322Z","end":"2026-07-22T03:51:54.919122Z","steps":["trace[44913802] 'process raft request' (duration: 153.906713ms)","trace[44913802] 'compare' (duration: 451.047331ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T03:51:54.919307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:51:54.313306Z","time spent":"605.93276ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T03:51:54.919518Z","caller":"traceutil/trace.go:171","msg":"trace[2023269672] linearizableReadLoop","detail":"{readStateIndex:2965; appliedIndex:2963; }","duration":"599.998435ms","start":"2026-07-22T03:51:54.319471Z","end":"2026-07-22T03:51:54.919469Z","steps":["trace[2023269672] 'read index received' (duration: 45.683653ms)","trace[2023269672] 'applied index is now lower than readState.Index' (duration: 554.312802ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T03:51:54.919708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.234944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T03:51:54.92044Z","caller":"traceutil/trace.go:171","msg":"trace[949673175] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2854; }","duration":"601.015233ms","start":"2026-07-22T03:51:54.319406Z","end":"2026-07-22T03:51:54.920421Z","steps":["trace[949673175] 'agreement among raft nodes before linearized reading' (duration: 600.227643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:51:54.920604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:51:54.319379Z","time spent":"601.21281ms","remote":"127.0.0.1:58942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":24,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-07-22T03:51:54.91979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.749711ms","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-22T03:51:54.921458Z","caller":"traceutil/trace.go:171","msg":"trace[692181549] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2854; }","duration":"571.423904ms","start":"2026-07-22T03:51:54.349994Z","end":"2026-07-22T03:51:54.921418Z","steps":["trace[692181549] 'agreement among raft nodes before linearized reading' (duration: 569.670878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:51:54.919812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.746972ms","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":"warn","ts":"2026-07-22T03:51:54.921574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:51:54.349982Z","time spent":"571.56974ms","remote":"127.0.0.1:59034","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":"info","ts":"2026-07-22T03:51:54.921642Z","caller":"traceutil/trace.go:171","msg":"trace[93998462] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2854; }","duration":"526.584493ms","start":"2026-07-22T03:51:54.395022Z","end":"2026-07-22T03:51:54.921606Z","steps":["trace[93998462] 'agreement among raft nodes before linearized reading' (duration: 524.66579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:51:54.921703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:51:54.395012Z","time spent":"526.678676ms","remote":"127.0.0.1:59112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":90,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-07-22T03:51:54.919883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.793272ms","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-22T03:51:54.921937Z","caller":"traceutil/trace.go:171","msg":"trace[1455059991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2854; }","duration":"560.847131ms","start":"2026-07-22T03:51:54.361027Z","end":"2026-07-22T03:51:54.921875Z","steps":["trace[1455059991] 'agreement among raft nodes before linearized reading' (duration: 558.787292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:51:54.919948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.589929ms","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-22T03:51:54.919994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.734713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-07-22T03:51:54.92001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.472258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-22T03:51:54.920012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-22T03:51:54.91999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.833735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c4802e7d606f02\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-07-22T03:51:54.922101Z","caller":"traceutil/trace.go:171","msg":"trace[1215711821] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2854; }","duration":"487.851084ms","start":"2026-07-22T03:51:54.434227Z","end":"2026-07-22T03:51:54.922078Z","steps":["trace[1215711821] 'agreement among raft nodes before linearized reading' (duration: 485.62999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:51:54.922166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:51:54.434216Z","time spent":"487.933499ms","remote":"127.0.0.1:59034","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":"info","ts":"2026-07-22T03:51:54.922155Z","caller":"traceutil/trace.go:171","msg":"trace[2065204487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2854; }","duration":"278.639502ms","start":"2026-07-22T03:51:54.643501Z","end":"2026-07-22T03:51:54.922141Z","steps":["trace[2065204487] 'agreement among raft nodes before linearized reading' (duration: 276.486909ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:51:54.92229Z","caller":"traceutil/trace.go:171","msg":"trace[1972842388] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2854; }","duration":"108.986299ms","start":"2026-07-22T03:51:54.813293Z","end":"2026-07-22T03:51:54.922279Z","steps":["trace[1972842388] 'agreement among raft nodes before linearized reading' (duration: 106.655589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:51:54.922313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:51:54.360996Z","time spent":"561.305129ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-22T03:51:54.922466Z","caller":"traceutil/trace.go:171","msg":"trace[1184739663] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2854; }","duration":"569.120876ms","start":"2026-07-22T03:51:54.353331Z","end":"2026-07-22T03:51:54.922452Z","steps":["trace[1184739663] 'agreement among raft nodes before linearized reading' (duration: 566.569198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:51:54.922515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:51:54.353321Z","time spent":"569.182109ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-07-22T03:51:54.922071Z","caller":"traceutil/trace.go:171","msg":"trace[617692170] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c4802e7d606f02; range_end:; response_count:1; response_revision:2854; }","duration":"413.993507ms","start":"2026-07-22T03:51:54.508062Z","end":"2026-07-22T03:51:54.922055Z","steps":["trace[617692170] 'agreement among raft nodes before linearized reading' (duration: 411.621986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:51:54.922625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:51:54.508039Z","time spent":"414.571939ms","remote":"127.0.0.1:58864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18c4802e7d606f02\" "} {"level":"info","ts":"2026-07-22T03:55:45.68699Z","caller":"traceutil/trace.go:171","msg":"trace[1673235555] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"125.440057ms","start":"2026-07-22T03:55:45.561513Z","end":"2026-07-22T03:55:45.686953Z","steps":["trace[1673235555] 'process raft request' (duration: 95.644508ms)","trace[1673235555] 'compare' (duration: 29.36393ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T03:55:45.987323Z","caller":"traceutil/trace.go:171","msg":"trace[674127820] transaction","detail":"{read_only:false; response_revision:4788; number_of_response:1; }","duration":"139.242584ms","start":"2026-07-22T03:55:45.848031Z","end":"2026-07-22T03:55:45.987274Z","steps":["trace[674127820] 'process raft request' (duration: 86.036716ms)","trace[674127820] 'compare' (duration: 52.8762ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T03:55:45.987471Z","caller":"traceutil/trace.go:171","msg":"trace[1186443098] linearizableReadLoop","detail":"{readStateIndex:4982; appliedIndex:4978; }","duration":"136.053439ms","start":"2026-07-22T03:55:45.851397Z","end":"2026-07-22T03:55:45.987451Z","steps":["trace[1186443098] 'read index received' (duration: 82.658848ms)","trace[1186443098] 'applied index is now lower than readState.Index' (duration: 53.393391ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T03:55:45.987693Z","caller":"traceutil/trace.go:171","msg":"trace[1451552828] transaction","detail":"{read_only:false; response_revision:4789; number_of_response:1; }","duration":"138.613351ms","start":"2026-07-22T03:55:45.849061Z","end":"2026-07-22T03:55:45.987675Z","steps":["trace[1451552828] 'process raft request' (duration: 138.091531ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:55:45.987926Z","caller":"traceutil/trace.go:171","msg":"trace[657975248] transaction","detail":"{read_only:false; response_revision:4791; number_of_response:1; }","duration":"138.444978ms","start":"2026-07-22T03:55:45.849472Z","end":"2026-07-22T03:55:45.987917Z","steps":["trace[657975248] 'process raft request' (duration: 137.877866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:55:45.987936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.524999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-07-22T03:55:45.987989Z","caller":"traceutil/trace.go:171","msg":"trace[1410857898] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4791; }","duration":"136.640502ms","start":"2026-07-22T03:55:45.851331Z","end":"2026-07-22T03:55:45.987971Z","steps":["trace[1410857898] 'agreement among raft nodes before linearized reading' (duration: 136.335755ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:55:45.987936Z","caller":"traceutil/trace.go:171","msg":"trace[323617046] transaction","detail":"{read_only:false; response_revision:4790; number_of_response:1; }","duration":"138.52031ms","start":"2026-07-22T03:55:45.849401Z","end":"2026-07-22T03:55:45.987921Z","steps":["trace[323617046] 'process raft request' (duration: 137.879457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:55:52.740886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.802101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T03:55:52.741034Z","caller":"traceutil/trace.go:171","msg":"trace[391293637] linearizableReadLoop","detail":"{readStateIndex:5108; appliedIndex:5107; }","duration":"232.082953ms","start":"2026-07-22T03:55:52.508932Z","end":"2026-07-22T03:55:52.741015Z","steps":["trace[391293637] 'read index received' (duration: 109.031477ms)","trace[391293637] 'applied index is now lower than readState.Index' (duration: 123.050296ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T03:55:52.741125Z","caller":"traceutil/trace.go:171","msg":"trace[944616660] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"237.640722ms","start":"2026-07-22T03:55:52.503435Z","end":"2026-07-22T03:55:52.741076Z","steps":["trace[944616660] 'process raft request' (duration: 114.561446ms)","trace[944616660] 'compare' (duration: 122.652947ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T03:55:52.741173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.263389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-22T03:55:52.741209Z","caller":"traceutil/trace.go:171","msg":"trace[1068515396] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:4915; }","duration":"206.310751ms","start":"2026-07-22T03:55:52.534885Z","end":"2026-07-22T03:55:52.741196Z","steps":["trace[1068515396] 'agreement among raft nodes before linearized reading' (duration: 206.239789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:55:52.741257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.315847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-566cff65f4-jq47c.18c4806b7c02a9f7\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-07-22T03:55:52.741339Z","caller":"traceutil/trace.go:171","msg":"trace[265360229] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-566cff65f4-jq47c.18c4806b7c02a9f7; range_end:; response_count:1; response_revision:4915; }","duration":"232.440379ms","start":"2026-07-22T03:55:52.50888Z","end":"2026-07-22T03:55:52.74132Z","steps":["trace[265360229] 'agreement among raft nodes before linearized reading' (duration: 232.213415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:55:52.741528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.350167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-22T03:55:52.741625Z","caller":"traceutil/trace.go:171","msg":"trace[993581108] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4915; }","duration":"162.487439ms","start":"2026-07-22T03:55:52.579112Z","end":"2026-07-22T03:55:52.741599Z","steps":["trace[993581108] 'agreement among raft nodes before linearized reading' (duration: 162.203973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:55:52.741728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.965594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:127764"} {"level":"info","ts":"2026-07-22T03:55:52.741777Z","caller":"traceutil/trace.go:171","msg":"trace[104106738] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4915; }","duration":"205.098086ms","start":"2026-07-22T03:55:52.536668Z","end":"2026-07-22T03:55:52.741766Z","steps":["trace[104106738] 'agreement among raft nodes before linearized reading' (duration: 204.631306ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:55:53.00837Z","caller":"traceutil/trace.go:171","msg":"trace[969595026] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"171.838086ms","start":"2026-07-22T03:55:52.836496Z","end":"2026-07-22T03:55:53.008334Z","steps":["trace[969595026] 'process raft request' (duration: 171.774815ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:55:53.00876Z","caller":"traceutil/trace.go:171","msg":"trace[2145157769] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"179.892695ms","start":"2026-07-22T03:55:52.82883Z","end":"2026-07-22T03:55:53.008722Z","steps":["trace[2145157769] 'process raft request' (duration: 101.187241ms)","trace[2145157769] 'compare' (duration: 77.873448ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T03:55:53.008878Z","caller":"traceutil/trace.go:171","msg":"trace[201590134] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"172.442627ms","start":"2026-07-22T03:55:52.83621Z","end":"2026-07-22T03:55:53.008652Z","steps":["trace[201590134] 'process raft request' (duration: 171.932216ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:55:53.008927Z","caller":"traceutil/trace.go:171","msg":"trace[594740551] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"172.478798ms","start":"2026-07-22T03:55:52.836418Z","end":"2026-07-22T03:55:53.008897Z","steps":["trace[594740551] 'process raft request' (duration: 171.785504ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:56:01.335347Z","caller":"traceutil/trace.go:171","msg":"trace[266418277] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"133.500263ms","start":"2026-07-22T03:56:01.20183Z","end":"2026-07-22T03:56:01.335331Z","steps":["trace[266418277] 'process raft request' (duration: 87.56734ms)","trace[266418277] 'compare' (duration: 45.621126ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T03:56:24.524911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.378701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T03:56:24.525089Z","caller":"traceutil/trace.go:171","msg":"trace[1509868241] linearizableReadLoop","detail":"{readStateIndex:5496; appliedIndex:5495; }","duration":"463.796324ms","start":"2026-07-22T03:56:24.061275Z","end":"2026-07-22T03:56:24.525071Z","steps":["trace[1509868241] 'read index received' (duration: 39.826802ms)","trace[1509868241] 'applied index is now lower than readState.Index' (duration: 423.967992ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T03:56:24.525107Z","caller":"traceutil/trace.go:171","msg":"trace[701339405] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"490.399523ms","start":"2026-07-22T03:56:24.034677Z","end":"2026-07-22T03:56:24.525077Z","steps":["trace[701339405] 'process raft request' (duration: 66.404491ms)","trace[701339405] 'compare' (duration: 422.285398ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T03:56:24.525257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:56:24.034654Z","time spent":"490.526015ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T03:56:24.526916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.632678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T03:56:24.527007Z","caller":"traceutil/trace.go:171","msg":"trace[1677277759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5293; }","duration":"465.726171ms","start":"2026-07-22T03:56:24.061267Z","end":"2026-07-22T03:56:24.526994Z","steps":["trace[1677277759] 'agreement among raft nodes before linearized reading' (duration: 463.932326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:56:24.527067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:56:24.061209Z","time spent":"465.848213ms","remote":"127.0.0.1:58786","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-22T03:56:24.591409Z","caller":"traceutil/trace.go:171","msg":"trace[1245371543] transaction","detail":"{read_only:false; response_revision:5294; number_of_response:1; }","duration":"478.635708ms","start":"2026-07-22T03:56:24.112743Z","end":"2026-07-22T03:56:24.591379Z","steps":["trace[1245371543] 'process raft request' (duration: 478.450963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:56:24.591556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T03:56:24.11272Z","time spent":"478.759159ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T03:56:24.591667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.434635ms","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-22T03:56:24.591746Z","caller":"traceutil/trace.go:171","msg":"trace[1096755879] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5294; }","duration":"211.556048ms","start":"2026-07-22T03:56:24.380171Z","end":"2026-07-22T03:56:24.591727Z","steps":["trace[1096755879] 'agreement among raft nodes before linearized reading' (duration: 211.337364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:56:24.59189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.81211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-07-22T03:56:24.591938Z","caller":"traceutil/trace.go:171","msg":"trace[1340120684] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5294; }","duration":"236.875501ms","start":"2026-07-22T03:56:24.355049Z","end":"2026-07-22T03:56:24.591925Z","steps":["trace[1340120684] 'agreement among raft nodes before linearized reading' (duration: 236.741529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:56:24.592103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.239705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-22T03:56:24.592126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.42606ms","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-22T03:56:24.592147Z","caller":"traceutil/trace.go:171","msg":"trace[509013751] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5294; }","duration":"195.306627ms","start":"2026-07-22T03:56:24.39683Z","end":"2026-07-22T03:56:24.592137Z","steps":["trace[509013751] 'agreement among raft nodes before linearized reading' (duration: 195.122833ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:56:24.592157Z","caller":"traceutil/trace.go:171","msg":"trace[2067637542] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5294; }","duration":"240.477371ms","start":"2026-07-22T03:56:24.351672Z","end":"2026-07-22T03:56:24.592149Z","steps":["trace[2067637542] 'agreement among raft nodes before linearized reading' (duration: 240.39497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:56:24.592288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.912375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-22T03:56:24.592319Z","caller":"traceutil/trace.go:171","msg":"trace[148839009] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:5294; }","duration":"268.983036ms","start":"2026-07-22T03:56:24.323328Z","end":"2026-07-22T03:56:24.592311Z","steps":["trace[148839009] 'agreement among raft nodes before linearized reading' (duration: 268.899275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:56:24.592368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.400288ms","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-22T03:56:24.592405Z","caller":"traceutil/trace.go:171","msg":"trace[1132125904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5294; }","duration":"196.457119ms","start":"2026-07-22T03:56:24.395939Z","end":"2026-07-22T03:56:24.592396Z","steps":["trace[1132125904] 'agreement among raft nodes before linearized reading' (duration: 196.369487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:56:24.592573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.967758ms","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-22T03:56:24.592618Z","caller":"traceutil/trace.go:171","msg":"trace[137776920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5294; }","duration":"197.030749ms","start":"2026-07-22T03:56:24.395575Z","end":"2026-07-22T03:56:24.592606Z","steps":["trace[137776920] 'agreement among raft nodes before linearized reading' (duration: 196.940858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:56:24.59273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.041506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-22T03:56:24.592766Z","caller":"traceutil/trace.go:171","msg":"trace[222190410] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5294; }","duration":"201.112718ms","start":"2026-07-22T03:56:24.391642Z","end":"2026-07-22T03:56:24.592754Z","steps":["trace[222190410] 'agreement among raft nodes before linearized reading' (duration: 201.041016ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:56:24.709563Z","caller":"traceutil/trace.go:171","msg":"trace[1597158127] transaction","detail":"{read_only:false; response_revision:5295; number_of_response:1; }","duration":"112.655555ms","start":"2026-07-22T03:56:24.596887Z","end":"2026-07-22T03:56:24.709542Z","steps":["trace[1597158127] 'process raft request' (duration: 112.37683ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:56:24.709677Z","caller":"traceutil/trace.go:171","msg":"trace[1747639977] transaction","detail":"{read_only:false; response_revision:5296; number_of_response:1; }","duration":"112.755798ms","start":"2026-07-22T03:56:24.596909Z","end":"2026-07-22T03:56:24.709665Z","steps":["trace[1747639977] 'process raft request' (duration: 112.501373ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:56:24.709821Z","caller":"traceutil/trace.go:171","msg":"trace[2084333249] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"112.903591ms","start":"2026-07-22T03:56:24.596906Z","end":"2026-07-22T03:56:24.709809Z","steps":["trace[2084333249] 'process raft request' (duration: 112.722947ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:56:24.710057Z","caller":"traceutil/trace.go:171","msg":"trace[1043310560] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"113.068373ms","start":"2026-07-22T03:56:24.596973Z","end":"2026-07-22T03:56:24.710041Z","steps":["trace[1043310560] 'process raft request' (duration: 112.686355ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:56:24.711822Z","caller":"traceutil/trace.go:171","msg":"trace[9482563] linearizableReadLoop","detail":"{readStateIndex:5505; appliedIndex:5497; }","duration":"103.656773ms","start":"2026-07-22T03:56:24.608157Z","end":"2026-07-22T03:56:24.711814Z","steps":["trace[9482563] 'read index received' (duration: 82.703281ms)","trace[9482563] 'applied index is now lower than readState.Index' (duration: 20.953112ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T03:56:24.711976Z","caller":"traceutil/trace.go:171","msg":"trace[424336640] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"113.577732ms","start":"2026-07-22T03:56:24.598392Z","end":"2026-07-22T03:56:24.71197Z","steps":["trace[424336640] 'process raft request' (duration: 113.332127ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:56:24.712341Z","caller":"traceutil/trace.go:171","msg":"trace[268626626] transaction","detail":"{read_only:false; response_revision:5301; number_of_response:1; }","duration":"113.41277ms","start":"2026-07-22T03:56:24.59891Z","end":"2026-07-22T03:56:24.712323Z","steps":["trace[268626626] 'process raft request' (duration: 112.88074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:56:24.712236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.053491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T03:56:24.71244Z","caller":"traceutil/trace.go:171","msg":"trace[1854634179] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5301; }","duration":"104.335316ms","start":"2026-07-22T03:56:24.608095Z","end":"2026-07-22T03:56:24.712431Z","steps":["trace[1854634179] 'agreement among raft nodes before linearized reading' (duration: 104.049851ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:56:24.712932Z","caller":"traceutil/trace.go:171","msg":"trace[884081194] transaction","detail":"{read_only:false; response_revision:5299; number_of_response:1; }","duration":"114.019202ms","start":"2026-07-22T03:56:24.597807Z","end":"2026-07-22T03:56:24.711826Z","steps":["trace[884081194] 'process raft request' (duration: 111.996983ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:56:24.855782Z","caller":"traceutil/trace.go:171","msg":"trace[751962447] linearizableReadLoop","detail":"{readStateIndex:5506; appliedIndex:5505; }","duration":"143.896442ms","start":"2026-07-22T03:56:24.71187Z","end":"2026-07-22T03:56:24.855767Z","steps":["trace[751962447] 'read index received' (duration: 134.117045ms)","trace[751962447] 'applied index is now lower than readState.Index' (duration: 9.778727ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T03:56:24.855857Z","caller":"traceutil/trace.go:171","msg":"trace[1638342201] transaction","detail":"{read_only:false; response_revision:5302; number_of_response:1; }","duration":"156.532994ms","start":"2026-07-22T03:56:24.69932Z","end":"2026-07-22T03:56:24.855853Z","steps":["trace[1638342201] 'process raft request' (duration: 146.785298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:56:24.856334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.123028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-credential-setup-zmnmd\" ","response":"range_response_count:1 size:7557"} {"level":"info","ts":"2026-07-22T03:56:24.856389Z","caller":"traceutil/trace.go:171","msg":"trace[338523927] range","detail":"{range_begin:/registry/pods/openstack/keystone-credential-setup-zmnmd; range_end:; response_count:1; response_revision:5302; }","duration":"131.21523ms","start":"2026-07-22T03:56:24.725159Z","end":"2026-07-22T03:56:24.856374Z","steps":["trace[338523927] 'agreement among raft nodes before linearized reading' (duration: 130.981615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T03:56:24.857092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.911614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T03:56:24.857117Z","caller":"traceutil/trace.go:171","msg":"trace[1059882826] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:5302; }","duration":"188.987055ms","start":"2026-07-22T03:56:24.668123Z","end":"2026-07-22T03:56:24.85711Z","steps":["trace[1059882826] 'agreement among raft nodes before linearized reading' (duration: 187.856744ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T03:56:51.26138Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2842} {"level":"info","ts":"2026-07-22T03:56:51.336416Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2842,"took":"73.732384ms","hash":2989163446,"current-db-size-bytes":53268480,"current-db-size":"53 MB","current-db-size-in-use-bytes":29200384,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-22T03:56:51.336567Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2989163446,"revision":2842,"compact-revision":-1} {"level":"info","ts":"2026-07-22T04:01:51.268683Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5492} {"level":"info","ts":"2026-07-22T04:01:51.354103Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5492,"took":"83.797566ms","hash":1618882135,"current-db-size-bytes":53268480,"current-db-size":"53 MB","current-db-size-in-use-bytes":18608128,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-07-22T04:01:51.354196Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1618882135,"revision":5492,"compact-revision":2842}