{"level":"warn","ts":"2026-10-27T21:03:27.495368Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-27T21:03:27.495478Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.95:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.95:2380","--initial-cluster=instance=https://199.19.213.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.95: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-10-27T21:03:27.495561Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-27T21:03:27.495575Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.95:2380"]} {"level":"info","ts":"2026-10-27T21:03:27.495603Z","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-10-27T21:03:27.496794Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.95:2379"]} {"level":"info","ts":"2026-10-27T21:03:27.498147Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.95:2380"],"listen-peer-urls":["https://199.19.213.95:2380"],"advertise-client-urls":["https://199.19.213.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.95: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-10-27T21:03:27.503498Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.768729ms"} {"level":"info","ts":"2026-10-27T21:03:27.509912Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"371c7c2e52231793","cluster-id":"c115742627eabb7c"} {"level":"info","ts":"2026-10-27T21:03:27.510066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 switched to configuration voters=()"} {"level":"info","ts":"2026-10-27T21:03:27.510104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 became follower at term 0"} {"level":"info","ts":"2026-10-27T21:03:27.51019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 371c7c2e52231793 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-27T21:03:27.510224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 became follower at term 1"} {"level":"info","ts":"2026-10-27T21:03:27.510291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 switched to configuration voters=(3971185509822306195)"} {"level":"warn","ts":"2026-10-27T21:03:27.517172Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-27T21:03:27.521239Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-27T21:03:27.523399Z","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-10-27T21:03:27.526831Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"371c7c2e52231793","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-27T21:03:27.527076Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"371c7c2e52231793","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-27T21:03:27.527202Z","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-10-27T21:03:27.527405Z","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-10-27T21:03:27.527425Z","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-10-27T21:03:27.52857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 switched to configuration voters=(3971185509822306195)"} {"level":"info","ts":"2026-10-27T21:03:27.528735Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c115742627eabb7c","local-member-id":"371c7c2e52231793","added-peer-id":"371c7c2e52231793","added-peer-peer-urls":["https://199.19.213.95:2380"]} {"level":"info","ts":"2026-10-27T21:03:27.531157Z","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-10-27T21:03:27.531294Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.95:2380"} {"level":"info","ts":"2026-10-27T21:03:27.531366Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.95:2380"} {"level":"info","ts":"2026-10-27T21:03:27.531551Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"371c7c2e52231793","initial-advertise-peer-urls":["https://199.19.213.95:2380"],"listen-peer-urls":["https://199.19.213.95:2380"],"advertise-client-urls":["https://199.19.213.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-27T21:03:27.531625Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-27T21:03:28.511502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 is starting a new election at term 1"} {"level":"info","ts":"2026-10-27T21:03:28.511571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-27T21:03:28.511622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 received MsgPreVoteResp from 371c7c2e52231793 at term 1"} {"level":"info","ts":"2026-10-27T21:03:28.511805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 became candidate at term 2"} {"level":"info","ts":"2026-10-27T21:03:28.511838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 received MsgVoteResp from 371c7c2e52231793 at term 2"} {"level":"info","ts":"2026-10-27T21:03:28.511854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 became leader at term 2"} {"level":"info","ts":"2026-10-27T21:03:28.511868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 371c7c2e52231793 elected leader 371c7c2e52231793 at term 2"} {"level":"info","ts":"2026-10-27T21:03:28.513268Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T21:03:28.514499Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-27T21:03:28.514485Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"371c7c2e52231793","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.95:2379]}","request-path":"/0/members/371c7c2e52231793/attributes","cluster-id":"c115742627eabb7c","publish-timeout":"7s"} {"level":"info","ts":"2026-10-27T21:03:28.514584Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-27T21:03:28.51484Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-27T21:03:28.514899Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-27T21:03:28.515163Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c115742627eabb7c","local-member-id":"371c7c2e52231793","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T21:03:28.515331Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T21:03:28.515385Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T21:03:28.518467Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-27T21:03:28.518783Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.95:2379"} {"level":"info","ts":"2026-10-27T21:03:41.01559Z","caller":"traceutil/trace.go:171","msg":"trace[871892588] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:413; }","duration":"117.30443ms","start":"2026-10-27T21:03:40.898262Z","end":"2026-10-27T21:03:41.015566Z","steps":["trace[871892588] 'read index received' (duration: 117.148956ms)","trace[871892588] 'applied index is now lower than readState.Index' (duration: 154.544µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:03:41.015716Z","caller":"traceutil/trace.go:171","msg":"trace[296813345] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"160.663899ms","start":"2026-10-27T21:03:40.855013Z","end":"2026-10-27T21:03:41.015677Z","steps":["trace[296813345] 'process raft request' (duration: 160.393732ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:03:41.015738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.460084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-10-27T21:03:41.01599Z","caller":"traceutil/trace.go:171","msg":"trace[1464525222] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:401; }","duration":"117.813625ms","start":"2026-10-27T21:03:40.898162Z","end":"2026-10-27T21:03:41.015976Z","steps":["trace[1464525222] 'agreement among raft nodes before linearized reading' (duration: 117.513306ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:03:41.488731Z","caller":"traceutil/trace.go:171","msg":"trace[1277430223] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"458.382151ms","start":"2026-10-27T21:03:41.030321Z","end":"2026-10-27T21:03:41.488703Z","steps":["trace[1277430223] 'process raft request' (duration: 398.089467ms)","trace[1277430223] 'compare' (duration: 60.166641ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:03:41.489296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:03:41.030298Z","time spent":"458.506045ms","remote":"127.0.0.1:49344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:03:41.868196Z","caller":"traceutil/trace.go:171","msg":"trace[985587293] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"367.191175ms","start":"2026-10-27T21:03:41.500935Z","end":"2026-10-27T21:03:41.868126Z","steps":["trace[985587293] 'process raft request' (duration: 284.163008ms)","trace[985587293] 'compare' (duration: 82.620686ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:03:41.868504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:03:41.500909Z","time spent":"367.40823ms","remote":"127.0.0.1:49344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:04:14.020645Z","caller":"traceutil/trace.go:171","msg":"trace[1537455850] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"125.946094ms","start":"2026-10-27T21:04:13.894664Z","end":"2026-10-27T21:04:14.020611Z","steps":["trace[1537455850] 'process raft request' (duration: 125.717418ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:04:14.028568Z","caller":"traceutil/trace.go:171","msg":"trace[2007035740] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"124.009071ms","start":"2026-10-27T21:04:13.904529Z","end":"2026-10-27T21:04:14.028538Z","steps":["trace[2007035740] 'process raft request' (duration: 123.769795ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:04:14.028507Z","caller":"traceutil/trace.go:171","msg":"trace[781758978] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"124.394722ms","start":"2026-10-27T21:04:13.904062Z","end":"2026-10-27T21:04:14.028456Z","steps":["trace[781758978] 'process raft request' (duration: 124.109955ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:04:14.028778Z","caller":"traceutil/trace.go:171","msg":"trace[76950596] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"109.451099ms","start":"2026-10-27T21:04:13.919266Z","end":"2026-10-27T21:04:14.028717Z","steps":["trace[76950596] 'process raft request' (duration: 109.225783ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:04:14.028798Z","caller":"traceutil/trace.go:171","msg":"trace[634228581] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"112.278525ms","start":"2026-10-27T21:04:13.916471Z","end":"2026-10-27T21:04:14.028749Z","steps":["trace[634228581] 'process raft request' (duration: 111.894625ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:04:14.028857Z","caller":"traceutil/trace.go:171","msg":"trace[571604736] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"108.976227ms","start":"2026-10-27T21:04:13.919834Z","end":"2026-10-27T21:04:14.02881Z","steps":["trace[571604736] 'process raft request' (duration: 108.73851ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:04:14.028902Z","caller":"traceutil/trace.go:171","msg":"trace[506900302] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"105.929825ms","start":"2026-10-27T21:04:13.922954Z","end":"2026-10-27T21:04:14.028883Z","steps":["trace[506900302] 'process raft request' (duration: 105.656017ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:04:14.028917Z","caller":"traceutil/trace.go:171","msg":"trace[1505192444] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"109.273384ms","start":"2026-10-27T21:04:13.919551Z","end":"2026-10-27T21:04:14.028824Z","steps":["trace[1505192444] 'process raft request' (duration: 108.982936ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:04:21.013069Z","caller":"traceutil/trace.go:171","msg":"trace[961156110] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"171.82918ms","start":"2026-10-27T21:04:20.84119Z","end":"2026-10-27T21:04:21.01302Z","steps":["trace[961156110] 'read index received' (duration: 123.251211ms)","trace[961156110] 'applied index is now lower than readState.Index' (duration: 48.576579ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:04:21.013137Z","caller":"traceutil/trace.go:171","msg":"trace[1709488492] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"184.346818ms","start":"2026-10-27T21:04:20.828742Z","end":"2026-10-27T21:04:21.013088Z","steps":["trace[1709488492] 'process raft request' (duration: 135.7853ms)","trace[1709488492] 'compare' (duration: 48.358393ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:04:21.013213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.993105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:04:21.013303Z","caller":"traceutil/trace.go:171","msg":"trace[1991329314] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:658; }","duration":"172.096427ms","start":"2026-10-27T21:04:20.841184Z","end":"2026-10-27T21:04:21.01328Z","steps":["trace[1991329314] 'agreement among raft nodes before linearized reading' (duration: 171.948783ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:04:21.246129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.542491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T21:04:21.246228Z","caller":"traceutil/trace.go:171","msg":"trace[408764091] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"102.358108ms","start":"2026-10-27T21:04:21.143851Z","end":"2026-10-27T21:04:21.246209Z","steps":["trace[408764091] 'read index received' (duration: 35.461µs)","trace[408764091] 'applied index is now lower than readState.Index' (duration: 102.321727ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:04:21.246281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.43141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:04:21.246298Z","caller":"traceutil/trace.go:171","msg":"trace[742069192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"102.45043ms","start":"2026-10-27T21:04:21.143841Z","end":"2026-10-27T21:04:21.246292Z","steps":["trace[742069192] 'agreement among raft nodes before linearized reading' (duration: 102.406149ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:04:21.246295Z","caller":"traceutil/trace.go:171","msg":"trace[1771396674] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"170.415812ms","start":"2026-10-27T21:04:21.075849Z","end":"2026-10-27T21:04:21.246265Z","steps":["trace[1771396674] 'process raft request' (duration: 40.516691ms)","trace[1771396674] 'compare' (duration: 129.426028ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:04:21.640085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.039181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T21:04:21.640226Z","caller":"traceutil/trace.go:171","msg":"trace[1344390033] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"346.131338ms","start":"2026-10-27T21:04:21.29407Z","end":"2026-10-27T21:04:21.640202Z","steps":["trace[1344390033] 'process raft request' (duration: 99.765129ms)","trace[1344390033] 'compare' (duration: 245.601259ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:04:21.64036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:04:21.293993Z","time spent":"346.319933ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T21:04:22.148408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.334914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T21:04:22.148509Z","caller":"traceutil/trace.go:171","msg":"trace[1754927329] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:684; }","duration":"357.97745ms","start":"2026-10-27T21:04:21.790516Z","end":"2026-10-27T21:04:22.148493Z","steps":["trace[1754927329] 'read index received' (duration: 54.652µs)","trace[1754927329] 'applied index is now lower than readState.Index' (duration: 357.921648ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:04:22.148562Z","caller":"traceutil/trace.go:171","msg":"trace[1262872666] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"697.269543ms","start":"2026-10-27T21:04:21.45126Z","end":"2026-10-27T21:04:22.148529Z","steps":["trace[1262872666] 'process raft request' (duration: 336.735984ms)","trace[1262872666] 'compare' (duration: 359.978094ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:04:22.148626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.111765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-27T21:04:22.148666Z","caller":"traceutil/trace.go:171","msg":"trace[938400413] range","detail":"{range_begin:/registry/masterleases/199.19.213.95; range_end:; response_count:1; response_revision:662; }","duration":"358.192227ms","start":"2026-10-27T21:04:21.790462Z","end":"2026-10-27T21:04:22.148654Z","steps":["trace[938400413] 'agreement among raft nodes before linearized reading' (duration: 358.113745ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:04:22.148708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:04:21.790443Z","time spent":"358.253329ms","remote":"127.0.0.1:49218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.95\" "} {"level":"warn","ts":"2026-10-27T21:04:22.148683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.427449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:04:22.148773Z","caller":"traceutil/trace.go:171","msg":"trace[1594477834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:662; }","duration":"176.562862ms","start":"2026-10-27T21:04:21.972187Z","end":"2026-10-27T21:04:22.14875Z","steps":["trace[1594477834] 'agreement among raft nodes before linearized reading' (duration: 176.417358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:04:22.148785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:04:21.45123Z","time spent":"697.403677ms","remote":"127.0.0.1:49254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:04:22.339202Z","caller":"traceutil/trace.go:171","msg":"trace[1717194180] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"114.630034ms","start":"2026-10-27T21:04:22.224543Z","end":"2026-10-27T21:04:22.339173Z","steps":["trace[1717194180] 'process raft request' (duration: 114.406907ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:04:22.589929Z","caller":"traceutil/trace.go:171","msg":"trace[939297146] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:687; }","duration":"246.019402ms","start":"2026-10-27T21:04:22.343887Z","end":"2026-10-27T21:04:22.589906Z","steps":["trace[939297146] 'read index received' (duration: 10.3649ms)","trace[939297146] 'applied index is now lower than readState.Index' (duration: 235.653582ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:04:22.590183Z","caller":"traceutil/trace.go:171","msg":"trace[949371259] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"246.557867ms","start":"2026-10-27T21:04:22.343598Z","end":"2026-10-27T21:04:22.590156Z","steps":["trace[949371259] 'process raft request' (duration: 171.676809ms)","trace[949371259] 'compare' (duration: 74.525548ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:04:22.590273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.328981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-10-27T21:04:22.590343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.538374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-10-27T21:04:22.590382Z","caller":"traceutil/trace.go:171","msg":"trace[624145072] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:665; }","duration":"246.502355ms","start":"2026-10-27T21:04:22.343855Z","end":"2026-10-27T21:04:22.590358Z","steps":["trace[624145072] 'agreement among raft nodes before linearized reading' (duration: 246.254348ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:04:22.590403Z","caller":"traceutil/trace.go:171","msg":"trace[687357975] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:665; }","duration":"233.656158ms","start":"2026-10-27T21:04:22.356731Z","end":"2026-10-27T21:04:22.590387Z","steps":["trace[687357975] 'agreement among raft nodes before linearized reading' (duration: 233.525984ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:04:37.965208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.736816ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-27T21:04:37.965353Z","caller":"traceutil/trace.go:171","msg":"trace[765001941] linearizableReadLoop","detail":"{readStateIndex:856; appliedIndex:855; }","duration":"251.594644ms","start":"2026-10-27T21:04:37.713735Z","end":"2026-10-27T21:04:37.96533Z","steps":["trace[765001941] 'read index received' (duration: 81.646681ms)","trace[765001941] 'applied index is now lower than readState.Index' (duration: 169.946143ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:04:37.965541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.865953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-27T21:04:37.965584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.840521ms","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-10-27T21:04:37.965594Z","caller":"traceutil/trace.go:171","msg":"trace[1546851278] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:827; }","duration":"141.987336ms","start":"2026-10-27T21:04:37.823592Z","end":"2026-10-27T21:04:37.965579Z","steps":["trace[1546851278] 'agreement among raft nodes before linearized reading' (duration: 141.864243ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:04:37.965623Z","caller":"traceutil/trace.go:171","msg":"trace[1254974336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:827; }","duration":"251.914403ms","start":"2026-10-27T21:04:37.713696Z","end":"2026-10-27T21:04:37.96561Z","steps":["trace[1254974336] 'agreement among raft nodes before linearized reading' (duration: 251.744218ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:05:06.209804Z","caller":"traceutil/trace.go:171","msg":"trace[656468536] linearizableReadLoop","detail":"{readStateIndex:1258; appliedIndex:1257; }","duration":"108.301793ms","start":"2026-10-27T21:05:06.101476Z","end":"2026-10-27T21:05:06.209778Z","steps":["trace[656468536] 'read index received' (duration: 108.055237ms)","trace[656468536] 'applied index is now lower than readState.Index' (duration: 245.606µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:05:06.209936Z","caller":"traceutil/trace.go:171","msg":"trace[1102749333] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"147.078693ms","start":"2026-10-27T21:05:06.062805Z","end":"2026-10-27T21:05:06.209883Z","steps":["trace[1102749333] 'process raft request' (duration: 146.793346ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:05:06.210207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.713165ms","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-10-27T21:05:06.210331Z","caller":"traceutil/trace.go:171","msg":"trace[2030366223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1223; }","duration":"108.900269ms","start":"2026-10-27T21:05:06.101402Z","end":"2026-10-27T21:05:06.210303Z","steps":["trace[2030366223] 'agreement among raft nodes before linearized reading' (duration: 108.536ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:05:06.450101Z","caller":"traceutil/trace.go:171","msg":"trace[1429470758] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"235.412565ms","start":"2026-10-27T21:05:06.214656Z","end":"2026-10-27T21:05:06.450069Z","steps":["trace[1429470758] 'process raft request' (duration: 235.284172ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:05:06.450136Z","caller":"traceutil/trace.go:171","msg":"trace[1783379266] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"235.948551ms","start":"2026-10-27T21:05:06.214136Z","end":"2026-10-27T21:05:06.450084Z","steps":["trace[1783379266] 'process raft request' (duration: 188.754463ms)","trace[1783379266] 'compare' (duration: 46.872449ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:05:24.098921Z","caller":"traceutil/trace.go:171","msg":"trace[2089236983] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"100.81374ms","start":"2026-10-27T21:05:23.998061Z","end":"2026-10-27T21:05:24.098874Z","steps":["trace[2089236983] 'process raft request' (duration: 71.373372ms)","trace[2089236983] 'compare' (duration: 29.255593ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:05:52.600279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.199167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7e670598-e757-459b-b9bb-fa82787098c2\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-27T21:05:52.600436Z","caller":"traceutil/trace.go:171","msg":"trace[27201398] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7e670598-e757-459b-b9bb-fa82787098c2; range_end:; response_count:1; response_revision:1880; }","duration":"109.443793ms","start":"2026-10-27T21:05:52.490958Z","end":"2026-10-27T21:05:52.600402Z","steps":["trace[27201398] 'range keys from in-memory index tree' (duration: 108.911279ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:05:52.849743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.881402ms","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-10-27T21:05:52.849853Z","caller":"traceutil/trace.go:171","msg":"trace[590265310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1881; }","duration":"142.044386ms","start":"2026-10-27T21:05:52.707786Z","end":"2026-10-27T21:05:52.84983Z","steps":["trace[590265310] 'range keys from in-memory index tree' (duration: 141.710628ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:05:53.024545Z","caller":"traceutil/trace.go:171","msg":"trace[1457222879] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"169.936442ms","start":"2026-10-27T21:05:52.854555Z","end":"2026-10-27T21:05:53.024492Z","steps":["trace[1457222879] 'process raft request' (duration: 123.177026ms)","trace[1457222879] 'compare' (duration: 46.51236ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:05:53.250413Z","caller":"traceutil/trace.go:171","msg":"trace[1273325782] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"153.406585ms","start":"2026-10-27T21:05:53.096977Z","end":"2026-10-27T21:05:53.250383Z","steps":["trace[1273325782] 'process raft request' (duration: 153.326572ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:05:53.250467Z","caller":"traceutil/trace.go:171","msg":"trace[1398525884] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"217.155531ms","start":"2026-10-27T21:05:53.033291Z","end":"2026-10-27T21:05:53.250447Z","steps":["trace[1398525884] 'process raft request' (duration: 142.640333ms)","trace[1398525884] 'compare' (duration: 74.19192ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:05:53.250491Z","caller":"traceutil/trace.go:171","msg":"trace[956309314] linearizableReadLoop","detail":"{readStateIndex:1958; appliedIndex:1957; }","duration":"177.850796ms","start":"2026-10-27T21:05:53.072598Z","end":"2026-10-27T21:05:53.250449Z","steps":["trace[956309314] 'read index received' (duration: 103.449741ms)","trace[956309314] 'applied index is now lower than readState.Index' (duration: 74.397915ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:05:53.250779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.159815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-10-27T21:05:53.250864Z","caller":"traceutil/trace.go:171","msg":"trace[302293307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1886; }","duration":"178.314979ms","start":"2026-10-27T21:05:53.072529Z","end":"2026-10-27T21:05:53.250844Z","steps":["trace[302293307] 'agreement among raft nodes before linearized reading' (duration: 178.065872ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:06:38.84377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.459661415s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-10-27T21:06:38.844234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.448585ms","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-10-27T21:06:38.844447Z","caller":"traceutil/trace.go:171","msg":"trace[1538901469] linearizableReadLoop","detail":"{readStateIndex:2264; appliedIndex:2262; }","duration":"1.579294249s","start":"2026-10-27T21:06:37.265104Z","end":"2026-10-27T21:06:38.844398Z","steps":["trace[1538901469] 'read index received' (duration: 118.949775ms)","trace[1538901469] 'applied index is now lower than readState.Index' (duration: 1.460343153s)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:06:38.844502Z","caller":"traceutil/trace.go:171","msg":"trace[1847279241] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"1.591650373s","start":"2026-10-27T21:06:37.252813Z","end":"2026-10-27T21:06:38.844463Z","steps":["trace[1847279241] 'process raft request' (duration: 1.591068088s)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:06:38.844457Z","caller":"traceutil/trace.go:171","msg":"trace[754419410] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2179; }","duration":"217.677781ms","start":"2026-10-27T21:06:38.626752Z","end":"2026-10-27T21:06:38.84443Z","steps":["trace[754419410] 'range keys from in-memory index tree' (duration: 217.430495ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:06:38.844668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:37.252789Z","time spent":"1.591779717s","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T21:06:39.309014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.043887335s","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-10-27T21:06:39.309111Z","caller":"traceutil/trace.go:171","msg":"trace[267856140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2180; }","duration":"2.044031719s","start":"2026-10-27T21:06:37.265061Z","end":"2026-10-27T21:06:39.309092Z","steps":["trace[267856140] 'agreement among raft nodes before linearized reading' (duration: 1.579481543s)","trace[267856140] 'range keys from in-memory index tree' (duration: 464.339039ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:06:39.309149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:37.265039Z","time spent":"2.04410061s","remote":"127.0.0.1:49438","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-10-27T21:06:39.309466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.890695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T21:06:39.309794Z","caller":"traceutil/trace.go:171","msg":"trace[550872432] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"1.801870154s","start":"2026-10-27T21:06:37.507889Z","end":"2026-10-27T21:06:39.309759Z","steps":["trace[550872432] 'process raft request' (duration: 1.336503226s)","trace[550872432] 'compare' (duration: 464.388831ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:06:39.309923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:37.50786Z","time spent":"1.801997706s","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:06:39.309942Z","caller":"traceutil/trace.go:171","msg":"trace[90102684] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"1.014939268s","start":"2026-10-27T21:06:38.294978Z","end":"2026-10-27T21:06:39.309918Z","steps":["trace[90102684] 'process raft request' (duration: 1.014832255s)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:06:39.309975Z","caller":"traceutil/trace.go:171","msg":"trace[1318502779] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"1.475433643s","start":"2026-10-27T21:06:37.834481Z","end":"2026-10-27T21:06:39.309915Z","steps":["trace[1318502779] 'process raft request' (duration: 1.475116504s)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:06:39.3101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:38.294955Z","time spent":"1.015036931s","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:06:39.310212Z","caller":"traceutil/trace.go:171","msg":"trace[1542677171] linearizableReadLoop","detail":"{readStateIndex:2269; appliedIndex:2264; }","duration":"465.685536ms","start":"2026-10-27T21:06:38.844511Z","end":"2026-10-27T21:06:39.310196Z","steps":["trace[1542677171] 'read index received' (duration: 275.670344ms)","trace[1542677171] 'applied index is now lower than readState.Index' (duration: 190.014262ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:06:39.310225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:37.834454Z","time spent":"1.4756744s","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:06:39.310283Z","caller":"traceutil/trace.go:171","msg":"trace[1676887759] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"687.002077ms","start":"2026-10-27T21:06:38.62322Z","end":"2026-10-27T21:06:39.310222Z","steps":["trace[1676887759] 'process raft request' (duration: 686.858833ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:06:39.310336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391088637s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5732"} {"level":"warn","ts":"2026-10-27T21:06:39.310379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:38.623192Z","time spent":"687.131121ms","remote":"127.0.0.1:49336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8051,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T21:06:39.310377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.425583422s","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-10-27T21:06:39.310395Z","caller":"traceutil/trace.go:171","msg":"trace[1431243034] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2185; }","duration":"1.391172699s","start":"2026-10-27T21:06:37.919195Z","end":"2026-10-27T21:06:39.310367Z","steps":["trace[1431243034] 'agreement among raft nodes before linearized reading' (duration: 1.391018695s)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:06:39.310411Z","caller":"traceutil/trace.go:171","msg":"trace[154248080] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2185; }","duration":"1.425648103s","start":"2026-10-27T21:06:37.884752Z","end":"2026-10-27T21:06:39.310401Z","steps":["trace[154248080] 'agreement among raft nodes before linearized reading' (duration: 1.42555435s)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:06:39.310425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:37.919174Z","time spent":"1.39124423s","remote":"127.0.0.1:54818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5755,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-10-27T21:06:39.310441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:37.884737Z","time spent":"1.425696225s","remote":"127.0.0.1:49492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":23,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-10-27T21:06:39.310439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.177245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:06:39.310479Z","caller":"traceutil/trace.go:171","msg":"trace[367713874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2185; }","duration":"339.257439ms","start":"2026-10-27T21:06:38.971209Z","end":"2026-10-27T21:06:39.310466Z","steps":["trace[367713874] 'agreement among raft nodes before linearized reading' (duration: 339.198137ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:06:39.310505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.832134764s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-27T21:06:39.310542Z","caller":"traceutil/trace.go:171","msg":"trace[856389111] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2185; }","duration":"1.832207086s","start":"2026-10-27T21:06:37.478324Z","end":"2026-10-27T21:06:39.310531Z","steps":["trace[856389111] 'agreement among raft nodes before linearized reading' (duration: 1.832055202s)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:06:39.310569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:37.478304Z","time spent":"1.832258537s","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-27T21:06:39.310636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439885889s","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-10-27T21:06:39.310661Z","caller":"traceutil/trace.go:171","msg":"trace[1254561715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2185; }","duration":"1.439947521s","start":"2026-10-27T21:06:37.870706Z","end":"2026-10-27T21:06:39.310653Z","steps":["trace[1254561715] 'agreement among raft nodes before linearized reading' (duration: 1.439863548s)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:06:39.310647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.822895044s","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-10-27T21:06:39.310676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:37.870686Z","time spent":"1.439986632s","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-27T21:06:39.310094Z","caller":"traceutil/trace.go:171","msg":"trace[1307055904] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"915.506162ms","start":"2026-10-27T21:06:38.394563Z","end":"2026-10-27T21:06:39.310069Z","steps":["trace[1307055904] 'process raft request' (duration: 915.306806ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:06:39.310707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.827153539s","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-10-27T21:06:39.310713Z","caller":"traceutil/trace.go:171","msg":"trace[995185648] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2185; }","duration":"1.822987027s","start":"2026-10-27T21:06:37.487705Z","end":"2026-10-27T21:06:39.310692Z","steps":["trace[995185648] 'agreement among raft nodes before linearized reading' (duration: 1.822789911s)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:06:39.310741Z","caller":"traceutil/trace.go:171","msg":"trace[41932103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2185; }","duration":"1.827223551s","start":"2026-10-27T21:06:37.483508Z","end":"2026-10-27T21:06:39.310732Z","steps":["trace[41932103] 'agreement among raft nodes before linearized reading' (duration: 1.827129808s)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:06:39.310752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:37.487693Z","time spent":"1.823050438s","remote":"127.0.0.1:49438","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-10-27T21:06:39.310767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:37.483488Z","time spent":"1.827272381s","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-27T21:06:39.310767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.2082ms","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-10-27T21:06:39.310804Z","caller":"traceutil/trace.go:171","msg":"trace[1559324654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2185; }","duration":"137.278312ms","start":"2026-10-27T21:06:39.173516Z","end":"2026-10-27T21:06:39.310794Z","steps":["trace[1559324654] 'agreement among raft nodes before linearized reading' (duration: 137.18602ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:06:39.310513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:38.971187Z","time spent":"339.31996ms","remote":"127.0.0.1:49182","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-10-27T21:06:39.310759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:38.394507Z","time spent":"916.211641ms","remote":"127.0.0.1:49438","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-10-27T21:06:39.31088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036576134s","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-10-27T21:06:39.310944Z","caller":"traceutil/trace.go:171","msg":"trace[365894063] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2185; }","duration":"1.036643336s","start":"2026-10-27T21:06:38.274288Z","end":"2026-10-27T21:06:39.310931Z","steps":["trace[365894063] 'agreement among raft nodes before linearized reading' (duration: 1.03602804s)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:06:39.310972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:38.27427Z","time spent":"1.036693677s","remote":"127.0.0.1:58514","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":"warn","ts":"2026-10-27T21:06:39.68797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.625086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T21:06:39.688233Z","caller":"traceutil/trace.go:171","msg":"trace[1587884152] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"372.606743ms","start":"2026-10-27T21:06:39.315502Z","end":"2026-10-27T21:06:39.688109Z","steps":["trace[1587884152] 'process raft request' (duration: 187.73676ms)","trace[1587884152] 'compare' (duration: 184.282077ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:06:39.688338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:39.315481Z","time spent":"372.808749ms","remote":"127.0.0.1:49438","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-10-27T21:06:39.764246Z","caller":"traceutil/trace.go:171","msg":"trace[1076943473] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"448.571012ms","start":"2026-10-27T21:06:39.315651Z","end":"2026-10-27T21:06:39.764222Z","steps":["trace[1076943473] 'process raft request' (duration: 448.424338ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:06:39.764399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:39.315636Z","time spent":"448.683115ms","remote":"127.0.0.1:49438","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-10-27T21:06:39.764521Z","caller":"traceutil/trace.go:171","msg":"trace[1649083600] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"448.734586ms","start":"2026-10-27T21:06:39.315715Z","end":"2026-10-27T21:06:39.764449Z","steps":["trace[1649083600] 'process raft request' (duration: 448.472919ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:06:39.764667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:39.315708Z","time spent":"448.913381ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:06:39.764854Z","caller":"traceutil/trace.go:171","msg":"trace[978670368] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"449.097476ms","start":"2026-10-27T21:06:39.315718Z","end":"2026-10-27T21:06:39.764816Z","steps":["trace[978670368] 'process raft request' (duration: 448.680945ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:06:39.764944Z","caller":"traceutil/trace.go:171","msg":"trace[549333334] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"448.949082ms","start":"2026-10-27T21:06:39.315985Z","end":"2026-10-27T21:06:39.764934Z","steps":["trace[549333334] 'process raft request' (duration: 448.819289ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:06:39.764973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.173303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-27T21:06:39.765068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:39.315691Z","time spent":"449.25507ms","remote":"127.0.0.1:49438","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-10-27T21:06:39.765102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:39.315966Z","time spent":"449.022914ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:06:39.764869Z","caller":"traceutil/trace.go:171","msg":"trace[1009218566] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"449.117447ms","start":"2026-10-27T21:06:39.315727Z","end":"2026-10-27T21:06:39.764844Z","steps":["trace[1009218566] 'process raft request' (duration: 448.986673ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:06:39.765092Z","caller":"traceutil/trace.go:171","msg":"trace[939294584] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2191; }","duration":"406.321156ms","start":"2026-10-27T21:06:39.358759Z","end":"2026-10-27T21:06:39.76508Z","steps":["trace[939294584] 'agreement among raft nodes before linearized reading' (duration: 406.169302ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:06:39.765253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:39.358744Z","time spent":"406.490111ms","remote":"127.0.0.1:49538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-10-27T21:06:39.764903Z","caller":"traceutil/trace.go:171","msg":"trace[2114497347] linearizableReadLoop","detail":"{readStateIndex:2275; appliedIndex:2269; }","duration":"406.08993ms","start":"2026-10-27T21:06:39.3588Z","end":"2026-10-27T21:06:39.76489Z","steps":["trace[2114497347] 'read index received' (duration: 144.454586ms)","trace[2114497347] 'applied index is now lower than readState.Index' (duration: 261.634484ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:06:39.765259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:39.315698Z","time spent":"449.521228ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T21:06:39.765408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.384678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-27T21:06:39.765447Z","caller":"traceutil/trace.go:171","msg":"trace[1010652066] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2191; }","duration":"302.46185ms","start":"2026-10-27T21:06:39.462971Z","end":"2026-10-27T21:06:39.765433Z","steps":["trace[1010652066] 'agreement among raft nodes before linearized reading' (duration: 302.389288ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:06:39.765484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:06:39.462942Z","time spent":"302.534023ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":2,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-10-27T21:06:40.146366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.260935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:06:40.146485Z","caller":"traceutil/trace.go:171","msg":"trace[646105484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2191; }","duration":"174.447849ms","start":"2026-10-27T21:06:39.972014Z","end":"2026-10-27T21:06:40.146461Z","steps":["trace[646105484] 'range keys from in-memory index tree' (duration: 174.180962ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:06:40.146471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.157318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-27T21:06:40.146657Z","caller":"traceutil/trace.go:171","msg":"trace[591670028] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2191; }","duration":"172.374513ms","start":"2026-10-27T21:06:39.97426Z","end":"2026-10-27T21:06:40.146635Z","steps":["trace[591670028] 'count revisions from in-memory index tree' (duration: 172.105006ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:06:41.264307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.109148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:06:41.264395Z","caller":"traceutil/trace.go:171","msg":"trace[1636887792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2192; }","duration":"293.218011ms","start":"2026-10-27T21:06:40.971157Z","end":"2026-10-27T21:06:41.264375Z","steps":["trace[1636887792] 'range keys from in-memory index tree' (duration: 292.998123ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:06:41.264375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.416572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:06:41.264458Z","caller":"traceutil/trace.go:171","msg":"trace[1797528346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2192; }","duration":"121.512165ms","start":"2026-10-27T21:06:41.142924Z","end":"2026-10-27T21:06:41.264437Z","steps":["trace[1797528346] 'range keys from in-memory index tree' (duration: 121.325369ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:07:29.590218Z","caller":"traceutil/trace.go:171","msg":"trace[733173991] transaction","detail":"{read_only:false; response_revision:2593; number_of_response:1; }","duration":"127.907018ms","start":"2026-10-27T21:07:29.462277Z","end":"2026-10-27T21:07:29.590184Z","steps":["trace[733173991] 'process raft request' (duration: 56.398839ms)","trace[733173991] 'compare' (duration: 71.17113ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:07:29.590256Z","caller":"traceutil/trace.go:171","msg":"trace[1401783173] transaction","detail":"{read_only:false; response_revision:2595; number_of_response:1; }","duration":"126.976682ms","start":"2026-10-27T21:07:29.46325Z","end":"2026-10-27T21:07:29.590227Z","steps":["trace[1401783173] 'process raft request' (duration: 126.90909ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:07:29.590261Z","caller":"traceutil/trace.go:171","msg":"trace[1082181603] transaction","detail":"{read_only:false; response_revision:2594; number_of_response:1; }","duration":"127.648071ms","start":"2026-10-27T21:07:29.462588Z","end":"2026-10-27T21:07:29.590236Z","steps":["trace[1082181603] 'process raft request' (duration: 127.512898ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:07:34.33108Z","caller":"traceutil/trace.go:171","msg":"trace[1666288090] linearizableReadLoop","detail":"{readStateIndex:2755; appliedIndex:2754; }","duration":"258.166659ms","start":"2026-10-27T21:07:34.072892Z","end":"2026-10-27T21:07:34.331058Z","steps":["trace[1666288090] 'read index received' (duration: 255.360313ms)","trace[1666288090] 'applied index is now lower than readState.Index' (duration: 2.805246ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:07:34.331128Z","caller":"traceutil/trace.go:171","msg":"trace[965033776] transaction","detail":"{read_only:false; response_revision:2653; number_of_response:1; }","duration":"266.612728ms","start":"2026-10-27T21:07:34.064483Z","end":"2026-10-27T21:07:34.331095Z","steps":["trace[965033776] 'process raft request' (duration: 263.790552ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:07:34.331306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.392075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-10-27T21:07:34.33134Z","caller":"traceutil/trace.go:171","msg":"trace[132335237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2653; }","duration":"258.475128ms","start":"2026-10-27T21:07:34.072854Z","end":"2026-10-27T21:07:34.331329Z","steps":["trace[132335237] 'agreement among raft nodes before linearized reading' (duration: 258.326194ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:07:34.331406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.181793ms","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-10-27T21:07:34.33144Z","caller":"traceutil/trace.go:171","msg":"trace[1319437421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2653; }","duration":"219.272275ms","start":"2026-10-27T21:07:34.112158Z","end":"2026-10-27T21:07:34.33143Z","steps":["trace[1319437421] 'agreement among raft nodes before linearized reading' (duration: 219.125241ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:07:34.33158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.021238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-10-27T21:07:34.331577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.763111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-27T21:07:34.331601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.542459ms","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-10-27T21:07:34.331633Z","caller":"traceutil/trace.go:171","msg":"trace[1622649235] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2653; }","duration":"219.10672ms","start":"2026-10-27T21:07:34.112511Z","end":"2026-10-27T21:07:34.331617Z","steps":["trace[1622649235] 'agreement among raft nodes before linearized reading' (duration: 218.961327ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:07:34.331677Z","caller":"traceutil/trace.go:171","msg":"trace[1832601173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2653; }","duration":"220.667462ms","start":"2026-10-27T21:07:34.110989Z","end":"2026-10-27T21:07:34.331657Z","steps":["trace[1832601173] 'agreement among raft nodes before linearized reading' (duration: 220.461197ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:07:34.331672Z","caller":"traceutil/trace.go:171","msg":"trace[1293883955] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2653; }","duration":"246.884844ms","start":"2026-10-27T21:07:34.084762Z","end":"2026-10-27T21:07:34.331647Z","steps":["trace[1293883955] 'agreement among raft nodes before linearized reading' (duration: 246.708199ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:08:06.747222Z","caller":"traceutil/trace.go:171","msg":"trace[1347314215] linearizableReadLoop","detail":"{readStateIndex:2958; appliedIndex:2957; }","duration":"214.187281ms","start":"2026-10-27T21:08:06.533002Z","end":"2026-10-27T21:08:06.74719Z","steps":["trace[1347314215] 'read index received' (duration: 206.551904ms)","trace[1347314215] 'applied index is now lower than readState.Index' (duration: 7.633947ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:08:06.747281Z","caller":"traceutil/trace.go:171","msg":"trace[1019998690] transaction","detail":"{read_only:false; response_revision:2850; number_of_response:1; }","duration":"244.898514ms","start":"2026-10-27T21:08:06.502364Z","end":"2026-10-27T21:08:06.747262Z","steps":["trace[1019998690] 'process raft request' (duration: 237.284667ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:08:06.747516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.487309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-27T21:08:06.747554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.411837ms","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-10-27T21:08:06.74758Z","caller":"traceutil/trace.go:171","msg":"trace[1207728621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2850; }","duration":"214.594142ms","start":"2026-10-27T21:08:06.532967Z","end":"2026-10-27T21:08:06.747561Z","steps":["trace[1207728621] 'agreement among raft nodes before linearized reading' (duration: 214.403167ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:08:06.747633Z","caller":"traceutil/trace.go:171","msg":"trace[306337106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2850; }","duration":"214.601513ms","start":"2026-10-27T21:08:06.533012Z","end":"2026-10-27T21:08:06.747614Z","steps":["trace[306337106] 'agreement among raft nodes before linearized reading' (duration: 214.381507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:08:06.747845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.723037ms","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-10-27T21:08:06.747937Z","caller":"traceutil/trace.go:171","msg":"trace[939863862] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2850; }","duration":"203.909142ms","start":"2026-10-27T21:08:06.544005Z","end":"2026-10-27T21:08:06.747914Z","steps":["trace[939863862] 'agreement among raft nodes before linearized reading' (duration: 203.420389ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:08:07.349698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.304134ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-27T21:08:07.349879Z","caller":"traceutil/trace.go:171","msg":"trace[479539437] linearizableReadLoop","detail":"{readStateIndex:2962; appliedIndex:2961; }","duration":"378.084876ms","start":"2026-10-27T21:08:06.971778Z","end":"2026-10-27T21:08:07.349863Z","steps":["trace[479539437] 'read index received' (duration: 90.508115ms)","trace[479539437] 'applied index is now lower than readState.Index' (duration: 287.575991ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:08:07.349936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.159719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:08:07.349957Z","caller":"traceutil/trace.go:171","msg":"trace[707577178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2853; }","duration":"378.2262ms","start":"2026-10-27T21:08:06.971725Z","end":"2026-10-27T21:08:07.349951Z","steps":["trace[707577178] 'agreement among raft nodes before linearized reading' (duration: 378.178279ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:08:07.34998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:08:06.971703Z","time spent":"378.272641ms","remote":"127.0.0.1:49182","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-10-27T21:08:07.350174Z","caller":"traceutil/trace.go:171","msg":"trace[16946708] transaction","detail":"{read_only:false; response_revision:2854; number_of_response:1; }","duration":"124.420686ms","start":"2026-10-27T21:08:07.225708Z","end":"2026-10-27T21:08:07.350129Z","steps":["trace[16946708] 'process raft request' (duration: 124.072177ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:10:52.297056Z","caller":"traceutil/trace.go:171","msg":"trace[355583193] transaction","detail":"{read_only:false; response_revision:3862; number_of_response:1; }","duration":"122.204739ms","start":"2026-10-27T21:10:52.174799Z","end":"2026-10-27T21:10:52.297004Z","steps":["trace[355583193] 'process raft request' (duration: 117.642996ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:10:52.297275Z","caller":"traceutil/trace.go:171","msg":"trace[1364414549] transaction","detail":"{read_only:false; response_revision:3863; number_of_response:1; }","duration":"117.943454ms","start":"2026-10-27T21:10:52.179293Z","end":"2026-10-27T21:10:52.297237Z","steps":["trace[1364414549] 'process raft request' (duration: 117.664556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:12:47.715928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.886209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8a8e001e-f7a1-4e01-9cd4-7046f25122f1\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-10-27T21:12:47.71603Z","caller":"traceutil/trace.go:171","msg":"trace[956173149] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8a8e001e-f7a1-4e01-9cd4-7046f25122f1; range_end:; response_count:1; response_revision:5047; }","duration":"106.053854ms","start":"2026-10-27T21:12:47.609954Z","end":"2026-10-27T21:12:47.716007Z","steps":["trace[956173149] 'range keys from in-memory index tree' (duration: 105.55421ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:12:47.73983Z","caller":"traceutil/trace.go:171","msg":"trace[1104478663] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"111.524412ms","start":"2026-10-27T21:12:47.628268Z","end":"2026-10-27T21:12:47.739792Z","steps":["trace[1104478663] 'process raft request' (duration: 111.13805ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:12:54.62151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.283652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T21:12:54.621707Z","caller":"traceutil/trace.go:171","msg":"trace[1803183679] linearizableReadLoop","detail":"{readStateIndex:5479; appliedIndex:5477; }","duration":"107.063741ms","start":"2026-10-27T21:12:54.514625Z","end":"2026-10-27T21:12:54.621689Z","steps":["trace[1803183679] 'read index received' (duration: 35.22µs)","trace[1803183679] 'applied index is now lower than readState.Index' (duration: 107.027511ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:12:54.621846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.214296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-27T21:12:54.621874Z","caller":"traceutil/trace.go:171","msg":"trace[1556619416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5279; }","duration":"107.266606ms","start":"2026-10-27T21:12:54.514597Z","end":"2026-10-27T21:12:54.621864Z","steps":["trace[1556619416] 'agreement among raft nodes before linearized reading' (duration: 107.141773ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:12:54.621845Z","caller":"traceutil/trace.go:171","msg":"trace[646723153] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"259.157682ms","start":"2026-10-27T21:12:54.362654Z","end":"2026-10-27T21:12:54.621812Z","steps":["trace[646723153] 'process raft request' (duration: 125.489699ms)","trace[646723153] 'compare' (duration: 133.18962ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:12:54.62208Z","caller":"traceutil/trace.go:171","msg":"trace[1536365060] transaction","detail":"{read_only:false; response_revision:5279; number_of_response:1; }","duration":"178.123906ms","start":"2026-10-27T21:12:54.443946Z","end":"2026-10-27T21:12:54.622069Z","steps":["trace[1536365060] 'process raft request' (duration: 177.689144ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:12:58.809284Z","caller":"traceutil/trace.go:171","msg":"trace[1935487606] transaction","detail":"{read_only:false; response_revision:5369; number_of_response:1; }","duration":"124.530715ms","start":"2026-10-27T21:12:58.684719Z","end":"2026-10-27T21:12:58.80925Z","steps":["trace[1935487606] 'process raft request' (duration: 114.4099ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:12:58.809546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.734917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-27T21:12:58.809621Z","caller":"traceutil/trace.go:171","msg":"trace[788573537] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5370; }","duration":"108.836229ms","start":"2026-10-27T21:12:58.700768Z","end":"2026-10-27T21:12:58.809604Z","steps":["trace[788573537] 'agreement among raft nodes before linearized reading' (duration: 108.594533ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:12:58.809941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.826131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-27T21:12:58.810121Z","caller":"traceutil/trace.go:171","msg":"trace[1769010129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5370; }","duration":"112.036286ms","start":"2026-10-27T21:12:58.698061Z","end":"2026-10-27T21:12:58.810097Z","steps":["trace[1769010129] 'agreement among raft nodes before linearized reading' (duration: 111.740738ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:13:10.101607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.079321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-86cf4f7cb6-n4qs6\" ","response":"range_response_count:1 size:8111"} {"level":"info","ts":"2026-10-27T21:13:10.101724Z","caller":"traceutil/trace.go:171","msg":"trace[1415468998] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-86cf4f7cb6-n4qs6; range_end:; response_count:1; response_revision:5551; }","duration":"238.235105ms","start":"2026-10-27T21:13:09.863461Z","end":"2026-10-27T21:13:10.101697Z","steps":["trace[1415468998] 'range keys from in-memory index tree' (duration: 237.898347ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:13:10.102758Z","caller":"traceutil/trace.go:171","msg":"trace[1805914838] linearizableReadLoop","detail":"{readStateIndex:5762; appliedIndex:5761; }","duration":"132.391867ms","start":"2026-10-27T21:13:09.970343Z","end":"2026-10-27T21:13:10.102735Z","steps":["trace[1805914838] 'read index received' (duration: 129.740915ms)","trace[1805914838] 'applied index is now lower than readState.Index' (duration: 2.649682ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:13:10.102928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.584362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:13:10.10298Z","caller":"traceutil/trace.go:171","msg":"trace[874717899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5552; }","duration":"132.674564ms","start":"2026-10-27T21:13:09.970291Z","end":"2026-10-27T21:13:10.102966Z","steps":["trace[874717899] 'agreement among raft nodes before linearized reading' (duration: 132.529861ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:13:10.10312Z","caller":"traceutil/trace.go:171","msg":"trace[747336392] transaction","detail":"{read_only:false; response_revision:5552; number_of_response:1; }","duration":"218.49016ms","start":"2026-10-27T21:13:09.884586Z","end":"2026-10-27T21:13:10.103077Z","steps":["trace[747336392] 'process raft request' (duration: 215.556511ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:13:18.083877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.129265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:13:18.083973Z","caller":"traceutil/trace.go:171","msg":"trace[1805477966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5692; }","duration":"113.28257ms","start":"2026-10-27T21:13:17.970665Z","end":"2026-10-27T21:13:18.083947Z","steps":["trace[1805477966] 'range keys from in-memory index tree' (duration: 112.994692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:13:18.084283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.719173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2cf4b494-05eb-42e8-8ffe-bda411b901f8\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-27T21:13:18.084412Z","caller":"traceutil/trace.go:171","msg":"trace[1387669264] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2cf4b494-05eb-42e8-8ffe-bda411b901f8; range_end:; response_count:1; response_revision:5692; }","duration":"109.840786ms","start":"2026-10-27T21:13:17.974504Z","end":"2026-10-27T21:13:18.084344Z","steps":["trace[1387669264] 'range keys from in-memory index tree' (duration: 109.528527ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:13:18.215915Z","caller":"traceutil/trace.go:171","msg":"trace[159794186] transaction","detail":"{read_only:false; response_revision:5693; number_of_response:1; }","duration":"123.209799ms","start":"2026-10-27T21:13:18.092678Z","end":"2026-10-27T21:13:18.215888Z","steps":["trace[159794186] 'process raft request' (duration: 123.014193ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:13:18.411713Z","caller":"traceutil/trace.go:171","msg":"trace[451366628] linearizableReadLoop","detail":"{readStateIndex:5906; appliedIndex:5905; }","duration":"155.7446ms","start":"2026-10-27T21:13:18.25595Z","end":"2026-10-27T21:13:18.411695Z","steps":["trace[451366628] 'read index received' (duration: 155.555865ms)","trace[451366628] 'applied index is now lower than readState.Index' (duration: 187.985µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:13:18.411827Z","caller":"traceutil/trace.go:171","msg":"trace[345161339] transaction","detail":"{read_only:false; response_revision:5694; number_of_response:1; }","duration":"190.591754ms","start":"2026-10-27T21:13:18.221204Z","end":"2026-10-27T21:13:18.411795Z","steps":["trace[345161339] 'process raft request' (duration: 190.362998ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:13:18.411891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.930204ms","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-10-27T21:13:18.411924Z","caller":"traceutil/trace.go:171","msg":"trace[1863367192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5694; }","duration":"156.013266ms","start":"2026-10-27T21:13:18.255901Z","end":"2026-10-27T21:13:18.411914Z","steps":["trace[1863367192] 'agreement among raft nodes before linearized reading' (duration: 155.865322ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:13:18.526421Z","caller":"traceutil/trace.go:171","msg":"trace[1587997651] linearizableReadLoop","detail":"{readStateIndex:5907; appliedIndex:5906; }","duration":"105.946721ms","start":"2026-10-27T21:13:18.420445Z","end":"2026-10-27T21:13:18.526391Z","steps":["trace[1587997651] 'read index received' (duration: 85.301472ms)","trace[1587997651] 'applied index is now lower than readState.Index' (duration: 20.643968ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:13:18.526499Z","caller":"traceutil/trace.go:171","msg":"trace[497392055] transaction","detail":"{read_only:false; response_revision:5695; number_of_response:1; }","duration":"111.83196ms","start":"2026-10-27T21:13:18.414634Z","end":"2026-10-27T21:13:18.526466Z","steps":["trace[497392055] 'process raft request' (duration: 91.178822ms)","trace[497392055] 'compare' (duration: 20.440323ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:13:18.526673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.205138ms","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-10-27T21:13:18.526753Z","caller":"traceutil/trace.go:171","msg":"trace[1496162460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5695; }","duration":"106.323121ms","start":"2026-10-27T21:13:18.420412Z","end":"2026-10-27T21:13:18.526736Z","steps":["trace[1496162460] 'agreement among raft nodes before linearized reading' (duration: 106.085004ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:13:28.578253Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2970} {"level":"info","ts":"2026-10-27T21:13:28.664593Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2970,"took":"84.388236ms","hash":2536694595,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":32325632,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-27T21:13:28.664677Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2536694595,"revision":2970,"compact-revision":-1} {"level":"warn","ts":"2026-10-27T21:13:29.259682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.712244ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T21:13:29.259792Z","caller":"traceutil/trace.go:171","msg":"trace[531081588] transaction","detail":"{read_only:false; response_revision:5869; number_of_response:1; }","duration":"194.890862ms","start":"2026-10-27T21:13:29.064878Z","end":"2026-10-27T21:13:29.259769Z","steps":["trace[531081588] 'process raft request' (duration: 91.998273ms)","trace[531081588] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; req_size:531; } (duration: 102.5519ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:13:29.297458Z","caller":"traceutil/trace.go:171","msg":"trace[407395323] linearizableReadLoop","detail":"{readStateIndex:6089; appliedIndex:6088; }","duration":"133.428705ms","start":"2026-10-27T21:13:29.163997Z","end":"2026-10-27T21:13:29.297425Z","steps":["trace[407395323] 'read index received' (duration: 133.214979ms)","trace[407395323] 'applied index is now lower than readState.Index' (duration: 212.566µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:13:29.297583Z","caller":"traceutil/trace.go:171","msg":"trace[2086294769] transaction","detail":"{read_only:false; response_revision:5870; number_of_response:1; }","duration":"188.676912ms","start":"2026-10-27T21:13:29.108887Z","end":"2026-10-27T21:13:29.297564Z","steps":["trace[2086294769] 'process raft request' (duration: 188.394845ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:13:29.297764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.753013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10689"} {"level":"info","ts":"2026-10-27T21:13:29.297821Z","caller":"traceutil/trace.go:171","msg":"trace[231787811] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5870; }","duration":"133.862776ms","start":"2026-10-27T21:13:29.163942Z","end":"2026-10-27T21:13:29.297805Z","steps":["trace[231787811] 'agreement among raft nodes before linearized reading' (duration: 133.675661ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:13:29.427872Z","caller":"traceutil/trace.go:171","msg":"trace[1455175105] transaction","detail":"{read_only:false; response_revision:5871; number_of_response:1; }","duration":"118.225043ms","start":"2026-10-27T21:13:29.309625Z","end":"2026-10-27T21:13:29.42785Z","steps":["trace[1455175105] 'process raft request' (duration: 118.07976ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:13:29.602903Z","caller":"traceutil/trace.go:171","msg":"trace[813812934] transaction","detail":"{read_only:false; response_revision:5873; number_of_response:1; }","duration":"167.907349ms","start":"2026-10-27T21:13:29.434969Z","end":"2026-10-27T21:13:29.602877Z","steps":["trace[813812934] 'process raft request' (duration: 147.839046ms)","trace[813812934] 'compare' (duration: 19.917489ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:13:29.603388Z","caller":"traceutil/trace.go:171","msg":"trace[664901095] linearizableReadLoop","detail":"{readStateIndex:6094; appliedIndex:6090; }","duration":"164.905127ms","start":"2026-10-27T21:13:29.438456Z","end":"2026-10-27T21:13:29.603361Z","steps":["trace[664901095] 'read index received' (duration: 49.789808ms)","trace[664901095] 'applied index is now lower than readState.Index' (duration: 115.113589ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:13:29.603462Z","caller":"traceutil/trace.go:171","msg":"trace[988768666] transaction","detail":"{read_only:false; response_revision:5874; number_of_response:1; }","duration":"168.316551ms","start":"2026-10-27T21:13:29.435128Z","end":"2026-10-27T21:13:29.603445Z","steps":["trace[988768666] 'process raft request' (duration: 167.707854ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:13:29.603466Z","caller":"traceutil/trace.go:171","msg":"trace[917972172] transaction","detail":"{read_only:false; response_revision:5875; number_of_response:1; }","duration":"168.164567ms","start":"2026-10-27T21:13:29.43528Z","end":"2026-10-27T21:13:29.603445Z","steps":["trace[917972172] 'process raft request' (duration: 167.966601ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:13:29.603613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.136713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v4wmd\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-27T21:13:29.603668Z","caller":"traceutil/trace.go:171","msg":"trace[263252154] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v4wmd; range_end:; response_count:1; response_revision:5875; }","duration":"165.240747ms","start":"2026-10-27T21:13:29.438412Z","end":"2026-10-27T21:13:29.603653Z","steps":["trace[263252154] 'agreement among raft nodes before linearized reading' (duration: 165.058612ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:14:09.081931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.912058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:14:09.082067Z","caller":"traceutil/trace.go:171","msg":"trace[1968084223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6227; }","duration":"107.106683ms","start":"2026-10-27T21:14:08.974923Z","end":"2026-10-27T21:14:09.08203Z","steps":["trace[1968084223] 'range keys from in-memory index tree' (duration: 106.789034ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:14:09.082565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.993807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4797"} {"level":"info","ts":"2026-10-27T21:14:09.082605Z","caller":"traceutil/trace.go:171","msg":"trace[1150377024] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:6227; }","duration":"115.093629ms","start":"2026-10-27T21:14:08.967499Z","end":"2026-10-27T21:14:09.082592Z","steps":["trace[1150377024] 'range keys from in-memory index tree' (duration: 114.805281ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:14:09.398992Z","caller":"traceutil/trace.go:171","msg":"trace[1728104911] transaction","detail":"{read_only:false; response_revision:6231; number_of_response:1; }","duration":"109.051146ms","start":"2026-10-27T21:14:09.289918Z","end":"2026-10-27T21:14:09.398969Z","steps":["trace[1728104911] 'process raft request' (duration: 108.858961ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:14:09.399464Z","caller":"traceutil/trace.go:171","msg":"trace[1536665677] transaction","detail":"{read_only:false; response_revision:6232; number_of_response:1; }","duration":"105.108498ms","start":"2026-10-27T21:14:09.294316Z","end":"2026-10-27T21:14:09.399425Z","steps":["trace[1536665677] 'process raft request' (duration: 104.612205ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:18:28.585997Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5858} {"level":"info","ts":"2026-10-27T21:18:28.675844Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5858,"took":"88.10306ms","hash":1620061494,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":26329088,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-27T21:18:28.675903Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1620061494,"revision":5858,"compact-revision":2970} {"level":"info","ts":"2026-10-27T21:19:21.179103Z","caller":"traceutil/trace.go:171","msg":"trace[545727789] linearizableReadLoop","detail":"{readStateIndex:8837; appliedIndex:8836; }","duration":"207.913508ms","start":"2026-10-27T21:19:20.971167Z","end":"2026-10-27T21:19:21.179081Z","steps":["trace[545727789] 'read index received' (duration: 207.738153ms)","trace[545727789] 'applied index is now lower than readState.Index' (duration: 174.225µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:19:21.179222Z","caller":"traceutil/trace.go:171","msg":"trace[1676930104] transaction","detail":"{read_only:false; response_revision:8535; number_of_response:1; }","duration":"270.439576ms","start":"2026-10-27T21:19:20.908746Z","end":"2026-10-27T21:19:21.179186Z","steps":["trace[1676930104] 'process raft request' (duration: 270.18201ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:19:21.179264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.079912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:19:21.179307Z","caller":"traceutil/trace.go:171","msg":"trace[567498359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8535; }","duration":"208.136503ms","start":"2026-10-27T21:19:20.971159Z","end":"2026-10-27T21:19:21.179296Z","steps":["trace[567498359] 'agreement among raft nodes before linearized reading' (duration: 208.057161ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:19:32.86302Z","caller":"traceutil/trace.go:171","msg":"trace[1913692904] transaction","detail":"{read_only:false; response_revision:8596; number_of_response:1; }","duration":"326.728558ms","start":"2026-10-27T21:19:32.536265Z","end":"2026-10-27T21:19:32.862994Z","steps":["trace[1913692904] 'process raft request' (duration: 326.576484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:19:32.863194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:19:32.53624Z","time spent":"326.877441ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:19:32.972527Z","caller":"traceutil/trace.go:171","msg":"trace[1408463529] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"436.208696ms","start":"2026-10-27T21:19:32.536291Z","end":"2026-10-27T21:19:32.9725Z","steps":["trace[1408463529] 'process raft request' (duration: 404.574941ms)","trace[1408463529] 'compare' (duration: 31.437279ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:19:32.97273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:19:32.536264Z","time spent":"436.35218ms","remote":"127.0.0.1:49438","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-10-27T21:19:32.972817Z","caller":"traceutil/trace.go:171","msg":"trace[1834653459] transaction","detail":"{read_only:false; response_revision:8598; number_of_response:1; }","duration":"360.167852ms","start":"2026-10-27T21:19:32.612608Z","end":"2026-10-27T21:19:32.972776Z","steps":["trace[1834653459] 'process raft request' (duration: 359.834743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:19:32.973025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:19:32.612594Z","time spent":"360.326186ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:19:32.973055Z","caller":"traceutil/trace.go:171","msg":"trace[463238530] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"360.347107ms","start":"2026-10-27T21:19:32.61268Z","end":"2026-10-27T21:19:32.973028Z","steps":["trace[463238530] 'process raft request' (duration: 360.006038ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:19:32.973124Z","caller":"traceutil/trace.go:171","msg":"trace[450771721] transaction","detail":"{read_only:false; response_revision:8600; number_of_response:1; }","duration":"288.840584ms","start":"2026-10-27T21:19:32.684129Z","end":"2026-10-27T21:19:32.972969Z","steps":["trace[450771721] 'process raft request' (duration: 288.668ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:19:32.973224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:19:32.612672Z","time spent":"360.497ms","remote":"127.0.0.1:49438","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-10-27T21:19:34.575505Z","caller":"traceutil/trace.go:171","msg":"trace[777270163] transaction","detail":"{read_only:false; response_revision:8606; number_of_response:1; }","duration":"119.021016ms","start":"2026-10-27T21:19:34.456452Z","end":"2026-10-27T21:19:34.575473Z","steps":["trace[777270163] 'process raft request' (duration: 105.631344ms)","trace[777270163] 'compare' (duration: 13.180606ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:19:59.345661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.248254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-27T21:19:59.345774Z","caller":"traceutil/trace.go:171","msg":"trace[2031839755] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8788; }","duration":"118.413059ms","start":"2026-10-27T21:19:59.227333Z","end":"2026-10-27T21:19:59.345746Z","steps":["trace[2031839755] 'range keys from in-memory index tree' (duration: 118.050439ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:20:02.449605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.853487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-10-27T21:20:02.449773Z","caller":"traceutil/trace.go:171","msg":"trace[1581421016] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:8806; }","duration":"526.070823ms","start":"2026-10-27T21:20:01.923681Z","end":"2026-10-27T21:20:02.449752Z","steps":["trace[1581421016] 'range keys from in-memory index tree' (duration: 525.676882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:20:02.450156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:20:01.923659Z","time spent":"526.479284ms","remote":"127.0.0.1:49334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-10-27T21:20:02.450886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.713778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v4wmd\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-27T21:20:02.451015Z","caller":"traceutil/trace.go:171","msg":"trace[1839608106] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v4wmd; range_end:; response_count:1; response_revision:8806; }","duration":"500.903134ms","start":"2026-10-27T21:20:01.950096Z","end":"2026-10-27T21:20:02.450999Z","steps":["trace[1839608106] 'range keys from in-memory index tree' (duration: 500.541094ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:20:02.45154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:20:01.949998Z","time spent":"501.525701ms","remote":"127.0.0.1:49344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-v4wmd\" "} {"level":"warn","ts":"2026-10-27T21:20:02.451894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.666049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:20:02.451927Z","caller":"traceutil/trace.go:171","msg":"trace[438724351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8806; }","duration":"479.754431ms","start":"2026-10-27T21:20:01.972161Z","end":"2026-10-27T21:20:02.451916Z","steps":["trace[438724351] 'range keys from in-memory index tree' (duration: 479.548776ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:20:02.451951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:20:01.97213Z","time spent":"479.814373ms","remote":"127.0.0.1:49168","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-10-27T21:20:02.452763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.871746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-65b4866fcf-5vntl.18e27fa5e39add12\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-10-27T21:20:02.452859Z","caller":"traceutil/trace.go:171","msg":"trace[699864722] range","detail":"{range_begin:/registry/events/openstack/barbican-api-65b4866fcf-5vntl.18e27fa5e39add12; range_end:; response_count:1; response_revision:8806; }","duration":"339.02365ms","start":"2026-10-27T21:20:02.113822Z","end":"2026-10-27T21:20:02.452845Z","steps":["trace[699864722] 'range keys from in-memory index tree' (duration: 338.736052ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:20:02.452928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:20:02.11379Z","time spent":"339.125823ms","remote":"127.0.0.1:49248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1157,"request content":"key:\"/registry/events/openstack/barbican-api-65b4866fcf-5vntl.18e27fa5e39add12\" "} {"level":"warn","ts":"2026-10-27T21:20:03.65634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.116383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T21:20:03.656463Z","caller":"traceutil/trace.go:171","msg":"trace[1441884578] linearizableReadLoop","detail":"{readStateIndex:9130; appliedIndex:9129; }","duration":"163.497677ms","start":"2026-10-27T21:20:03.492947Z","end":"2026-10-27T21:20:03.656445Z","steps":["trace[1441884578] 'read index received' (duration: 12.058206ms)","trace[1441884578] 'applied index is now lower than readState.Index' (duration: 151.437801ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:20:03.656576Z","caller":"traceutil/trace.go:171","msg":"trace[885903500] transaction","detail":"{read_only:false; response_revision:8816; number_of_response:1; }","duration":"164.660519ms","start":"2026-10-27T21:20:03.491871Z","end":"2026-10-27T21:20:03.656531Z","steps":["trace[885903500] 'process raft request' (duration: 13.254139ms)","trace[885903500] 'compare' (duration: 150.964149ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:20:03.656687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.736483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-27T21:20:03.656716Z","caller":"traceutil/trace.go:171","msg":"trace[428490205] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8816; }","duration":"163.793425ms","start":"2026-10-27T21:20:03.492913Z","end":"2026-10-27T21:20:03.656707Z","steps":["trace[428490205] 'agreement among raft nodes before linearized reading' (duration: 163.644081ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:20:03.656736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.208029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:20:03.656794Z","caller":"traceutil/trace.go:171","msg":"trace[1161749128] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:8816; }","duration":"106.329283ms","start":"2026-10-27T21:20:03.55045Z","end":"2026-10-27T21:20:03.656779Z","steps":["trace[1161749128] 'agreement among raft nodes before linearized reading' (duration: 106.21512ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:20:09.306561Z","caller":"traceutil/trace.go:171","msg":"trace[1234085499] transaction","detail":"{read_only:false; response_revision:8845; number_of_response:1; }","duration":"121.002569ms","start":"2026-10-27T21:20:09.185526Z","end":"2026-10-27T21:20:09.306528Z","steps":["trace[1234085499] 'process raft request' (duration: 67.592126ms)","trace[1234085499] 'compare' (duration: 53.28398ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:20:10.061835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.09405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T21:20:10.061983Z","caller":"traceutil/trace.go:171","msg":"trace[1100989220] transaction","detail":"{read_only:false; response_revision:8848; number_of_response:1; }","duration":"343.769389ms","start":"2026-10-27T21:20:09.718193Z","end":"2026-10-27T21:20:10.061962Z","steps":["trace[1100989220] 'process raft request' (duration: 161.426882ms)","trace[1100989220] 'compare' (duration: 181.784681ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:20:10.062102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:20:09.718176Z","time spent":"343.862301ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:20:16.224105Z","caller":"traceutil/trace.go:171","msg":"trace[991907570] linearizableReadLoop","detail":"{readStateIndex:9241; appliedIndex:9240; }","duration":"109.098928ms","start":"2026-10-27T21:20:16.114989Z","end":"2026-10-27T21:20:16.224088Z","steps":["trace[991907570] 'read index received' (duration: 108.956034ms)","trace[991907570] 'applied index is now lower than readState.Index' (duration: 142.254µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:20:16.224156Z","caller":"traceutil/trace.go:171","msg":"trace[1358524500] transaction","detail":"{read_only:false; response_revision:8923; number_of_response:1; }","duration":"131.3926ms","start":"2026-10-27T21:20:16.092751Z","end":"2026-10-27T21:20:16.224144Z","steps":["trace[1358524500] 'process raft request' (duration: 131.203035ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:20:16.224269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.255672ms","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-10-27T21:20:16.224326Z","caller":"traceutil/trace.go:171","msg":"trace[1918505262] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:8923; }","duration":"109.364435ms","start":"2026-10-27T21:20:16.114946Z","end":"2026-10-27T21:20:16.224311Z","steps":["trace[1918505262] 'agreement among raft nodes before linearized reading' (duration: 109.20898ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:20:16.224994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.152321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"info","ts":"2026-10-27T21:20:16.225026Z","caller":"traceutil/trace.go:171","msg":"trace[346857274] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8923; }","duration":"105.230813ms","start":"2026-10-27T21:20:16.119789Z","end":"2026-10-27T21:20:16.22502Z","steps":["trace[346857274] 'agreement among raft nodes before linearized reading' (duration: 104.4062ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:20:16.483164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.497113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112094"} {"level":"info","ts":"2026-10-27T21:20:16.483239Z","caller":"traceutil/trace.go:171","msg":"trace[360498081] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:8923; }","duration":"171.619006ms","start":"2026-10-27T21:20:16.311601Z","end":"2026-10-27T21:20:16.48322Z","steps":["trace[360498081] 'range keys from in-memory index tree' (duration: 171.148254ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:20:16.605483Z","caller":"traceutil/trace.go:171","msg":"trace[1950143633] transaction","detail":"{read_only:false; response_revision:8924; number_of_response:1; }","duration":"106.081435ms","start":"2026-10-27T21:20:16.499376Z","end":"2026-10-27T21:20:16.605457Z","steps":["trace[1950143633] 'process raft request' (duration: 105.86742ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:20:29.37985Z","caller":"traceutil/trace.go:171","msg":"trace[494714739] linearizableReadLoop","detail":"{readStateIndex:9345; appliedIndex:9344; }","duration":"140.418104ms","start":"2026-10-27T21:20:29.239405Z","end":"2026-10-27T21:20:29.379823Z","steps":["trace[494714739] 'read index received' (duration: 140.29757ms)","trace[494714739] 'applied index is now lower than readState.Index' (duration: 119.113µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:20:29.379982Z","caller":"traceutil/trace.go:171","msg":"trace[699273327] transaction","detail":"{read_only:false; response_revision:9023; number_of_response:1; }","duration":"153.655881ms","start":"2026-10-27T21:20:29.226291Z","end":"2026-10-27T21:20:29.379947Z","steps":["trace[699273327] 'process raft request' (duration: 153.376543ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:20:29.380115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.690732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-27T21:20:29.380155Z","caller":"traceutil/trace.go:171","msg":"trace[1662968537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9023; }","duration":"140.778614ms","start":"2026-10-27T21:20:29.239364Z","end":"2026-10-27T21:20:29.380142Z","steps":["trace[1662968537] 'agreement among raft nodes before linearized reading' (duration: 140.603078ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:20:29.669367Z","caller":"traceutil/trace.go:171","msg":"trace[856906726] transaction","detail":"{read_only:false; response_revision:9024; number_of_response:1; }","duration":"416.595636ms","start":"2026-10-27T21:20:29.252736Z","end":"2026-10-27T21:20:29.669331Z","steps":["trace[856906726] 'process raft request' (duration: 406.738259ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:20:29.669446Z","caller":"traceutil/trace.go:171","msg":"trace[30356876] transaction","detail":"{read_only:false; response_revision:9025; number_of_response:1; }","duration":"284.288891ms","start":"2026-10-27T21:20:29.385132Z","end":"2026-10-27T21:20:29.669421Z","steps":["trace[30356876] 'process raft request' (duration: 284.169358ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:20:29.66947Z","caller":"traceutil/trace.go:171","msg":"trace[1334605551] linearizableReadLoop","detail":"{readStateIndex:9346; appliedIndex:9345; }","duration":"289.479991ms","start":"2026-10-27T21:20:29.379941Z","end":"2026-10-27T21:20:29.669421Z","steps":["trace[1334605551] 'read index received' (duration: 279.545282ms)","trace[1334605551] 'applied index is now lower than readState.Index' (duration: 9.928339ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:20:29.669522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:20:29.25271Z","time spent":"416.73263ms","remote":"127.0.0.1:49438","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-10-27T21:20:29.669785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.852555ms","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-10-27T21:20:29.66983Z","caller":"traceutil/trace.go:171","msg":"trace[704211684] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:9025; }","duration":"301.952008ms","start":"2026-10-27T21:20:29.367864Z","end":"2026-10-27T21:20:29.669816Z","steps":["trace[704211684] 'agreement among raft nodes before linearized reading' (duration: 301.728772ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:20:29.669866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:20:29.367842Z","time spent":"302.01694ms","remote":"127.0.0.1:49438","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-10-27T21:20:29.669887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.989692ms","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-10-27T21:20:29.669962Z","caller":"traceutil/trace.go:171","msg":"trace[562993668] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9025; }","duration":"271.128015ms","start":"2026-10-27T21:20:29.398814Z","end":"2026-10-27T21:20:29.669942Z","steps":["trace[562993668] 'agreement among raft nodes before linearized reading' (duration: 270.905979ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:20:31.564684Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024a1.wal"} {"level":"warn","ts":"2026-10-27T21:21:36.441759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.257159ms","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-10-27T21:21:36.441852Z","caller":"traceutil/trace.go:171","msg":"trace[1659144528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9627; }","duration":"264.406463ms","start":"2026-10-27T21:21:36.177426Z","end":"2026-10-27T21:21:36.441832Z","steps":["trace[1659144528] 'range keys from in-memory index tree' (duration: 264.039623ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:21:36.787736Z","caller":"traceutil/trace.go:171","msg":"trace[1586866539] linearizableReadLoop","detail":"{readStateIndex:9968; appliedIndex:9967; }","duration":"209.275624ms","start":"2026-10-27T21:21:36.578435Z","end":"2026-10-27T21:21:36.787711Z","steps":["trace[1586866539] 'read index received' (duration: 209.055388ms)","trace[1586866539] 'applied index is now lower than readState.Index' (duration: 219.366µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:21:36.7878Z","caller":"traceutil/trace.go:171","msg":"trace[481643698] transaction","detail":"{read_only:false; response_revision:9629; number_of_response:1; }","duration":"232.478656ms","start":"2026-10-27T21:21:36.555287Z","end":"2026-10-27T21:21:36.787765Z","steps":["trace[481643698] 'process raft request' (duration: 232.262841ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:36.78789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.436878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:21:36.787936Z","caller":"traceutil/trace.go:171","msg":"trace[918574745] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:9629; }","duration":"209.537291ms","start":"2026-10-27T21:21:36.578385Z","end":"2026-10-27T21:21:36.787922Z","steps":["trace[918574745] 'agreement among raft nodes before linearized reading' (duration: 209.448138ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:36.788036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.428155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:21:36.788207Z","caller":"traceutil/trace.go:171","msg":"trace[1915029172] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:9629; }","duration":"103.572109ms","start":"2026-10-27T21:21:36.684579Z","end":"2026-10-27T21:21:36.788151Z","steps":["trace[1915029172] 'agreement among raft nodes before linearized reading' (duration: 103.346483ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:21:39.511735Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"371c7c2e52231793","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T21:21:39.51921Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-27T21:21:39.519305Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-27T21:23:28.592673Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8046} {"level":"info","ts":"2026-10-27T21:23:28.665581Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8046,"took":"70.207733ms","hash":2482125660,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":29118464,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-27T21:23:28.665658Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2482125660,"revision":8046,"compact-revision":5858} {"level":"info","ts":"2026-10-27T21:25:17.329582Z","caller":"traceutil/trace.go:171","msg":"trace[299308898] transaction","detail":"{read_only:false; response_revision:11555; number_of_response:1; }","duration":"120.307267ms","start":"2026-10-27T21:25:17.209252Z","end":"2026-10-27T21:25:17.32956Z","steps":["trace[299308898] 'process raft request' (duration: 120.141133ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:25:17.388057Z","caller":"traceutil/trace.go:171","msg":"trace[64003498] transaction","detail":"{read_only:false; response_revision:11556; number_of_response:1; }","duration":"177.04491ms","start":"2026-10-27T21:25:17.210994Z","end":"2026-10-27T21:25:17.388039Z","steps":["trace[64003498] 'process raft request' (duration: 176.895406ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:28:28.600272Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10526} {"level":"info","ts":"2026-10-27T21:28:28.690789Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10526,"took":"88.123964ms","hash":2218832286,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":28102656,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-27T21:28:28.690921Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2218832286,"revision":10526,"compact-revision":8046} {"level":"warn","ts":"2026-10-27T21:31:12.129892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.678601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:31:12.1301Z","caller":"traceutil/trace.go:171","msg":"trace[90945747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13968; }","duration":"157.991359ms","start":"2026-10-27T21:31:11.972077Z","end":"2026-10-27T21:31:12.130068Z","steps":["trace[90945747] 'range keys from in-memory index tree' (duration: 157.579838ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:31:12.132777Z","caller":"traceutil/trace.go:171","msg":"trace[743868308] linearizableReadLoop","detail":"{readStateIndex:14448; appliedIndex:14447; }","duration":"154.274708ms","start":"2026-10-27T21:31:11.978456Z","end":"2026-10-27T21:31:12.132731Z","steps":["trace[743868308] 'read index received' (duration: 154.095783ms)","trace[743868308] 'applied index is now lower than readState.Index' (duration: 178.095µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:31:12.1328Z","caller":"traceutil/trace.go:171","msg":"trace[1223662662] transaction","detail":"{read_only:false; response_revision:13969; number_of_response:1; }","duration":"154.36719ms","start":"2026-10-27T21:31:11.978405Z","end":"2026-10-27T21:31:12.132773Z","steps":["trace[1223662662] 'process raft request' (duration: 154.193095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:31:12.133342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.877204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-27T21:31:12.133402Z","caller":"traceutil/trace.go:171","msg":"trace[869402537] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13969; }","duration":"154.977226ms","start":"2026-10-27T21:31:11.978408Z","end":"2026-10-27T21:31:12.133385Z","steps":["trace[869402537] 'agreement among raft nodes before linearized reading' (duration: 154.470253ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:31:33.788946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.161542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T21:31:33.78916Z","caller":"traceutil/trace.go:171","msg":"trace[796019372] transaction","detail":"{read_only:false; response_revision:14088; number_of_response:1; }","duration":"245.64944ms","start":"2026-10-27T21:31:33.543482Z","end":"2026-10-27T21:31:33.789131Z","steps":["trace[796019372] 'process raft request' (duration: 96.20152ms)","trace[796019372] 'compare' (duration: 149.009688ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:31:34.182516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.833897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:31:34.182606Z","caller":"traceutil/trace.go:171","msg":"trace[589795190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14090; }","duration":"211.161676ms","start":"2026-10-27T21:31:33.971426Z","end":"2026-10-27T21:31:34.182587Z","steps":["trace[589795190] 'range keys from in-memory index tree' (duration: 210.688333ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:31:50.83392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.702358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T21:31:50.834085Z","caller":"traceutil/trace.go:171","msg":"trace[1111033594] linearizableReadLoop","detail":"{readStateIndex:14676; appliedIndex:14675; }","duration":"343.705141ms","start":"2026-10-27T21:31:50.490354Z","end":"2026-10-27T21:31:50.834059Z","steps":["trace[1111033594] 'read index received' (duration: 46.251µs)","trace[1111033594] 'applied index is now lower than readState.Index' (duration: 343.65678ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:31:50.834275Z","caller":"traceutil/trace.go:171","msg":"trace[1490403998] transaction","detail":"{read_only:false; response_revision:14189; number_of_response:1; }","duration":"482.216057ms","start":"2026-10-27T21:31:50.352038Z","end":"2026-10-27T21:31:50.834254Z","steps":["trace[1490403998] 'process raft request' (duration: 118.043737ms)","trace[1490403998] 'compare' (duration: 363.416469ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:31:50.83443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:31:50.352016Z","time spent":"482.34947ms","remote":"127.0.0.1:49438","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-10-27T21:31:50.83441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.037181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v4wmd\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-27T21:31:50.835001Z","caller":"traceutil/trace.go:171","msg":"trace[1087402537] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v4wmd; range_end:; response_count:1; response_revision:14189; }","duration":"344.694878ms","start":"2026-10-27T21:31:50.490289Z","end":"2026-10-27T21:31:50.834984Z","steps":["trace[1087402537] 'agreement among raft nodes before linearized reading' (duration: 343.912987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:31:50.835107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:31:50.490264Z","time spent":"344.826172ms","remote":"127.0.0.1:49344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-v4wmd\" "} {"level":"warn","ts":"2026-10-27T21:31:50.834302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.225104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:31:50.8353Z","caller":"traceutil/trace.go:171","msg":"trace[1542127571] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:14189; }","duration":"194.219251ms","start":"2026-10-27T21:31:50.641021Z","end":"2026-10-27T21:31:50.83524Z","steps":["trace[1542127571] 'agreement among raft nodes before linearized reading' (duration: 193.190663ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:31:52.459572Z","caller":"traceutil/trace.go:171","msg":"trace[1097990610] linearizableReadLoop","detail":"{readStateIndex:14695; appliedIndex:14693; }","duration":"102.601483ms","start":"2026-10-27T21:31:52.356944Z","end":"2026-10-27T21:31:52.459546Z","steps":["trace[1097990610] 'read index received' (duration: 49.302513ms)","trace[1097990610] 'applied index is now lower than readState.Index' (duration: 53.29784ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:31:52.459635Z","caller":"traceutil/trace.go:171","msg":"trace[1463889761] transaction","detail":"{read_only:false; response_revision:14206; number_of_response:1; }","duration":"139.640626ms","start":"2026-10-27T21:31:52.31996Z","end":"2026-10-27T21:31:52.459601Z","steps":["trace[1463889761] 'process raft request' (duration: 86.309895ms)","trace[1463889761] 'compare' (duration: 53.069154ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:31:52.459729Z","caller":"traceutil/trace.go:171","msg":"trace[1812389045] transaction","detail":"{read_only:false; response_revision:14207; number_of_response:1; }","duration":"129.142234ms","start":"2026-10-27T21:31:52.330568Z","end":"2026-10-27T21:31:52.45971Z","steps":["trace[1812389045] 'process raft request' (duration: 128.911738ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:31:52.459895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.923252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:31:52.459964Z","caller":"traceutil/trace.go:171","msg":"trace[237076557] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:14207; }","duration":"103.043655ms","start":"2026-10-27T21:31:52.356901Z","end":"2026-10-27T21:31:52.459945Z","steps":["trace[237076557] 'agreement among raft nodes before linearized reading' (duration: 102.927882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:32:01.422265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.293564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:32:01.422355Z","caller":"traceutil/trace.go:171","msg":"trace[2017817147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14354; }","duration":"449.440667ms","start":"2026-10-27T21:32:00.972896Z","end":"2026-10-27T21:32:01.422337Z","steps":["trace[2017817147] 'range keys from in-memory index tree' (duration: 449.233582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:32:01.422393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:32:00.972885Z","time spent":"449.499639ms","remote":"127.0.0.1:49182","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-10-27T21:32:01.422457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.479062ms","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":"warn","ts":"2026-10-27T21:32:01.422502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.654736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v4wmd\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-27T21:32:01.422623Z","caller":"traceutil/trace.go:171","msg":"trace[738409366] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v4wmd; range_end:; response_count:1; response_revision:14354; }","duration":"470.817291ms","start":"2026-10-27T21:32:00.951783Z","end":"2026-10-27T21:32:01.4226Z","steps":["trace[738409366] 'range keys from in-memory index tree' (duration: 470.451831ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:32:01.422572Z","caller":"traceutil/trace.go:171","msg":"trace[215043790] transaction","detail":"{read_only:false; response_revision:14355; number_of_response:1; }","duration":"362.482494ms","start":"2026-10-27T21:32:01.060041Z","end":"2026-10-27T21:32:01.422524Z","steps":["trace[215043790] 'process raft request' (duration: 309.577575ms)","trace[215043790] 'compare' (duration: 52.371155ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:32:01.422682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:32:00.951769Z","time spent":"470.898234ms","remote":"127.0.0.1:49344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-v4wmd\" "} {"level":"info","ts":"2026-10-27T21:32:01.422525Z","caller":"traceutil/trace.go:171","msg":"trace[1068295998] linearizableReadLoop","detail":"{readStateIndex:14846; appliedIndex:14845; }","duration":"137.741175ms","start":"2026-10-27T21:32:01.284761Z","end":"2026-10-27T21:32:01.422502Z","steps":["trace[1068295998] 'read index received' (duration: 84.878178ms)","trace[1068295998] 'applied index is now lower than readState.Index' (duration: 52.861597ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:32:01.422726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.962281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d4c7cd7a-4143-4032-9607-fdd246a35ab9\" ","response":"range_response_count:1 size:3606"} {"level":"info","ts":"2026-10-27T21:32:01.42255Z","caller":"traceutil/trace.go:171","msg":"trace[1615407089] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14354; }","duration":"495.599656ms","start":"2026-10-27T21:32:00.926922Z","end":"2026-10-27T21:32:01.422522Z","steps":["trace[1615407089] 'range keys from in-memory index tree' (duration: 495.343128ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:32:01.422757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:32:01.060017Z","time spent":"362.659239ms","remote":"127.0.0.1:49438","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-10-27T21:32:01.42278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:32:00.926909Z","time spent":"495.857512ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-27T21:32:01.422772Z","caller":"traceutil/trace.go:171","msg":"trace[1221860645] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d4c7cd7a-4143-4032-9607-fdd246a35ab9; range_end:; response_count:1; response_revision:14355; }","duration":"138.093904ms","start":"2026-10-27T21:32:01.284667Z","end":"2026-10-27T21:32:01.42276Z","steps":["trace[1221860645] 'agreement among raft nodes before linearized reading' (duration: 137.914869ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:32:01.422877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.993589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-j572b.18e28058a0200ebe\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-10-27T21:32:01.422916Z","caller":"traceutil/trace.go:171","msg":"trace[445433438] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-j572b.18e28058a0200ebe; range_end:; response_count:1; response_revision:14355; }","duration":"117.092272ms","start":"2026-10-27T21:32:01.305813Z","end":"2026-10-27T21:32:01.422905Z","steps":["trace[445433438] 'agreement among raft nodes before linearized reading' (duration: 116.984738ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:32:14.691031Z","caller":"traceutil/trace.go:171","msg":"trace[371785992] transaction","detail":"{read_only:false; response_revision:14630; number_of_response:1; }","duration":"154.495544ms","start":"2026-10-27T21:32:14.536511Z","end":"2026-10-27T21:32:14.691007Z","steps":["trace[371785992] 'process raft request' (duration: 115.282072ms)","trace[371785992] 'compare' (duration: 39.105469ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:33:28.607842Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12805} {"level":"info","ts":"2026-10-27T21:33:28.700956Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12805,"took":"90.322603ms","hash":1344939377,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":30965760,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-27T21:33:28.701024Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1344939377,"revision":12805,"compact-revision":10526} {"level":"warn","ts":"2026-10-27T21:33:41.71044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.076688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-27T21:33:41.710526Z","caller":"traceutil/trace.go:171","msg":"trace[1359865600] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15444; }","duration":"178.213671ms","start":"2026-10-27T21:33:41.532295Z","end":"2026-10-27T21:33:41.710509Z","steps":["trace[1359865600] 'range keys from in-memory index tree' (duration: 177.830411ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:33:41.710659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.367968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-10-27T21:33:41.710689Z","caller":"traceutil/trace.go:171","msg":"trace[836564241] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:15444; }","duration":"111.4344ms","start":"2026-10-27T21:33:41.599246Z","end":"2026-10-27T21:33:41.710681Z","steps":["trace[836564241] 'range keys from in-memory index tree' (duration: 111.272665ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:33:41.710663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.257813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:33:41.710735Z","caller":"traceutil/trace.go:171","msg":"trace[2106186268] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15444; }","duration":"178.370516ms","start":"2026-10-27T21:33:41.532347Z","end":"2026-10-27T21:33:41.710717Z","steps":["trace[2106186268] 'range keys from in-memory index tree' (duration: 178.075848ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:33:42.192142Z","caller":"traceutil/trace.go:171","msg":"trace[271577531] linearizableReadLoop","detail":"{readStateIndex:15969; appliedIndex:15968; }","duration":"192.382962ms","start":"2026-10-27T21:33:41.999739Z","end":"2026-10-27T21:33:42.192122Z","steps":["trace[271577531] 'read index received' (duration: 192.278109ms)","trace[271577531] 'applied index is now lower than readState.Index' (duration: 103.613µs)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:33:42.192408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.661909ms","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-10-27T21:33:42.192459Z","caller":"traceutil/trace.go:171","msg":"trace[1805862865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15445; }","duration":"192.749921ms","start":"2026-10-27T21:33:41.999691Z","end":"2026-10-27T21:33:42.192441Z","steps":["trace[1805862865] 'agreement among raft nodes before linearized reading' (duration: 192.575267ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:33:42.192485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.378174ms","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-10-27T21:33:42.192543Z","caller":"traceutil/trace.go:171","msg":"trace[244756216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15445; }","duration":"188.441266ms","start":"2026-10-27T21:33:42.004086Z","end":"2026-10-27T21:33:42.192527Z","steps":["trace[244756216] 'agreement among raft nodes before linearized reading' (duration: 188.201679ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:33:42.192566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.347626ms","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-10-27T21:33:42.19262Z","caller":"traceutil/trace.go:171","msg":"trace[599887624] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15445; }","duration":"184.423648ms","start":"2026-10-27T21:33:42.008179Z","end":"2026-10-27T21:33:42.192602Z","steps":["trace[599887624] 'agreement among raft nodes before linearized reading' (duration: 184.272854ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:33:42.739645Z","caller":"traceutil/trace.go:171","msg":"trace[1937299644] transaction","detail":"{read_only:false; response_revision:15451; number_of_response:1; }","duration":"223.605188ms","start":"2026-10-27T21:33:42.516005Z","end":"2026-10-27T21:33:42.73961Z","steps":["trace[1937299644] 'process raft request' (duration: 222.854879ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:33:42.744609Z","caller":"traceutil/trace.go:171","msg":"trace[459081546] linearizableReadLoop","detail":"{readStateIndex:15976; appliedIndex:15974; }","duration":"143.963882ms","start":"2026-10-27T21:33:42.600611Z","end":"2026-10-27T21:33:42.744575Z","steps":["trace[459081546] 'read index received' (duration: 138.365472ms)","trace[459081546] 'applied index is now lower than readState.Index' (duration: 5.59685ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:33:42.744937Z","caller":"traceutil/trace.go:171","msg":"trace[1618866099] transaction","detail":"{read_only:false; response_revision:15452; number_of_response:1; }","duration":"222.529839ms","start":"2026-10-27T21:33:42.522395Z","end":"2026-10-27T21:33:42.744925Z","steps":["trace[1618866099] 'process raft request' (duration: 221.776209ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:33:42.745272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.621139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v4wmd\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-27T21:33:42.745301Z","caller":"traceutil/trace.go:171","msg":"trace[1535148227] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v4wmd; range_end:; response_count:1; response_revision:15452; }","duration":"144.716692ms","start":"2026-10-27T21:33:42.600575Z","end":"2026-10-27T21:33:42.745292Z","steps":["trace[1535148227] 'agreement among raft nodes before linearized reading' (duration: 144.519257ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:38:28.617408Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15380} {"level":"info","ts":"2026-10-27T21:38:28.726354Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15380,"took":"105.671666ms","hash":2159407961,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":31932416,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-27T21:38:28.726429Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2159407961,"revision":15380,"compact-revision":12805} {"level":"info","ts":"2026-10-27T21:39:22.27605Z","caller":"traceutil/trace.go:171","msg":"trace[499011355] transaction","detail":"{read_only:false; response_revision:17971; number_of_response:1; }","duration":"127.254594ms","start":"2026-10-27T21:39:22.148751Z","end":"2026-10-27T21:39:22.276005Z","steps":["trace[499011355] 'process raft request' (duration: 117.702179ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:39:22.302715Z","caller":"traceutil/trace.go:171","msg":"trace[1692800700] transaction","detail":"{read_only:false; response_revision:17972; number_of_response:1; }","duration":"153.947291ms","start":"2026-10-27T21:39:22.148735Z","end":"2026-10-27T21:39:22.302682Z","steps":["trace[1692800700] 'process raft request' (duration: 153.738275ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:39:22.302951Z","caller":"traceutil/trace.go:171","msg":"trace[88051685] linearizableReadLoop","detail":"{readStateIndex:18579; appliedIndex:18577; }","duration":"147.513869ms","start":"2026-10-27T21:39:22.155408Z","end":"2026-10-27T21:39:22.302922Z","steps":["trace[88051685] 'read index received' (duration: 111.058591ms)","trace[88051685] 'applied index is now lower than readState.Index' (duration: 36.453938ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:39:22.303337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.076662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-27T21:39:22.303447Z","caller":"traceutil/trace.go:171","msg":"trace[409708340] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17973; }","duration":"139.200375ms","start":"2026-10-27T21:39:22.164223Z","end":"2026-10-27T21:39:22.303423Z","steps":["trace[409708340] 'agreement among raft nodes before linearized reading' (duration: 138.949809ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:39:22.30416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.731531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:927462"} {"level":"info","ts":"2026-10-27T21:39:22.304217Z","caller":"traceutil/trace.go:171","msg":"trace[800969817] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:17973; }","duration":"148.831023ms","start":"2026-10-27T21:39:22.155375Z","end":"2026-10-27T21:39:22.304206Z","steps":["trace[800969817] 'agreement among raft nodes before linearized reading' (duration: 147.916179ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:39:57.704284Z","caller":"traceutil/trace.go:171","msg":"trace[1740979399] transaction","detail":"{read_only:false; response_revision:18431; number_of_response:1; }","duration":"141.062985ms","start":"2026-10-27T21:39:57.561803Z","end":"2026-10-27T21:39:57.702866Z","steps":["trace[1740979399] 'process raft request' (duration: 121.11679ms)","trace[1740979399] 'compare' (duration: 19.699019ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:42:02.929367Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"371c7c2e52231793","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T21:42:02.936198Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-27T21:42:02.936319Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-27T21:43:28.62519Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17556} {"level":"info","ts":"2026-10-27T21:43:28.730881Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17556,"took":"101.021963ms","hash":395485192,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":34230272,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-27T21:43:28.730954Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":395485192,"revision":17556,"compact-revision":15380} {"level":"info","ts":"2026-10-27T21:44:25.259769Z","caller":"traceutil/trace.go:171","msg":"trace[1413698032] linearizableReadLoop","detail":"{readStateIndex:21054; appliedIndex:21053; }","duration":"117.798444ms","start":"2026-10-27T21:44:25.141938Z","end":"2026-10-27T21:44:25.259737Z","steps":["trace[1413698032] 'read index received' (duration: 117.462055ms)","trace[1413698032] 'applied index is now lower than readState.Index' (duration: 331.638µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:44:25.259874Z","caller":"traceutil/trace.go:171","msg":"trace[1141191040] transaction","detail":"{read_only:false; response_revision:20370; number_of_response:1; }","duration":"138.080028ms","start":"2026-10-27T21:44:25.121743Z","end":"2026-10-27T21:44:25.259824Z","steps":["trace[1141191040] 'process raft request' (duration: 137.757689ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:44:25.260048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.082011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T21:44:25.260152Z","caller":"traceutil/trace.go:171","msg":"trace[1410706247] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:20370; }","duration":"118.265186ms","start":"2026-10-27T21:44:25.141863Z","end":"2026-10-27T21:44:25.260128Z","steps":["trace[1410706247] 'agreement among raft nodes before linearized reading' (duration: 118.072841ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:44:41.362165Z","caller":"traceutil/trace.go:171","msg":"trace[678688068] linearizableReadLoop","detail":"{readStateIndex:21137; appliedIndex:21136; }","duration":"293.949263ms","start":"2026-10-27T21:44:41.068147Z","end":"2026-10-27T21:44:41.362096Z","steps":["trace[678688068] 'read index received' (duration: 293.684766ms)","trace[678688068] 'applied index is now lower than readState.Index' (duration: 263.357µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:44:41.362198Z","caller":"traceutil/trace.go:171","msg":"trace[339977978] transaction","detail":"{read_only:false; response_revision:20450; number_of_response:1; }","duration":"373.469519ms","start":"2026-10-27T21:44:40.98869Z","end":"2026-10-27T21:44:41.36216Z","steps":["trace[339977978] 'process raft request' (duration: 373.195201ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:44:41.362348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.148319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-27T21:44:41.362476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:44:40.988656Z","time spent":"373.700375ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:44:41.362489Z","caller":"traceutil/trace.go:171","msg":"trace[583896488] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:20450; }","duration":"294.347213ms","start":"2026-10-27T21:44:41.068106Z","end":"2026-10-27T21:44:41.362453Z","steps":["trace[583896488] 'agreement among raft nodes before linearized reading' (duration: 294.091927ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:44:41.623191Z","caller":"traceutil/trace.go:171","msg":"trace[228264488] linearizableReadLoop","detail":"{readStateIndex:21138; appliedIndex:21137; }","duration":"217.086049ms","start":"2026-10-27T21:44:41.406074Z","end":"2026-10-27T21:44:41.62316Z","steps":["trace[228264488] 'read index received' (duration: 152.148445ms)","trace[228264488] 'applied index is now lower than readState.Index' (duration: 64.936494ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:44:41.623371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.263095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-27T21:44:41.623405Z","caller":"traceutil/trace.go:171","msg":"trace[1875471560] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:20451; }","duration":"217.350397ms","start":"2026-10-27T21:44:41.406042Z","end":"2026-10-27T21:44:41.623393Z","steps":["trace[1875471560] 'agreement among raft nodes before linearized reading' (duration: 217.255104ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:44:41.623415Z","caller":"traceutil/trace.go:171","msg":"trace[380388551] transaction","detail":"{read_only:false; response_revision:20451; number_of_response:1; }","duration":"256.157369ms","start":"2026-10-27T21:44:41.36722Z","end":"2026-10-27T21:44:41.623377Z","steps":["trace[380388551] 'process raft request' (duration: 190.964007ms)","trace[380388551] 'compare' (duration: 64.815421ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:44:41.885918Z","caller":"traceutil/trace.go:171","msg":"trace[2062558467] transaction","detail":"{read_only:false; response_revision:20453; number_of_response:1; }","duration":"118.627775ms","start":"2026-10-27T21:44:41.767249Z","end":"2026-10-27T21:44:41.885877Z","steps":["trace[2062558467] 'process raft request' (duration: 98.120815ms)","trace[2062558467] 'compare' (duration: 19.930685ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:44:42.070153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.305512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-27T21:44:42.070291Z","caller":"traceutil/trace.go:171","msg":"trace[792118674] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20453; }","duration":"110.453206ms","start":"2026-10-27T21:44:41.959781Z","end":"2026-10-27T21:44:42.070234Z","steps":["trace[792118674] 'agreement among raft nodes before linearized reading' (duration: 110.153928ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:44:42.069877Z","caller":"traceutil/trace.go:171","msg":"trace[1947294739] linearizableReadLoop","detail":"{readStateIndex:21141; appliedIndex:21140; }","duration":"109.983184ms","start":"2026-10-27T21:44:41.959827Z","end":"2026-10-27T21:44:42.06981Z","steps":["trace[1947294739] 'read index received' (duration: 82.761712ms)","trace[1947294739] 'applied index is now lower than readState.Index' (duration: 27.219341ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:44:42.396376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.872283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T21:44:42.396522Z","caller":"traceutil/trace.go:171","msg":"trace[976686929] transaction","detail":"{read_only:false; response_revision:20454; number_of_response:1; }","duration":"325.121801ms","start":"2026-10-27T21:44:42.071373Z","end":"2026-10-27T21:44:42.396495Z","steps":["trace[976686929] 'process raft request' (duration: 161.98525ms)","trace[976686929] 'compare' (duration: 162.644427ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:44:42.396662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:44:42.071342Z","time spent":"325.220684ms","remote":"127.0.0.1:49218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:44:42.408288Z","caller":"traceutil/trace.go:171","msg":"trace[1774947106] transaction","detail":"{read_only:false; response_revision:20456; number_of_response:1; }","duration":"332.832738ms","start":"2026-10-27T21:44:42.075388Z","end":"2026-10-27T21:44:42.40822Z","steps":["trace[1774947106] 'process raft request' (duration: 332.649093ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:44:42.408367Z","caller":"traceutil/trace.go:171","msg":"trace[839479830] transaction","detail":"{read_only:false; response_revision:20457; number_of_response:1; }","duration":"332.386456ms","start":"2026-10-27T21:44:42.075951Z","end":"2026-10-27T21:44:42.408338Z","steps":["trace[839479830] 'process raft request' (duration: 332.137789ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:44:42.408393Z","caller":"traceutil/trace.go:171","msg":"trace[1999301550] transaction","detail":"{read_only:false; response_revision:20458; number_of_response:1; }","duration":"229.923454ms","start":"2026-10-27T21:44:42.178454Z","end":"2026-10-27T21:44:42.408378Z","steps":["trace[1999301550] 'process raft request' (duration: 229.707418ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:44:42.408465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:44:42.075922Z","time spent":"332.487999ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T21:44:42.408525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:44:42.075368Z","time spent":"333.028882ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:44:42.408229Z","caller":"traceutil/trace.go:171","msg":"trace[301272825] transaction","detail":"{read_only:false; response_revision:20455; number_of_response:1; }","duration":"332.841678ms","start":"2026-10-27T21:44:42.075337Z","end":"2026-10-27T21:44:42.408178Z","steps":["trace[301272825] 'process raft request' (duration: 332.460887ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:44:42.408673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.826143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-27T21:44:42.408497Z","caller":"traceutil/trace.go:171","msg":"trace[447172547] linearizableReadLoop","detail":"{readStateIndex:21145; appliedIndex:21141; }","duration":"311.553646ms","start":"2026-10-27T21:44:42.096794Z","end":"2026-10-27T21:44:42.408348Z","steps":["trace[447172547] 'read index received' (duration: 136.66689ms)","trace[447172547] 'applied index is now lower than readState.Index' (duration: 174.885136ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:44:42.408867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:44:42.075312Z","time spent":"333.399673ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:44:42.408763Z","caller":"traceutil/trace.go:171","msg":"trace[1409614371] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:20458; }","duration":"311.981796ms","start":"2026-10-27T21:44:42.096757Z","end":"2026-10-27T21:44:42.408739Z","steps":["trace[1409614371] 'agreement among raft nodes before linearized reading' (duration: 311.72722ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:44:42.409023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:44:42.096745Z","time spent":"312.251604ms","remote":"127.0.0.1:49518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-10-27T21:44:42.409343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.615136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-27T21:44:42.409406Z","caller":"traceutil/trace.go:171","msg":"trace[806988169] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:20458; }","duration":"102.719408ms","start":"2026-10-27T21:44:42.306661Z","end":"2026-10-27T21:44:42.40938Z","steps":["trace[806988169] 'agreement among raft nodes before linearized reading' (duration: 102.624896ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:44:42.409531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.010887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-27T21:44:42.409648Z","caller":"traceutil/trace.go:171","msg":"trace[1457061162] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20458; }","duration":"268.378047ms","start":"2026-10-27T21:44:42.141245Z","end":"2026-10-27T21:44:42.409623Z","steps":["trace[1457061162] 'agreement among raft nodes before linearized reading' (duration: 267.807572ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:44:42.681819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.803629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-27T21:44:42.681878Z","caller":"traceutil/trace.go:171","msg":"trace[1145535233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20459; }","duration":"172.922743ms","start":"2026-10-27T21:44:42.50894Z","end":"2026-10-27T21:44:42.681863Z","steps":["trace[1145535233] 'range keys from in-memory index tree' (duration: 172.667356ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:48:28.650707Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20052} {"level":"info","ts":"2026-10-27T21:48:28.784969Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20052,"took":"129.95071ms","hash":3111876600,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":32948224,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-27T21:48:28.785043Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3111876600,"revision":20052,"compact-revision":17556} {"level":"info","ts":"2026-10-27T21:48:38.583331Z","caller":"traceutil/trace.go:171","msg":"trace[163430877] transaction","detail":"{read_only:false; response_revision:22902; number_of_response:1; }","duration":"197.662917ms","start":"2026-10-27T21:48:38.385629Z","end":"2026-10-27T21:48:38.583292Z","steps":["trace[163430877] 'process raft request' (duration: 194.653696ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:48:38.901916Z","caller":"traceutil/trace.go:171","msg":"trace[210433649] transaction","detail":"{read_only:false; response_revision:22903; number_of_response:1; }","duration":"181.899035ms","start":"2026-10-27T21:48:38.719968Z","end":"2026-10-27T21:48:38.901867Z","steps":["trace[210433649] 'process raft request' (duration: 177.595929ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:48:39.006151Z","caller":"traceutil/trace.go:171","msg":"trace[1263629866] transaction","detail":"{read_only:false; response_revision:22904; number_of_response:1; }","duration":"252.417638ms","start":"2026-10-27T21:48:38.753706Z","end":"2026-10-27T21:48:39.006124Z","steps":["trace[1263629866] 'process raft request' (duration: 250.357533ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:48:39.006385Z","caller":"traceutil/trace.go:171","msg":"trace[1560064647] transaction","detail":"{read_only:false; response_revision:22905; number_of_response:1; }","duration":"110.955448ms","start":"2026-10-27T21:48:38.895396Z","end":"2026-10-27T21:48:39.006352Z","steps":["trace[1560064647] 'process raft request' (duration: 110.669161ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:51:47.08743Z","caller":"traceutil/trace.go:171","msg":"trace[983836481] linearizableReadLoop","detail":"{readStateIndex:25630; appliedIndex:25629; }","duration":"114.449744ms","start":"2026-10-27T21:51:46.972904Z","end":"2026-10-27T21:51:47.087354Z","steps":["trace[983836481] 'read index received' (duration: 112.290466ms)","trace[983836481] 'applied index is now lower than readState.Index' (duration: 2.157578ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:51:47.087554Z","caller":"traceutil/trace.go:171","msg":"trace[375390039] transaction","detail":"{read_only:false; response_revision:24821; number_of_response:1; }","duration":"119.422857ms","start":"2026-10-27T21:51:46.968078Z","end":"2026-10-27T21:51:47.087501Z","steps":["trace[375390039] 'process raft request' (duration: 116.964631ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:51:47.087657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.729031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T21:51:47.087702Z","caller":"traceutil/trace.go:171","msg":"trace[1393330742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24821; }","duration":"114.835614ms","start":"2026-10-27T21:51:46.972854Z","end":"2026-10-27T21:51:47.08769Z","steps":["trace[1393330742] 'agreement among raft nodes before linearized reading' (duration: 114.738011ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:51:52.96862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.330801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T21:51:52.968753Z","caller":"traceutil/trace.go:171","msg":"trace[1778616367] linearizableReadLoop","detail":"{readStateIndex:25678; appliedIndex:25677; }","duration":"140.546814ms","start":"2026-10-27T21:51:52.828187Z","end":"2026-10-27T21:51:52.968733Z","steps":["trace[1778616367] 'read index received' (duration: 35.081µs)","trace[1778616367] 'applied index is now lower than readState.Index' (duration: 140.510243ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:51:52.968773Z","caller":"traceutil/trace.go:171","msg":"trace[763519068] transaction","detail":"{read_only:false; response_revision:24867; number_of_response:1; }","duration":"393.791903ms","start":"2026-10-27T21:51:52.574941Z","end":"2026-10-27T21:51:52.968733Z","steps":["trace[763519068] 'process raft request' (duration: 216.206925ms)","trace[763519068] 'compare' (duration: 177.188998ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:51:52.968981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.792521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"warn","ts":"2026-10-27T21:51:52.968976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:51:52.574909Z","time spent":"393.949517ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:51:52.969019Z","caller":"traceutil/trace.go:171","msg":"trace[361536070] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24867; }","duration":"140.862152ms","start":"2026-10-27T21:51:52.828149Z","end":"2026-10-27T21:51:52.969011Z","steps":["trace[361536070] 'agreement among raft nodes before linearized reading' (duration: 140.679158ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:51:52.969046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.573035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-10-27T21:51:52.969091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.078229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-27T21:51:52.969133Z","caller":"traceutil/trace.go:171","msg":"trace[1954670965] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24867; }","duration":"105.677287ms","start":"2026-10-27T21:51:52.863435Z","end":"2026-10-27T21:51:52.969113Z","steps":["trace[1954670965] 'agreement among raft nodes before linearized reading' (duration: 105.434271ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:51:52.969189Z","caller":"traceutil/trace.go:171","msg":"trace[2117011647] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24867; }","duration":"112.208473ms","start":"2026-10-27T21:51:52.856959Z","end":"2026-10-27T21:51:52.969167Z","steps":["trace[2117011647] 'agreement among raft nodes before linearized reading' (duration: 111.946646ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:51:52.969183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.515274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T21:51:52.969391Z","caller":"traceutil/trace.go:171","msg":"trace[1718760888] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:24867; }","duration":"133.796683ms","start":"2026-10-27T21:51:52.835561Z","end":"2026-10-27T21:51:52.969358Z","steps":["trace[1718760888] 'agreement among raft nodes before linearized reading' (duration: 133.484803ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:51:52.969745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.350186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12431"} {"level":"info","ts":"2026-10-27T21:51:52.969795Z","caller":"traceutil/trace.go:171","msg":"trace[1952338784] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24867; }","duration":"135.420957ms","start":"2026-10-27T21:51:52.834361Z","end":"2026-10-27T21:51:52.969782Z","steps":["trace[1952338784] 'agreement among raft nodes before linearized reading' (duration: 134.490511ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:51:53.331628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.15716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T21:51:53.331841Z","caller":"traceutil/trace.go:171","msg":"trace[1815765965] transaction","detail":"{read_only:false; response_revision:24869; number_of_response:1; }","duration":"356.670558ms","start":"2026-10-27T21:51:52.97515Z","end":"2026-10-27T21:51:53.331821Z","steps":["trace[1815765965] 'process raft request' (duration: 356.583665ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:51:53.331851Z","caller":"traceutil/trace.go:171","msg":"trace[1299377703] transaction","detail":"{read_only:false; response_revision:24868; number_of_response:1; }","duration":"357.86595ms","start":"2026-10-27T21:51:52.973921Z","end":"2026-10-27T21:51:53.331787Z","steps":["trace[1299377703] 'process raft request' (duration: 200.467863ms)","trace[1299377703] 'compare' (duration: 157.026377ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:51:53.331944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:51:52.975134Z","time spent":"356.76397ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:51:53.332018Z","caller":"traceutil/trace.go:171","msg":"trace[1658524114] transaction","detail":"{read_only:false; response_revision:24870; number_of_response:1; }","duration":"356.796531ms","start":"2026-10-27T21:51:52.975182Z","end":"2026-10-27T21:51:53.331978Z","steps":["trace[1658524114] 'process raft request' (duration: 356.603766ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:51:53.332169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:51:52.973896Z","time spent":"358.088076ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T21:51:53.332203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.034367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18703"} {"level":"info","ts":"2026-10-27T21:51:53.331834Z","caller":"traceutil/trace.go:171","msg":"trace[1653725174] linearizableReadLoop","detail":"{readStateIndex:25679; appliedIndex:25678; }","duration":"356.627406ms","start":"2026-10-27T21:51:52.975156Z","end":"2026-10-27T21:51:53.331783Z","steps":["trace[1653725174] 'read index received' (duration: 199.25872ms)","trace[1653725174] 'applied index is now lower than readState.Index' (duration: 157.363846ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:51:53.332286Z","caller":"traceutil/trace.go:171","msg":"trace[291695700] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24870; }","duration":"357.110389ms","start":"2026-10-27T21:51:52.975131Z","end":"2026-10-27T21:51:53.332241Z","steps":["trace[291695700] 'agreement among raft nodes before linearized reading' (duration: 356.827121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:51:53.332333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:51:52.975114Z","time spent":"357.209272ms","remote":"127.0.0.1:49336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18726,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-27T21:51:53.332202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:51:52.975166Z","time spent":"356.942104ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T21:51:53.393829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.227134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-27T21:51:53.393879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.869234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-10-27T21:51:53.393948Z","caller":"traceutil/trace.go:171","msg":"trace[1422110611] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:24871; }","duration":"204.422409ms","start":"2026-10-27T21:51:53.189497Z","end":"2026-10-27T21:51:53.39392Z","steps":["trace[1422110611] 'agreement among raft nodes before linearized reading' (duration: 204.155982ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:51:53.394003Z","caller":"traceutil/trace.go:171","msg":"trace[1522949184] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24871; }","duration":"417.041229ms","start":"2026-10-27T21:51:52.976928Z","end":"2026-10-27T21:51:53.393969Z","steps":["trace[1522949184] 'agreement among raft nodes before linearized reading' (duration: 416.69829ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:51:53.393984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.961521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-27T21:51:53.394033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.396657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-27T21:51:53.394102Z","caller":"traceutil/trace.go:171","msg":"trace[1322524369] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24871; }","duration":"257.142135ms","start":"2026-10-27T21:51:53.136936Z","end":"2026-10-27T21:51:53.394079Z","steps":["trace[1322524369] 'agreement among raft nodes before linearized reading' (duration: 256.808876ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:51:53.394087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:51:52.976917Z","time spent":"417.159222ms","remote":"127.0.0.1:49630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11860,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"info","ts":"2026-10-27T21:51:53.394119Z","caller":"traceutil/trace.go:171","msg":"trace[1084935751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24871; }","duration":"271.568553ms","start":"2026-10-27T21:51:53.122531Z","end":"2026-10-27T21:51:53.3941Z","steps":["trace[1084935751] 'agreement among raft nodes before linearized reading' (duration: 271.279605ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:52:57.043548Z","caller":"traceutil/trace.go:171","msg":"trace[1630498214] transaction","detail":"{read_only:false; response_revision:25437; number_of_response:1; }","duration":"113.267564ms","start":"2026-10-27T21:52:56.930241Z","end":"2026-10-27T21:52:57.043508Z","steps":["trace[1630498214] 'process raft request' (duration: 112.992347ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:52:57.386794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.097663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-27T21:52:57.386918Z","caller":"traceutil/trace.go:171","msg":"trace[1330624139] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:25437; }","duration":"300.281958ms","start":"2026-10-27T21:52:57.08661Z","end":"2026-10-27T21:52:57.386892Z","steps":["trace[1330624139] 'count revisions from in-memory index tree' (duration: 299.99893ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:52:57.386963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:52:57.086596Z","time spent":"300.355209ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":17,"response size":31,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-10-27T21:52:57.387013Z","caller":"traceutil/trace.go:171","msg":"trace[1747278774] transaction","detail":"{read_only:false; response_revision:25438; number_of_response:1; }","duration":"244.476779ms","start":"2026-10-27T21:52:57.142485Z","end":"2026-10-27T21:52:57.386962Z","steps":["trace[1747278774] 'process raft request' (duration: 167.959383ms)","trace[1747278774] 'compare' (duration: 76.231528ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:52:57.765548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.630626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T21:52:57.765836Z","caller":"traceutil/trace.go:171","msg":"trace[1616521804] transaction","detail":"{read_only:false; response_revision:25439; number_of_response:1; }","duration":"552.206546ms","start":"2026-10-27T21:52:57.213593Z","end":"2026-10-27T21:52:57.765799Z","steps":["trace[1616521804] 'process raft request' (duration: 442.23412ms)","trace[1616521804] 'compare' (duration: 109.348438ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:52:57.765998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:52:57.213561Z","time spent":"552.35412ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T21:52:57.954002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.33044ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-27T21:52:57.954995Z","caller":"traceutil/trace.go:171","msg":"trace[938671608] transaction","detail":"{read_only:false; response_revision:25440; number_of_response:1; }","duration":"275.033499ms","start":"2026-10-27T21:52:57.679927Z","end":"2026-10-27T21:52:57.95496Z","steps":["trace[938671608] 'process raft request' (duration: 274.227808ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:52:58.104773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.51591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T21:52:58.104957Z","caller":"traceutil/trace.go:171","msg":"trace[1472604007] linearizableReadLoop","detail":"{readStateIndex:26266; appliedIndex:26263; }","duration":"374.316097ms","start":"2026-10-27T21:52:57.730625Z","end":"2026-10-27T21:52:58.104941Z","steps":["trace[1472604007] 'read index received' (duration: 209.52767ms)","trace[1472604007] 'applied index is now lower than readState.Index' (duration: 164.787327ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:52:58.105041Z","caller":"traceutil/trace.go:171","msg":"trace[1113440786] transaction","detail":"{read_only:false; response_revision:25442; number_of_response:1; }","duration":"250.235794ms","start":"2026-10-27T21:52:57.854794Z","end":"2026-10-27T21:52:58.10503Z","steps":["trace[1113440786] 'process raft request' (duration: 250.07932ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:52:58.105315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.685917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-27T21:52:58.105355Z","caller":"traceutil/trace.go:171","msg":"trace[1873227889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25442; }","duration":"374.727968ms","start":"2026-10-27T21:52:57.730617Z","end":"2026-10-27T21:52:58.105345Z","steps":["trace[1873227889] 'agreement among raft nodes before linearized reading' (duration: 374.558854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:52:58.105385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:52:57.730553Z","time spent":"374.82278ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-27T21:52:58.105644Z","caller":"traceutil/trace.go:171","msg":"trace[226188278] transaction","detail":"{read_only:false; response_revision:25441; number_of_response:1; }","duration":"418.607037ms","start":"2026-10-27T21:52:57.686731Z","end":"2026-10-27T21:52:58.105338Z","steps":["trace[226188278] 'process raft request' (duration: 269.393018ms)","trace[226188278] 'compare' (duration: 148.399127ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:52:58.106126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:52:57.686699Z","time spent":"419.1049ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:52:58.1147Z","caller":"traceutil/trace.go:171","msg":"trace[1246040431] transaction","detail":"{read_only:false; response_revision:25443; number_of_response:1; }","duration":"169.572015ms","start":"2026-10-27T21:52:57.945099Z","end":"2026-10-27T21:52:58.114671Z","steps":["trace[1246040431] 'process raft request' (duration: 169.38285ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:52:58.120365Z","caller":"traceutil/trace.go:171","msg":"trace[1484258623] transaction","detail":"{read_only:false; response_revision:25444; number_of_response:1; }","duration":"120.448566ms","start":"2026-10-27T21:52:57.999893Z","end":"2026-10-27T21:52:58.120342Z","steps":["trace[1484258623] 'process raft request' (duration: 120.264711ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:52:58.120733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.854439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-27T21:52:58.121025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.039402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-27T21:52:58.121058Z","caller":"traceutil/trace.go:171","msg":"trace[1023889765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25445; }","duration":"389.090003ms","start":"2026-10-27T21:52:57.73196Z","end":"2026-10-27T21:52:58.12105Z","steps":["trace[1023889765] 'agreement among raft nodes before linearized reading' (duration: 389.018601ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:52:58.121078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:52:57.731953Z","time spent":"389.119874ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-27T21:52:58.120842Z","caller":"traceutil/trace.go:171","msg":"trace[222084552] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:25445; }","duration":"200.047354ms","start":"2026-10-27T21:52:57.92077Z","end":"2026-10-27T21:52:58.120818Z","steps":["trace[222084552] 'agreement among raft nodes before linearized reading' (duration: 199.794988ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:52:58.120779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.727125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-10-27T21:52:58.121611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.555396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-27T21:52:58.121611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.633888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-27T21:52:58.121651Z","caller":"traceutil/trace.go:171","msg":"trace[1989399362] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25445; }","duration":"309.65489ms","start":"2026-10-27T21:52:57.81198Z","end":"2026-10-27T21:52:58.121635Z","steps":["trace[1989399362] 'agreement among raft nodes before linearized reading' (duration: 308.694304ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:52:58.121668Z","caller":"traceutil/trace.go:171","msg":"trace[2061996661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25445; }","duration":"386.661429ms","start":"2026-10-27T21:52:57.734989Z","end":"2026-10-27T21:52:58.121651Z","steps":["trace[2061996661] 'agreement among raft nodes before linearized reading' (duration: 385.598209ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:52:58.121697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:52:57.811952Z","time spent":"309.735252ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-27T21:52:58.121695Z","caller":"traceutil/trace.go:171","msg":"trace[1229146028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25445; }","duration":"389.737531ms","start":"2026-10-27T21:52:57.731935Z","end":"2026-10-27T21:52:58.121673Z","steps":["trace[1229146028] 'agreement among raft nodes before linearized reading' (duration: 388.59462ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:52:58.121704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:52:57.734977Z","time spent":"386.71934ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-27T21:52:58.121733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:52:57.731864Z","time spent":"389.862135ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-27T21:53:28.659088Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22388} {"level":"info","ts":"2026-10-27T21:53:28.795003Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22388,"took":"132.004527ms","hash":358665593,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":44740608,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-27T21:53:28.7951Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":358665593,"revision":22388,"compact-revision":20052} {"level":"info","ts":"2026-10-27T21:53:38.062886Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006865.wal"} {"level":"info","ts":"2026-10-27T21:58:28.666562Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25672} {"level":"info","ts":"2026-10-27T21:58:28.837865Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25672,"took":"166.969315ms","hash":1459888220,"current-db-size-bytes":57835520,"current-db-size":"58 MB","current-db-size-in-use-bytes":35500032,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-27T21:58:28.837944Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1459888220,"revision":25672,"compact-revision":22388} {"level":"info","ts":"2026-10-27T21:59:44.457659Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"371c7c2e52231793","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T21:59:44.464707Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-27T21:59:44.464866Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-27T22:03:28.674851Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28536} {"level":"info","ts":"2026-10-27T22:03:28.838803Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28536,"took":"157.301094ms","hash":3590738012,"current-db-size-bytes":57835520,"current-db-size":"58 MB","current-db-size-in-use-bytes":30928896,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-27T22:03:28.838879Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3590738012,"revision":28536,"compact-revision":25672} {"level":"info","ts":"2026-10-27T22:08:28.681075Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30816} {"level":"info","ts":"2026-10-27T22:08:28.815889Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30816,"took":"131.617141ms","hash":3850282585,"current-db-size-bytes":57835520,"current-db-size":"58 MB","current-db-size-in-use-bytes":30388224,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-27T22:08:28.815969Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3850282585,"revision":30816,"compact-revision":28536} {"level":"info","ts":"2026-10-27T22:13:28.698913Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33106} {"level":"info","ts":"2026-10-27T22:13:28.881961Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33106,"took":"178.30192ms","hash":1819840501,"current-db-size-bytes":58875904,"current-db-size":"59 MB","current-db-size-in-use-bytes":30957568,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-27T22:13:28.882059Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1819840501,"revision":33106,"compact-revision":30816} {"level":"info","ts":"2026-10-27T22:18:28.706438Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35401} {"level":"info","ts":"2026-10-27T22:18:28.851785Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35401,"took":"140.657424ms","hash":4080116035,"current-db-size-bytes":58875904,"current-db-size":"59 MB","current-db-size-in-use-bytes":32694272,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-27T22:18:28.851867Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4080116035,"revision":35401,"compact-revision":33106} {"level":"info","ts":"2026-10-27T22:20:58.430873Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"371c7c2e52231793","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T22:20:58.438058Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-27T22:20:58.438164Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-27T22:23:28.714117Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37633} {"level":"info","ts":"2026-10-27T22:23:28.854621Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37633,"took":"136.243843ms","hash":3317976512,"current-db-size-bytes":58875904,"current-db-size":"59 MB","current-db-size-in-use-bytes":31997952,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-27T22:23:28.854691Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3317976512,"revision":37633,"compact-revision":35401}