{"level":"warn","ts":"2026-03-26T17:55:15.169893Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-26T17:55:15.170028Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.169:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.169:2380","--initial-cluster=instance=https://199.19.213.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.169:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-26T17:55:15.170111Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-26T17:55:15.170121Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.169:2380"]} {"level":"info","ts":"2026-03-26T17:55:15.170144Z","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-03-26T17:55:15.17116Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"]} {"level":"info","ts":"2026-03-26T17:55:15.171237Z","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.169:2380"],"listen-peer-urls":["https://199.19.213.169:2380"],"advertise-client-urls":["https://199.19.213.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.169:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-26T17:55:15.175472Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.977429ms"} {"level":"info","ts":"2026-03-26T17:55:15.185942Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a742f9f5aa7326e5","cluster-id":"a6b466adbadbbcd5"} {"level":"info","ts":"2026-03-26T17:55:15.186068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=()"} {"level":"info","ts":"2026-03-26T17:55:15.186108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became follower at term 0"} {"level":"info","ts":"2026-03-26T17:55:15.186129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a742f9f5aa7326e5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-26T17:55:15.186142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became follower at term 1"} {"level":"info","ts":"2026-03-26T17:55:15.186197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=(12052470386318845669)"} {"level":"warn","ts":"2026-03-26T17:55:15.192211Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-26T17:55:15.196195Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-26T17:55:15.19759Z","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-03-26T17:55:15.200038Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a742f9f5aa7326e5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-26T17:55:15.200651Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a742f9f5aa7326e5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-26T17:55:15.201009Z","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-03-26T17:55:15.201057Z","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-03-26T17:55:15.201068Z","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-03-26T17:55:15.201402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=(12052470386318845669)"} {"level":"info","ts":"2026-03-26T17:55:15.201642Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a6b466adbadbbcd5","local-member-id":"a742f9f5aa7326e5","added-peer-id":"a742f9f5aa7326e5","added-peer-peer-urls":["https://199.19.213.169:2380"]} {"level":"info","ts":"2026-03-26T17:55:15.205058Z","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-03-26T17:55:15.205262Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.169:2380"} {"level":"info","ts":"2026-03-26T17:55:15.205305Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.169:2380"} {"level":"info","ts":"2026-03-26T17:55:15.205726Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a742f9f5aa7326e5","initial-advertise-peer-urls":["https://199.19.213.169:2380"],"listen-peer-urls":["https://199.19.213.169:2380"],"advertise-client-urls":["https://199.19.213.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-26T17:55:15.205823Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-26T17:55:15.386711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 is starting a new election at term 1"} {"level":"info","ts":"2026-03-26T17:55:15.386912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-26T17:55:15.387024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 received MsgPreVoteResp from a742f9f5aa7326e5 at term 1"} {"level":"info","ts":"2026-03-26T17:55:15.387072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became candidate at term 2"} {"level":"info","ts":"2026-03-26T17:55:15.387124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 received MsgVoteResp from a742f9f5aa7326e5 at term 2"} {"level":"info","ts":"2026-03-26T17:55:15.387166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became leader at term 2"} {"level":"info","ts":"2026-03-26T17:55:15.387206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a742f9f5aa7326e5 elected leader a742f9f5aa7326e5 at term 2"} {"level":"info","ts":"2026-03-26T17:55:15.388263Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T17:55:15.389162Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-26T17:55:15.389286Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-26T17:55:15.389157Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a742f9f5aa7326e5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.169:2379]}","request-path":"/0/members/a742f9f5aa7326e5/attributes","cluster-id":"a6b466adbadbbcd5","publish-timeout":"7s"} {"level":"info","ts":"2026-03-26T17:55:15.389443Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a6b466adbadbbcd5","local-member-id":"a742f9f5aa7326e5","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T17:55:15.389552Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-26T17:55:15.389638Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T17:55:15.3897Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T17:55:15.389742Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-26T17:55:15.393373Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.169:2379"} {"level":"info","ts":"2026-03-26T17:55:15.394271Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-26T17:55:50.728286Z","caller":"traceutil/trace.go:171","msg":"trace[793112891] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"140.664558ms","start":"2026-03-26T17:55:50.587594Z","end":"2026-03-26T17:55:50.728258Z","steps":["trace[793112891] 'process raft request' (duration: 140.319969ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:55:50.72889Z","caller":"traceutil/trace.go:171","msg":"trace[1761414765] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"137.47268ms","start":"2026-03-26T17:55:50.591397Z","end":"2026-03-26T17:55:50.72887Z","steps":["trace[1761414765] 'process raft request' (duration: 136.791381ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:55:50.729093Z","caller":"traceutil/trace.go:171","msg":"trace[720408507] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"123.502595ms","start":"2026-03-26T17:55:50.605574Z","end":"2026-03-26T17:55:50.729077Z","steps":["trace[720408507] 'process raft request' (duration: 123.247718ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:55:50.729111Z","caller":"traceutil/trace.go:171","msg":"trace[2060976363] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"128.338847ms","start":"2026-03-26T17:55:50.600729Z","end":"2026-03-26T17:55:50.729068Z","steps":["trace[2060976363] 'process raft request' (duration: 127.987018ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:55:50.729232Z","caller":"traceutil/trace.go:171","msg":"trace[1547710643] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"119.409922ms","start":"2026-03-26T17:55:50.609799Z","end":"2026-03-26T17:55:50.729209Z","steps":["trace[1547710643] 'process raft request' (duration: 119.249928ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:55:50.72932Z","caller":"traceutil/trace.go:171","msg":"trace[1375371236] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"121.131909ms","start":"2026-03-26T17:55:50.608176Z","end":"2026-03-26T17:55:50.729308Z","steps":["trace[1375371236] 'process raft request' (duration: 120.713118ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:55:50.729377Z","caller":"traceutil/trace.go:171","msg":"trace[1258468044] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"119.709961ms","start":"2026-03-26T17:55:50.60965Z","end":"2026-03-26T17:55:50.72936Z","steps":["trace[1258468044] 'process raft request' (duration: 119.31364ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:55:50.73583Z","caller":"traceutil/trace.go:171","msg":"trace[1398779974] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"103.918425ms","start":"2026-03-26T17:55:50.631883Z","end":"2026-03-26T17:55:50.735801Z","steps":["trace[1398779974] 'process raft request' (duration: 103.326059ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:55:56.486543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.35984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T17:55:56.486689Z","caller":"traceutil/trace.go:171","msg":"trace[657776744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"181.606826ms","start":"2026-03-26T17:55:56.305061Z","end":"2026-03-26T17:55:56.486668Z","steps":["trace[657776744] 'range keys from in-memory index tree' (duration: 181.209115ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:55:56.640687Z","caller":"traceutil/trace.go:171","msg":"trace[1685273484] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"138.071847ms","start":"2026-03-26T17:55:56.502522Z","end":"2026-03-26T17:55:56.640594Z","steps":["trace[1685273484] 'process raft request' (duration: 137.44383ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:55:56.823547Z","caller":"traceutil/trace.go:171","msg":"trace[1700792812] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"234.841235ms","start":"2026-03-26T17:55:56.588669Z","end":"2026-03-26T17:55:56.82351Z","steps":["trace[1700792812] 'process raft request' (duration: 229.626041ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:55:57.465747Z","caller":"traceutil/trace.go:171","msg":"trace[1193327892] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"113.898629ms","start":"2026-03-26T17:55:57.351819Z","end":"2026-03-26T17:55:57.465717Z","steps":["trace[1193327892] 'read index received' (duration: 110.579398ms)","trace[1193327892] 'applied index is now lower than readState.Index' (duration: 3.318001ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:55:57.466126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.293289ms","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-03-26T17:55:57.466212Z","caller":"traceutil/trace.go:171","msg":"trace[1250283626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"114.463404ms","start":"2026-03-26T17:55:57.351727Z","end":"2026-03-26T17:55:57.46619Z","steps":["trace[1250283626] 'agreement among raft nodes before linearized reading' (duration: 114.107454ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:55:57.465803Z","caller":"traceutil/trace.go:171","msg":"trace[1495735615] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"576.778469ms","start":"2026-03-26T17:55:56.888982Z","end":"2026-03-26T17:55:57.465761Z","steps":["trace[1495735615] 'process raft request' (duration: 573.459728ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:55:57.466904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:55:56.888949Z","time spent":"577.352835ms","remote":"127.0.0.1:40000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T17:55:58.743388Z","caller":"traceutil/trace.go:171","msg":"trace[39856609] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"148.988438ms","start":"2026-03-26T17:55:58.594361Z","end":"2026-03-26T17:55:58.74335Z","steps":["trace[39856609] 'process raft request' (duration: 148.778062ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:56:41.936034Z","caller":"traceutil/trace.go:171","msg":"trace[1968241505] linearizableReadLoop","detail":"{readStateIndex:1184; appliedIndex:1183; }","duration":"229.268911ms","start":"2026-03-26T17:56:41.706738Z","end":"2026-03-26T17:56:41.936007Z","steps":["trace[1968241505] 'read index received' (duration: 229.005914ms)","trace[1968241505] 'applied index is now lower than readState.Index' (duration: 262.187µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:56:41.936172Z","caller":"traceutil/trace.go:171","msg":"trace[234169713] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"232.796008ms","start":"2026-03-26T17:56:41.703339Z","end":"2026-03-26T17:56:41.936135Z","steps":["trace[234169713] 'process raft request' (duration: 232.47235ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:56:41.936321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.54904ms","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-03-26T17:56:41.936371Z","caller":"traceutil/trace.go:171","msg":"trace[1292522802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1149; }","duration":"229.647552ms","start":"2026-03-26T17:56:41.706711Z","end":"2026-03-26T17:56:41.936359Z","steps":["trace[1292522802] 'agreement among raft nodes before linearized reading' (duration: 229.458076ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:57:27.995391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.360524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T17:57:27.995675Z","caller":"traceutil/trace.go:171","msg":"trace[2010445152] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"490.851397ms","start":"2026-03-26T17:57:27.504769Z","end":"2026-03-26T17:57:27.99562Z","steps":["trace[2010445152] 'process raft request' (duration: 116.807806ms)","trace[2010445152] 'compare' (duration: 373.199979ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:57:27.995886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:57:27.504735Z","time spent":"491.028962ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T17:57:28.504411Z","caller":"traceutil/trace.go:171","msg":"trace[536136285] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"219.374109ms","start":"2026-03-26T17:57:28.285004Z","end":"2026-03-26T17:57:28.504379Z","steps":["trace[536136285] 'process raft request' (duration: 219.123743ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:57:28.524439Z","caller":"traceutil/trace.go:171","msg":"trace[1373760175] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"238.167843ms","start":"2026-03-26T17:57:28.286245Z","end":"2026-03-26T17:57:28.524412Z","steps":["trace[1373760175] 'process raft request' (duration: 238.05519ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:57:28.524651Z","caller":"traceutil/trace.go:171","msg":"trace[1367684794] linearizableReadLoop","detail":"{readStateIndex:1889; appliedIndex:1886; }","duration":"168.252967ms","start":"2026-03-26T17:57:28.356371Z","end":"2026-03-26T17:57:28.524625Z","steps":["trace[1367684794] 'read index received' (duration: 147.813128ms)","trace[1367684794] 'applied index is now lower than readState.Index' (duration: 20.437809ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:57:28.524442Z","caller":"traceutil/trace.go:171","msg":"trace[638420594] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"238.196625ms","start":"2026-03-26T17:57:28.286202Z","end":"2026-03-26T17:57:28.524399Z","steps":["trace[638420594] 'process raft request' (duration: 237.930048ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:57:28.524734Z","caller":"traceutil/trace.go:171","msg":"trace[1046587999] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"131.86552ms","start":"2026-03-26T17:57:28.392854Z","end":"2026-03-26T17:57:28.524719Z","steps":["trace[1046587999] 'process raft request' (duration: 131.49427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:57:28.52495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.537275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T17:57:28.525026Z","caller":"traceutil/trace.go:171","msg":"trace[812889105] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1819; }","duration":"168.669948ms","start":"2026-03-26T17:57:28.356334Z","end":"2026-03-26T17:57:28.525004Z","steps":["trace[812889105] 'agreement among raft nodes before linearized reading' (duration: 168.502524ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:57:42.189638Z","caller":"traceutil/trace.go:171","msg":"trace[1897607910] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"141.637942ms","start":"2026-03-26T17:57:42.047962Z","end":"2026-03-26T17:57:42.1896Z","steps":["trace[1897607910] 'process raft request' (duration: 141.070607ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:58:18.489914Z","caller":"traceutil/trace.go:171","msg":"trace[731694278] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"144.231922ms","start":"2026-03-26T17:58:18.345639Z","end":"2026-03-26T17:58:18.489871Z","steps":["trace[731694278] 'process raft request' (duration: 143.919864ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:58:24.209047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.354621ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-26T17:58:24.44581Z","caller":"traceutil/trace.go:171","msg":"trace[1964668055] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"100.878936ms","start":"2026-03-26T17:58:24.344903Z","end":"2026-03-26T17:58:24.445782Z","steps":["trace[1964668055] 'process raft request' (duration: 100.691581ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:59:14.777249Z","caller":"traceutil/trace.go:171","msg":"trace[178288931] transaction","detail":"{read_only:false; response_revision:2618; number_of_response:1; }","duration":"223.821687ms","start":"2026-03-26T17:59:14.553387Z","end":"2026-03-26T17:59:14.777208Z","steps":["trace[178288931] 'process raft request' (duration: 223.57105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:59:48.427832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.51816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T17:59:48.427971Z","caller":"traceutil/trace.go:171","msg":"trace[565355865] linearizableReadLoop","detail":"{readStateIndex:2932; appliedIndex:2931; }","duration":"122.214135ms","start":"2026-03-26T17:59:48.305735Z","end":"2026-03-26T17:59:48.427949Z","steps":["trace[565355865] 'read index received' (duration: 78.573µs)","trace[565355865] 'applied index is now lower than readState.Index' (duration: 122.134142ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:59:48.42804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.319557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T17:59:48.428075Z","caller":"traceutil/trace.go:171","msg":"trace[507656265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2823; }","duration":"122.40627ms","start":"2026-03-26T17:59:48.305658Z","end":"2026-03-26T17:59:48.428064Z","steps":["trace[507656265] 'agreement among raft nodes before linearized reading' (duration: 122.347688ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:59:48.428039Z","caller":"traceutil/trace.go:171","msg":"trace[990634248] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"231.591625ms","start":"2026-03-26T17:59:48.196406Z","end":"2026-03-26T17:59:48.427998Z","steps":["trace[990634248] 'process raft request' (duration: 76.791208ms)","trace[990634248] 'compare' (duration: 154.309984ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:03:37.535458Z","caller":"traceutil/trace.go:171","msg":"trace[855124309] transaction","detail":"{read_only:false; response_revision:4846; number_of_response:1; }","duration":"165.001356ms","start":"2026-03-26T18:03:37.370419Z","end":"2026-03-26T18:03:37.535421Z","steps":["trace[855124309] 'process raft request' (duration: 164.814721ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:03:39.13183Z","caller":"traceutil/trace.go:171","msg":"trace[11634270] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"126.641374ms","start":"2026-03-26T18:03:39.005145Z","end":"2026-03-26T18:03:39.131786Z","steps":["trace[11634270] 'process raft request' (duration: 74.921766ms)","trace[11634270] 'compare' (duration: 51.022819ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:03:39.131968Z","caller":"traceutil/trace.go:171","msg":"trace[619051537] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"124.519547ms","start":"2026-03-26T18:03:39.007401Z","end":"2026-03-26T18:03:39.131921Z","steps":["trace[619051537] 'process raft request' (duration: 124.230129ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:03:39.132239Z","caller":"traceutil/trace.go:171","msg":"trace[213862378] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"124.617109ms","start":"2026-03-26T18:03:39.007596Z","end":"2026-03-26T18:03:39.132213Z","steps":["trace[213862378] 'process raft request' (duration: 124.120086ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:03:39.133336Z","caller":"traceutil/trace.go:171","msg":"trace[2143938856] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"125.358719ms","start":"2026-03-26T18:03:39.007942Z","end":"2026-03-26T18:03:39.133301Z","steps":["trace[2143938856] 'process raft request' (duration: 124.191688ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:03:39.133434Z","caller":"traceutil/trace.go:171","msg":"trace[1742784017] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"125.240616ms","start":"2026-03-26T18:03:39.008159Z","end":"2026-03-26T18:03:39.133399Z","steps":["trace[1742784017] 'process raft request' (duration: 124.736422ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:03:39.133624Z","caller":"traceutil/trace.go:171","msg":"trace[1308598589] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"101.060952ms","start":"2026-03-26T18:03:39.032493Z","end":"2026-03-26T18:03:39.133554Z","steps":["trace[1308598589] 'process raft request' (duration: 100.541948ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:03:40.932263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.286781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-03-26T18:03:40.932372Z","caller":"traceutil/trace.go:171","msg":"trace[1619521857] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4976; }","duration":"282.453705ms","start":"2026-03-26T18:03:40.649893Z","end":"2026-03-26T18:03:40.932347Z","steps":["trace[1619521857] 'range keys from in-memory index tree' (duration: 282.128467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:03:40.932608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.48695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719292"} {"level":"info","ts":"2026-03-26T18:03:40.932662Z","caller":"traceutil/trace.go:171","msg":"trace[1524348485] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4976; }","duration":"251.582212ms","start":"2026-03-26T18:03:40.681064Z","end":"2026-03-26T18:03:40.932647Z","steps":["trace[1524348485] 'range keys from in-memory index tree' (duration: 250.633877ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:03:52.403896Z","caller":"traceutil/trace.go:171","msg":"trace[1639899344] transaction","detail":"{read_only:false; response_revision:5206; number_of_response:1; }","duration":"105.731657ms","start":"2026-03-26T18:03:52.298133Z","end":"2026-03-26T18:03:52.403865Z","steps":["trace[1639899344] 'process raft request' (duration: 105.599113ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:03:52.403966Z","caller":"traceutil/trace.go:171","msg":"trace[1201519165] transaction","detail":"{read_only:false; response_revision:5205; number_of_response:1; }","duration":"106.359934ms","start":"2026-03-26T18:03:52.297562Z","end":"2026-03-26T18:03:52.403922Z","steps":["trace[1201519165] 'process raft request' (duration: 37.832058ms)","trace[1201519165] 'compare' (duration: 68.151616ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:03:52.783761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.183179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T18:03:52.783901Z","caller":"traceutil/trace.go:171","msg":"trace[1865823748] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"266.978753ms","start":"2026-03-26T18:03:52.516889Z","end":"2026-03-26T18:03:52.783868Z","steps":["trace[1865823748] 'process raft request' (duration: 11.585239ms)","trace[1865823748] 'compare' (duration: 254.987433ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:03:52.784018Z","caller":"traceutil/trace.go:171","msg":"trace[2067949249] linearizableReadLoop","detail":"{readStateIndex:5400; appliedIndex:5399; }","duration":"232.159855ms","start":"2026-03-26T18:03:52.551833Z","end":"2026-03-26T18:03:52.783993Z","steps":["trace[2067949249] 'read index received' (duration: 73.702µs)","trace[2067949249] 'applied index is now lower than readState.Index' (duration: 232.084913ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:03:52.784217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.378911ms","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-03-26T18:03:52.784235Z","caller":"traceutil/trace.go:171","msg":"trace[534231509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5208; }","duration":"232.454254ms","start":"2026-03-26T18:03:52.551775Z","end":"2026-03-26T18:03:52.78423Z","steps":["trace[534231509] 'agreement among raft nodes before linearized reading' (duration: 232.323ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:03:52.784345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.662732ms","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-03-26T18:03:52.784414Z","caller":"traceutil/trace.go:171","msg":"trace[1814824777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5208; }","duration":"225.761015ms","start":"2026-03-26T18:03:52.558636Z","end":"2026-03-26T18:03:52.784397Z","steps":["trace[1814824777] 'agreement among raft nodes before linearized reading' (duration: 225.5759ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:03:52.784525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.059927ms","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-03-26T18:03:52.784544Z","caller":"traceutil/trace.go:171","msg":"trace[1052902436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5208; }","duration":"222.126628ms","start":"2026-03-26T18:03:52.562411Z","end":"2026-03-26T18:03:52.784538Z","steps":["trace[1052902436] 'agreement among raft nodes before linearized reading' (duration: 222.072317ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:03:52.783969Z","caller":"traceutil/trace.go:171","msg":"trace[1474209263] transaction","detail":"{read_only:false; response_revision:5208; number_of_response:1; }","duration":"212.378948ms","start":"2026-03-26T18:03:52.571569Z","end":"2026-03-26T18:03:52.783948Z","steps":["trace[1474209263] 'process raft request' (duration: 212.301616ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:04:02.127279Z","caller":"traceutil/trace.go:171","msg":"trace[1233969411] linearizableReadLoop","detail":"{readStateIndex:5573; appliedIndex:5572; }","duration":"104.636348ms","start":"2026-03-26T18:04:02.022622Z","end":"2026-03-26T18:04:02.127258Z","steps":["trace[1233969411] 'read index received' (duration: 104.441042ms)","trace[1233969411] 'applied index is now lower than readState.Index' (duration: 193.996µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:04:02.127402Z","caller":"traceutil/trace.go:171","msg":"trace[2084963864] transaction","detail":"{read_only:false; response_revision:5375; number_of_response:1; }","duration":"145.196418ms","start":"2026-03-26T18:04:01.982195Z","end":"2026-03-26T18:04:02.127392Z","steps":["trace[2084963864] 'process raft request' (duration: 144.914651ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:04:02.128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.309435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f6bf2f2a-27b7-4087-b710-d036100aedb4\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-26T18:04:02.12818Z","caller":"traceutil/trace.go:171","msg":"trace[1955560657] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f6bf2f2a-27b7-4087-b710-d036100aedb4; range_end:; response_count:1; response_revision:5375; }","duration":"105.561082ms","start":"2026-03-26T18:04:02.02258Z","end":"2026-03-26T18:04:02.128141Z","steps":["trace[1955560657] 'agreement among raft nodes before linearized reading' (duration: 105.060249ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:04:02.380362Z","caller":"traceutil/trace.go:171","msg":"trace[1111265914] linearizableReadLoop","detail":"{readStateIndex:5574; appliedIndex:5573; }","duration":"167.108452ms","start":"2026-03-26T18:04:02.213225Z","end":"2026-03-26T18:04:02.380334Z","steps":["trace[1111265914] 'read index received' (duration: 166.863926ms)","trace[1111265914] 'applied index is now lower than readState.Index' (duration: 243.106µs)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:04:02.380621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.362479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-26T18:04:02.380746Z","caller":"traceutil/trace.go:171","msg":"trace[1390025236] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:5376; }","duration":"167.566125ms","start":"2026-03-26T18:04:02.213146Z","end":"2026-03-26T18:04:02.380712Z","steps":["trace[1390025236] 'agreement among raft nodes before linearized reading' (duration: 167.301937ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:04:02.380458Z","caller":"traceutil/trace.go:171","msg":"trace[319213741] transaction","detail":"{read_only:false; response_revision:5376; number_of_response:1; }","duration":"204.579191ms","start":"2026-03-26T18:04:02.175845Z","end":"2026-03-26T18:04:02.380424Z","steps":["trace[319213741] 'process raft request' (duration: 204.306314ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:04:02.589321Z","caller":"traceutil/trace.go:171","msg":"trace[652796716] transaction","detail":"{read_only:false; response_revision:5377; number_of_response:1; }","duration":"327.477585ms","start":"2026-03-26T18:04:02.261814Z","end":"2026-03-26T18:04:02.589291Z","steps":["trace[652796716] 'process raft request' (duration: 288.542137ms)","trace[652796716] 'compare' (duration: 38.812294ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:04:02.589526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:04:02.261796Z","time spent":"327.629169ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:04:02.589764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.222435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:04:02.589924Z","caller":"traceutil/trace.go:171","msg":"trace[1350902586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5377; }","duration":"286.437321ms","start":"2026-03-26T18:04:02.303467Z","end":"2026-03-26T18:04:02.589904Z","steps":["trace[1350902586] 'agreement among raft nodes before linearized reading' (duration: 286.192454ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:04:02.589307Z","caller":"traceutil/trace.go:171","msg":"trace[486119385] linearizableReadLoop","detail":"{readStateIndex:5575; appliedIndex:5574; }","duration":"208.841224ms","start":"2026-03-26T18:04:02.380439Z","end":"2026-03-26T18:04:02.589281Z","steps":["trace[486119385] 'read index received' (duration: 169.840605ms)","trace[486119385] 'applied index is now lower than readState.Index' (duration: 38.999439ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:04:02.590372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.525166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-26T18:04:02.590445Z","caller":"traceutil/trace.go:171","msg":"trace[1303228797] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:5377; }","duration":"208.654679ms","start":"2026-03-26T18:04:02.381766Z","end":"2026-03-26T18:04:02.59042Z","steps":["trace[1303228797] 'agreement among raft nodes before linearized reading' (duration: 208.535576ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:04:15.09234Z","caller":"traceutil/trace.go:171","msg":"trace[593439905] linearizableReadLoop","detail":"{readStateIndex:5750; appliedIndex:5749; }","duration":"189.700675ms","start":"2026-03-26T18:04:14.902616Z","end":"2026-03-26T18:04:15.092317Z","steps":["trace[593439905] 'read index received' (duration: 127.525758ms)","trace[593439905] 'applied index is now lower than readState.Index' (duration: 62.173277ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:04:15.092606Z","caller":"traceutil/trace.go:171","msg":"trace[482903771] transaction","detail":"{read_only:false; response_revision:5548; number_of_response:1; }","duration":"190.123855ms","start":"2026-03-26T18:04:14.902451Z","end":"2026-03-26T18:04:15.092575Z","steps":["trace[482903771] 'process raft request' (duration: 127.672361ms)","trace[482903771] 'compare' (duration: 61.9227ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:04:15.09268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.201256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T18:04:15.09276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.126186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-03-26T18:04:15.092844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.102434ms","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-03-26T18:04:15.092824Z","caller":"traceutil/trace.go:171","msg":"trace[1927926900] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:5548; }","duration":"116.388101ms","start":"2026-03-26T18:04:14.97641Z","end":"2026-03-26T18:04:15.092798Z","steps":["trace[1927926900] 'agreement among raft nodes before linearized reading' (duration: 116.159265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:04:15.092721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.63457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:04:15.092927Z","caller":"traceutil/trace.go:171","msg":"trace[2037457525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5548; }","duration":"190.234569ms","start":"2026-03-26T18:04:14.902653Z","end":"2026-03-26T18:04:15.092888Z","steps":["trace[2037457525] 'agreement among raft nodes before linearized reading' (duration: 189.873549ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:04:15.092966Z","caller":"traceutil/trace.go:171","msg":"trace[1621094552] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:5548; }","duration":"133.911278ms","start":"2026-03-26T18:04:14.959015Z","end":"2026-03-26T18:04:15.092926Z","steps":["trace[1621094552] 'agreement among raft nodes before linearized reading' (duration: 133.61602ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:04:15.092841Z","caller":"traceutil/trace.go:171","msg":"trace[807901359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5548; }","duration":"190.204088ms","start":"2026-03-26T18:04:14.902613Z","end":"2026-03-26T18:04:15.092817Z","steps":["trace[807901359] 'agreement among raft nodes before linearized reading' (duration: 189.90352ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:05:15.540185Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2975} {"level":"info","ts":"2026-03-26T18:05:15.610223Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2975,"took":"68.643869ms","hash":3725682456,"current-db-size-bytes":58249216,"current-db-size":"58 MB","current-db-size-in-use-bytes":33714176,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-03-26T18:05:15.610296Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3725682456,"revision":2975,"compact-revision":-1} {"level":"warn","ts":"2026-03-26T18:10:01.208058Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802819152346398079,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T18:10:01.290349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.63283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:10:01.290434Z","caller":"traceutil/trace.go:171","msg":"trace[8523889] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:8179; }","duration":"747.763603ms","start":"2026-03-26T18:10:00.542653Z","end":"2026-03-26T18:10:01.290416Z","steps":["trace[8523889] 'count revisions from in-memory index tree' (duration: 747.549207ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:01.290476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:00.542632Z","time spent":"747.833205ms","remote":"127.0.0.1:45250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"warn","ts":"2026-03-26T18:10:01.290589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.632376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:10:01.290679Z","caller":"traceutil/trace.go:171","msg":"trace[1299791399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8179; }","duration":"984.76564ms","start":"2026-03-26T18:10:00.305891Z","end":"2026-03-26T18:10:01.290657Z","steps":["trace[1299791399] 'range keys from in-memory index tree' (duration: 984.543704ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:01.290735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:00.30587Z","time spent":"984.855822ms","remote":"127.0.0.1:39466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T18:10:01.29081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055951045s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-26T18:10:01.290886Z","caller":"traceutil/trace.go:171","msg":"trace[1507374818] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:8179; }","duration":"1.056031507s","start":"2026-03-26T18:10:00.234833Z","end":"2026-03-26T18:10:01.290865Z","steps":["trace[1507374818] 'count revisions from in-memory index tree' (duration: 1.055916864s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:01.29097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:00.234828Z","time spent":"1.056098169s","remote":"127.0.0.1:39560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":13,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-03-26T18:10:01.291017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.517718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T18:10:01.290694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058137993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-n8vsx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-26T18:10:01.291134Z","caller":"traceutil/trace.go:171","msg":"trace[957796483] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:8179; }","duration":"957.685802ms","start":"2026-03-26T18:10:00.33342Z","end":"2026-03-26T18:10:01.291106Z","steps":["trace[957796483] 'count revisions from in-memory index tree' (duration: 957.375135ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:01.291153Z","caller":"traceutil/trace.go:171","msg":"trace[868662715] range","detail":"{range_begin:/registry/pods/openstack/keepalived-n8vsx; range_end:; response_count:1; response_revision:8179; }","duration":"1.058630786s","start":"2026-03-26T18:10:00.232489Z","end":"2026-03-26T18:10:01.29112Z","steps":["trace[868662715] 'range keys from in-memory index tree' (duration: 1.057936898s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:01.291208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:00.333391Z","time spent":"957.796086ms","remote":"127.0.0.1:47980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-03-26T18:10:01.29125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:00.232473Z","time spent":"1.05874808s","remote":"127.0.0.1:39620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-n8vsx\" "} {"level":"warn","ts":"2026-03-26T18:10:01.291232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.973998ms","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-03-26T18:10:01.291355Z","caller":"traceutil/trace.go:171","msg":"trace[479179908] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8179; }","duration":"191.112142ms","start":"2026-03-26T18:10:01.100226Z","end":"2026-03-26T18:10:01.291338Z","steps":["trace[479179908] 'range keys from in-memory index tree' (duration: 190.957368ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:01.370096Z","caller":"traceutil/trace.go:171","msg":"trace[852864493] linearizableReadLoop","detail":"{readStateIndex:8464; appliedIndex:8463; }","duration":"662.745652ms","start":"2026-03-26T18:10:00.707333Z","end":"2026-03-26T18:10:01.370079Z","steps":["trace[852864493] 'read index received' (duration: 662.637779ms)","trace[852864493] 'applied index is now lower than readState.Index' (duration: 107.103µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:10:01.370152Z","caller":"traceutil/trace.go:171","msg":"trace[857621669] transaction","detail":"{read_only:false; response_revision:8180; number_of_response:1; }","duration":"711.617356ms","start":"2026-03-26T18:10:00.658507Z","end":"2026-03-26T18:10:01.370124Z","steps":["trace[857621669] 'process raft request' (duration: 711.458242ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:01.37021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.862115ms","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-03-26T18:10:01.370238Z","caller":"traceutil/trace.go:171","msg":"trace[1367907951] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:8180; }","duration":"662.958078ms","start":"2026-03-26T18:10:00.707272Z","end":"2026-03-26T18:10:01.37023Z","steps":["trace[1367907951] 'agreement among raft nodes before linearized reading' (duration: 662.889046ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:01.370269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:00.707243Z","time spent":"663.017289ms","remote":"127.0.0.1:39618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-03-26T18:10:01.37028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:00.658485Z","time spent":"711.726189ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:10:01.380335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.146335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:10:01.380436Z","caller":"traceutil/trace.go:171","msg":"trace[374865326] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8181; }","duration":"523.263338ms","start":"2026-03-26T18:10:00.857123Z","end":"2026-03-26T18:10:01.380386Z","steps":["trace[374865326] 'agreement among raft nodes before linearized reading' (duration: 523.139794ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:01.380429Z","caller":"traceutil/trace.go:171","msg":"trace[1478429889] transaction","detail":"{read_only:false; response_revision:8181; number_of_response:1; }","duration":"136.658759ms","start":"2026-03-26T18:10:01.243738Z","end":"2026-03-26T18:10:01.380397Z","steps":["trace[1478429889] 'process raft request' (duration: 136.357931ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:01.380517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:00.857101Z","time spent":"523.382551ms","remote":"127.0.0.1:47906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-03-26T18:10:01.380545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.541434ms","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-03-26T18:10:01.380647Z","caller":"traceutil/trace.go:171","msg":"trace[817301518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8181; }","duration":"438.699308ms","start":"2026-03-26T18:10:00.941924Z","end":"2026-03-26T18:10:01.380624Z","steps":["trace[817301518] 'agreement among raft nodes before linearized reading' (duration: 438.450321ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:01.380719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:00.941904Z","time spent":"438.79403ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T18:10:01.380341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.034842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T18:10:01.380801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.310243ms","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-03-26T18:10:01.38087Z","caller":"traceutil/trace.go:171","msg":"trace[1944254110] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:8181; }","duration":"139.583376ms","start":"2026-03-26T18:10:01.241252Z","end":"2026-03-26T18:10:01.380835Z","steps":["trace[1944254110] 'agreement among raft nodes before linearized reading' (duration: 139.031072ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:01.38094Z","caller":"traceutil/trace.go:171","msg":"trace[2011295609] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8181; }","duration":"445.492868ms","start":"2026-03-26T18:10:00.935401Z","end":"2026-03-26T18:10:01.380894Z","steps":["trace[2011295609] 'agreement among raft nodes before linearized reading' (duration: 445.047586ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:01.381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:00.935382Z","time spent":"445.608411ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-26T18:10:01.381055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.528028ms","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-03-26T18:10:01.381821Z","caller":"traceutil/trace.go:171","msg":"trace[469188914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8181; }","duration":"432.320939ms","start":"2026-03-26T18:10:00.94947Z","end":"2026-03-26T18:10:01.381791Z","steps":["trace[469188914] 'agreement among raft nodes before linearized reading' (duration: 431.397344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:01.381957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:00.949459Z","time spent":"432.423311ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-26T18:10:12.039304Z","caller":"traceutil/trace.go:171","msg":"trace[576411963] transaction","detail":"{read_only:false; response_revision:8238; number_of_response:1; }","duration":"584.980102ms","start":"2026-03-26T18:10:11.454269Z","end":"2026-03-26T18:10:12.039249Z","steps":["trace[576411963] 'process raft request' (duration: 584.618112ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:12.039342Z","caller":"traceutil/trace.go:171","msg":"trace[624463490] linearizableReadLoop","detail":"{readStateIndex:8526; appliedIndex:8523; }","duration":"583.919363ms","start":"2026-03-26T18:10:11.455392Z","end":"2026-03-26T18:10:12.039312Z","steps":["trace[624463490] 'read index received' (duration: 4.848678ms)","trace[624463490] 'applied index is now lower than readState.Index' (duration: 579.069485ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:10:12.039385Z","caller":"traceutil/trace.go:171","msg":"trace[1471025612] transaction","detail":"{read_only:false; response_revision:8239; number_of_response:1; }","duration":"584.814647ms","start":"2026-03-26T18:10:11.454525Z","end":"2026-03-26T18:10:12.03934Z","steps":["trace[1471025612] 'process raft request' (duration: 584.596281ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:12.039661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.242803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-n8vsx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-26T18:10:12.039432Z","caller":"traceutil/trace.go:171","msg":"trace[1070824627] transaction","detail":"{read_only:false; response_revision:8240; number_of_response:1; }","duration":"583.391829ms","start":"2026-03-26T18:10:11.455992Z","end":"2026-03-26T18:10:12.039383Z","steps":["trace[1070824627] 'process raft request' (duration: 583.204714ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:12.039716Z","caller":"traceutil/trace.go:171","msg":"trace[1942829377] range","detail":"{range_begin:/registry/pods/openstack/keepalived-n8vsx; range_end:; response_count:1; response_revision:8240; }","duration":"584.348095ms","start":"2026-03-26T18:10:11.455354Z","end":"2026-03-26T18:10:12.039702Z","steps":["trace[1942829377] 'agreement among raft nodes before linearized reading' (duration: 584.084868ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:12.039707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:11.454499Z","time spent":"585.128166ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:10:12.039748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:11.455339Z","time spent":"584.400667ms","remote":"127.0.0.1:39620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-n8vsx\" "} {"level":"warn","ts":"2026-03-26T18:10:12.039746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:11.455976Z","time spent":"583.735259ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:10:12.039489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:11.454244Z","time spent":"585.152077ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:10:12.048264Z","caller":"traceutil/trace.go:171","msg":"trace[1072747264] transaction","detail":"{read_only:false; response_revision:8241; number_of_response:1; }","duration":"146.181572ms","start":"2026-03-26T18:10:11.902051Z","end":"2026-03-26T18:10:12.048232Z","steps":["trace[1072747264] 'process raft request' (duration: 146.011087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:12.04831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.814227ms","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-03-26T18:10:12.048359Z","caller":"traceutil/trace.go:171","msg":"trace[1894721061] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8241; }","duration":"354.905889ms","start":"2026-03-26T18:10:11.693439Z","end":"2026-03-26T18:10:12.048345Z","steps":["trace[1894721061] 'agreement among raft nodes before linearized reading' (duration: 354.767065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:12.048392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:11.69341Z","time spent":"354.974061ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-26T18:10:14.152564Z","caller":"traceutil/trace.go:171","msg":"trace[720261526] transaction","detail":"{read_only:false; response_revision:8247; number_of_response:1; }","duration":"105.800232ms","start":"2026-03-26T18:10:14.046671Z","end":"2026-03-26T18:10:14.152472Z","steps":["trace[720261526] 'process raft request' (duration: 105.592187ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:14.201648Z","caller":"traceutil/trace.go:171","msg":"trace[392352347] transaction","detail":"{read_only:false; response_revision:8248; number_of_response:1; }","duration":"154.223715ms","start":"2026-03-26T18:10:14.047398Z","end":"2026-03-26T18:10:14.201622Z","steps":["trace[392352347] 'process raft request' (duration: 119.397513ms)","trace[392352347] 'compare' (duration: 34.638567ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:10:14.201821Z","caller":"traceutil/trace.go:171","msg":"trace[371608001] transaction","detail":"{read_only:false; response_revision:8249; number_of_response:1; }","duration":"154.147443ms","start":"2026-03-26T18:10:14.047657Z","end":"2026-03-26T18:10:14.201804Z","steps":["trace[371608001] 'process raft request' (duration: 153.909407ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:14.201899Z","caller":"traceutil/trace.go:171","msg":"trace[1081084975] linearizableReadLoop","detail":"{readStateIndex:8538; appliedIndex:8534; }","duration":"146.658934ms","start":"2026-03-26T18:10:14.055224Z","end":"2026-03-26T18:10:14.201883Z","steps":["trace[1081084975] 'read index received' (duration: 96.968628ms)","trace[1081084975] 'applied index is now lower than readState.Index' (duration: 49.689306ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:10:14.201982Z","caller":"traceutil/trace.go:171","msg":"trace[280762130] transaction","detail":"{read_only:false; response_revision:8250; number_of_response:1; }","duration":"147.688551ms","start":"2026-03-26T18:10:14.054283Z","end":"2026-03-26T18:10:14.201971Z","steps":["trace[280762130] 'process raft request' (duration: 147.476396ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:14.202196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.957052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-n8vsx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-26T18:10:14.202244Z","caller":"traceutil/trace.go:171","msg":"trace[2030901864] range","detail":"{range_begin:/registry/pods/openstack/keepalived-n8vsx; range_end:; response_count:1; response_revision:8250; }","duration":"147.039884ms","start":"2026-03-26T18:10:14.055192Z","end":"2026-03-26T18:10:14.202232Z","steps":["trace[2030901864] 'agreement among raft nodes before linearized reading' (duration: 146.814988ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:14.25161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.469609ms","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-03-26T18:10:14.251685Z","caller":"traceutil/trace.go:171","msg":"trace[1895590029] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8251; }","duration":"143.587112ms","start":"2026-03-26T18:10:14.108082Z","end":"2026-03-26T18:10:14.251669Z","steps":["trace[1895590029] 'agreement among raft nodes before linearized reading' (duration: 143.410167ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:15.564054Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6059} {"level":"info","ts":"2026-03-26T18:10:15.671211Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6059,"took":"104.993001ms","hash":763176999,"current-db-size-bytes":58249216,"current-db-size":"58 MB","current-db-size-in-use-bytes":23314432,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-26T18:10:15.671279Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":763176999,"revision":6059,"compact-revision":2975} {"level":"info","ts":"2026-03-26T18:10:39.057204Z","caller":"traceutil/trace.go:171","msg":"trace[1763299167] transaction","detail":"{read_only:false; response_revision:8438; number_of_response:1; }","duration":"153.057094ms","start":"2026-03-26T18:10:38.904096Z","end":"2026-03-26T18:10:39.057153Z","steps":["trace[1763299167] 'process raft request' (duration: 152.847848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:42.204076Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802819152346398886,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-26T18:10:42.60415Z","caller":"traceutil/trace.go:171","msg":"trace[330748463] linearizableReadLoop","detail":"{readStateIndex:8749; appliedIndex:8748; }","duration":"900.334083ms","start":"2026-03-26T18:10:41.703796Z","end":"2026-03-26T18:10:42.60413Z","steps":["trace[330748463] 'read index received' (duration: 900.142698ms)","trace[330748463] 'applied index is now lower than readState.Index' (duration: 190.465µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:10:42.604315Z","caller":"traceutil/trace.go:171","msg":"trace[918813151] transaction","detail":"{read_only:false; response_revision:8453; number_of_response:1; }","duration":"977.171648ms","start":"2026-03-26T18:10:41.627075Z","end":"2026-03-26T18:10:42.604246Z","steps":["trace[918813151] 'process raft request' (duration: 976.914732ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:42.604386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.551159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T18:10:42.6046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:41.627064Z","time spent":"977.438505ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:10:42.604691Z","caller":"traceutil/trace.go:171","msg":"trace[122569769] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:8453; }","duration":"900.859037ms","start":"2026-03-26T18:10:41.703789Z","end":"2026-03-26T18:10:42.604648Z","steps":["trace[122569769] 'agreement among raft nodes before linearized reading' (duration: 900.502838ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:42.604762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:41.703733Z","time spent":"901.013092ms","remote":"127.0.0.1:48048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-03-26T18:10:42.680173Z","caller":"traceutil/trace.go:171","msg":"trace[1358123125] transaction","detail":"{read_only:false; response_revision:8454; number_of_response:1; }","duration":"392.164956ms","start":"2026-03-26T18:10:42.287993Z","end":"2026-03-26T18:10:42.680158Z","steps":["trace[1358123125] 'process raft request' (duration: 390.524712ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:42.680327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:42.287967Z","time spent":"392.297979ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:10:42.680397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.446195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:10:42.680452Z","caller":"traceutil/trace.go:171","msg":"trace[509191008] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:8455; }","duration":"821.537327ms","start":"2026-03-26T18:10:41.858901Z","end":"2026-03-26T18:10:42.680438Z","steps":["trace[509191008] 'agreement among raft nodes before linearized reading' (duration: 821.432944ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:42.68075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.752774ms","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-03-26T18:10:42.680834Z","caller":"traceutil/trace.go:171","msg":"trace[992115902] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8455; }","duration":"269.904398ms","start":"2026-03-26T18:10:42.410918Z","end":"2026-03-26T18:10:42.680823Z","steps":["trace[992115902] 'agreement among raft nodes before linearized reading' (duration: 269.743604ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:42.68098Z","caller":"traceutil/trace.go:171","msg":"trace[482227669] transaction","detail":"{read_only:false; response_revision:8455; number_of_response:1; }","duration":"345.594542ms","start":"2026-03-26T18:10:42.335376Z","end":"2026-03-26T18:10:42.680971Z","steps":["trace[482227669] 'process raft request' (duration: 344.74593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:42.681082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:42.335355Z","time spent":"345.687465ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:10:42.681317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.112767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-n8vsx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-26T18:10:42.681392Z","caller":"traceutil/trace.go:171","msg":"trace[1213786395] range","detail":"{range_begin:/registry/pods/openstack/keepalived-n8vsx; range_end:; response_count:1; response_revision:8455; }","duration":"266.20845ms","start":"2026-03-26T18:10:42.415174Z","end":"2026-03-26T18:10:42.681383Z","steps":["trace[1213786395] 'agreement among raft nodes before linearized reading' (duration: 266.065706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:42.681441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.35847ms","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-03-26T18:10:42.681497Z","caller":"traceutil/trace.go:171","msg":"trace[975051578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8455; }","duration":"355.425413ms","start":"2026-03-26T18:10:42.326055Z","end":"2026-03-26T18:10:42.68148Z","steps":["trace[975051578] 'agreement among raft nodes before linearized reading' (duration: 355.32643ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:42.681542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:42.326045Z","time spent":"355.489564ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-26T18:10:42.681336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.210213ms","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-03-26T18:10:42.681747Z","caller":"traceutil/trace.go:171","msg":"trace[272622862] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8455; }","duration":"359.652244ms","start":"2026-03-26T18:10:42.322083Z","end":"2026-03-26T18:10:42.681735Z","steps":["trace[272622862] 'agreement among raft nodes before linearized reading' (duration: 359.078639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:42.681794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.395675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:10:42.681832Z","caller":"traceutil/trace.go:171","msg":"trace[1328662395] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:8455; }","duration":"451.470987ms","start":"2026-03-26T18:10:42.23035Z","end":"2026-03-26T18:10:42.681821Z","steps":["trace[1328662395] 'agreement among raft nodes before linearized reading' (duration: 451.413485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:42.681866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:42.230331Z","time spent":"451.527768ms","remote":"127.0.0.1:35884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-03-26T18:10:42.681918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:42.322022Z","time spent":"359.784768ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-26T18:10:42.681695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.862587ms","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-03-26T18:10:42.682882Z","caller":"traceutil/trace.go:171","msg":"trace[1919798805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8455; }","duration":"396.077139ms","start":"2026-03-26T18:10:42.286787Z","end":"2026-03-26T18:10:42.682864Z","steps":["trace[1919798805] 'agreement among raft nodes before linearized reading' (duration: 394.849467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:42.683049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:42.286766Z","time spent":"396.267965ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-26T18:10:42.683122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:41.858883Z","time spent":"821.598418ms","remote":"127.0.0.1:46930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"info","ts":"2026-03-26T18:10:45.838027Z","caller":"traceutil/trace.go:171","msg":"trace[1873348456] transaction","detail":"{read_only:false; response_revision:8470; number_of_response:1; }","duration":"278.2896ms","start":"2026-03-26T18:10:45.559718Z","end":"2026-03-26T18:10:45.838007Z","steps":["trace[1873348456] 'process raft request' (duration: 277.694494ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:46.022404Z","caller":"traceutil/trace.go:171","msg":"trace[1922200683] linearizableReadLoop","detail":"{readStateIndex:8768; appliedIndex:8766; }","duration":"379.087399ms","start":"2026-03-26T18:10:45.643293Z","end":"2026-03-26T18:10:46.022381Z","steps":["trace[1922200683] 'read index received' (duration: 194.150142ms)","trace[1922200683] 'applied index is now lower than readState.Index' (duration: 184.936217ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:10:46.02266Z","caller":"traceutil/trace.go:171","msg":"trace[1456202284] transaction","detail":"{read_only:false; response_revision:8471; number_of_response:1; }","duration":"425.337134ms","start":"2026-03-26T18:10:45.59729Z","end":"2026-03-26T18:10:46.022627Z","steps":["trace[1456202284] 'process raft request' (duration: 389.202987ms)","trace[1456202284] 'compare' (duration: 35.636264ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:10:46.022677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.371147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19384"} {"level":"info","ts":"2026-03-26T18:10:46.022761Z","caller":"traceutil/trace.go:171","msg":"trace[873456937] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8471; }","duration":"379.48531ms","start":"2026-03-26T18:10:45.643253Z","end":"2026-03-26T18:10:46.022738Z","steps":["trace[873456937] 'agreement among raft nodes before linearized reading' (duration: 379.296335ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:46.022792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.626172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-b5858d8d-zkjsg.18a07689b3300b26\" ","response":"range_response_count:1 size:1129"} {"level":"warn","ts":"2026-03-26T18:10:46.02283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:45.643241Z","time spent":"379.571772ms","remote":"127.0.0.1:39618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19408,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-26T18:10:46.022831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:45.597267Z","time spent":"425.470428ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:10:46.02285Z","caller":"traceutil/trace.go:171","msg":"trace[2128507702] range","detail":"{range_begin:/registry/events/openstack/barbican-api-b5858d8d-zkjsg.18a07689b3300b26; range_end:; response_count:1; response_revision:8471; }","duration":"101.713543ms","start":"2026-03-26T18:10:45.921122Z","end":"2026-03-26T18:10:46.022835Z","steps":["trace[2128507702] 'agreement among raft nodes before linearized reading' (duration: 101.56689ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:55.046769Z","caller":"traceutil/trace.go:171","msg":"trace[1038843653] linearizableReadLoop","detail":"{readStateIndex:8860; appliedIndex:8859; }","duration":"391.892539ms","start":"2026-03-26T18:10:54.654852Z","end":"2026-03-26T18:10:55.046745Z","steps":["trace[1038843653] 'read index received' (duration: 326.939968ms)","trace[1038843653] 'applied index is now lower than readState.Index' (duration: 64.95156ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:10:55.046805Z","caller":"traceutil/trace.go:171","msg":"trace[444110993] transaction","detail":"{read_only:false; response_revision:8559; number_of_response:1; }","duration":"483.492575ms","start":"2026-03-26T18:10:54.563272Z","end":"2026-03-26T18:10:55.046765Z","steps":["trace[444110993] 'process raft request' (duration: 418.394631ms)","trace[444110993] 'compare' (duration: 64.726804ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:10:55.047014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.143225ms","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-03-26T18:10:55.047055Z","caller":"traceutil/trace.go:171","msg":"trace[17625193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8559; }","duration":"392.242067ms","start":"2026-03-26T18:10:54.654803Z","end":"2026-03-26T18:10:55.047045Z","steps":["trace[17625193] 'agreement among raft nodes before linearized reading' (duration: 392.057353ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:55.047039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:54.563256Z","time spent":"483.636108ms","remote":"127.0.0.1:39620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4638,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:10:55.047087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:10:54.65478Z","time spent":"392.300079ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-26T18:10:55.061869Z","caller":"traceutil/trace.go:171","msg":"trace[1122938071] transaction","detail":"{read_only:false; response_revision:8560; number_of_response:1; }","duration":"283.57991ms","start":"2026-03-26T18:10:54.77827Z","end":"2026-03-26T18:10:55.06185Z","steps":["trace[1122938071] 'process raft request' (duration: 283.454977ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:55.062156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.359181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-n8vsx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-26T18:10:55.062186Z","caller":"traceutil/trace.go:171","msg":"trace[733321750] range","detail":"{range_begin:/registry/pods/openstack/keepalived-n8vsx; range_end:; response_count:1; response_revision:8561; }","duration":"193.427632ms","start":"2026-03-26T18:10:54.868751Z","end":"2026-03-26T18:10:55.062178Z","steps":["trace[733321750] 'agreement among raft nodes before linearized reading' (duration: 193.295288ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:55.062213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.28027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-03-26T18:10:55.062236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.947345ms","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-03-26T18:10:55.062291Z","caller":"traceutil/trace.go:171","msg":"trace[1856132650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8561; }","duration":"217.033858ms","start":"2026-03-26T18:10:54.845244Z","end":"2026-03-26T18:10:55.062277Z","steps":["trace[1856132650] 'agreement among raft nodes before linearized reading' (duration: 216.899204ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:55.062296Z","caller":"traceutil/trace.go:171","msg":"trace[2038683492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8561; }","duration":"275.384903ms","start":"2026-03-26T18:10:54.786885Z","end":"2026-03-26T18:10:55.06227Z","steps":["trace[2038683492] 'agreement among raft nodes before linearized reading' (duration: 275.124166ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:55.062448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.912161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-26T18:10:55.062446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.25797ms","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-03-26T18:10:55.062468Z","caller":"traceutil/trace.go:171","msg":"trace[229969371] transaction","detail":"{read_only:false; response_revision:8561; number_of_response:1; }","duration":"283.369194ms","start":"2026-03-26T18:10:54.779075Z","end":"2026-03-26T18:10:55.062444Z","steps":["trace[229969371] 'process raft request' (duration: 282.742787ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:55.062487Z","caller":"traceutil/trace.go:171","msg":"trace[492121030] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8561; }","duration":"218.007843ms","start":"2026-03-26T18:10:54.844469Z","end":"2026-03-26T18:10:55.062477Z","steps":["trace[492121030] 'agreement among raft nodes before linearized reading' (duration: 217.595262ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:55.062497Z","caller":"traceutil/trace.go:171","msg":"trace[256134273] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8561; }","duration":"221.346772ms","start":"2026-03-26T18:10:54.841137Z","end":"2026-03-26T18:10:55.062484Z","steps":["trace[256134273] 'agreement among raft nodes before linearized reading' (duration: 220.988443ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:55.223703Z","caller":"traceutil/trace.go:171","msg":"trace[948376538] transaction","detail":"{read_only:false; response_revision:8563; number_of_response:1; }","duration":"157.45727ms","start":"2026-03-26T18:10:55.066221Z","end":"2026-03-26T18:10:55.223679Z","steps":["trace[948376538] 'process raft request' (duration: 157.162272ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:55.223714Z","caller":"traceutil/trace.go:171","msg":"trace[1113436732] transaction","detail":"{read_only:false; response_revision:8564; number_of_response:1; }","duration":"157.285055ms","start":"2026-03-26T18:10:55.066403Z","end":"2026-03-26T18:10:55.223688Z","steps":["trace[1113436732] 'process raft request' (duration: 157.154272ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:55.223701Z","caller":"traceutil/trace.go:171","msg":"trace[1890525314] transaction","detail":"{read_only:false; response_revision:8565; number_of_response:1; }","duration":"157.267805ms","start":"2026-03-26T18:10:55.06641Z","end":"2026-03-26T18:10:55.223678Z","steps":["trace[1890525314] 'process raft request' (duration: 157.183833ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:55.224267Z","caller":"traceutil/trace.go:171","msg":"trace[2088883414] transaction","detail":"{read_only:false; response_revision:8566; number_of_response:1; }","duration":"157.266804ms","start":"2026-03-26T18:10:55.066988Z","end":"2026-03-26T18:10:55.224255Z","steps":["trace[2088883414] 'process raft request' (duration: 156.647138ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:55.402889Z","caller":"traceutil/trace.go:171","msg":"trace[1070426715] linearizableReadLoop","detail":"{readStateIndex:8868; appliedIndex:8863; }","duration":"180.699405ms","start":"2026-03-26T18:10:55.222166Z","end":"2026-03-26T18:10:55.402866Z","steps":["trace[1070426715] 'read index received' (duration: 1.318185ms)","trace[1070426715] 'applied index is now lower than readState.Index' (duration: 179.38031ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:10:55.403041Z","caller":"traceutil/trace.go:171","msg":"trace[1036941449] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"205.313607ms","start":"2026-03-26T18:10:55.197716Z","end":"2026-03-26T18:10:55.40303Z","steps":["trace[1036941449] 'process raft request' (duration: 204.993658ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:10:55.403219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.027954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:10:55.403263Z","caller":"traceutil/trace.go:171","msg":"trace[2000298585] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:8567; }","duration":"181.130867ms","start":"2026-03-26T18:10:55.22212Z","end":"2026-03-26T18:10:55.403251Z","steps":["trace[2000298585] 'agreement among raft nodes before linearized reading' (duration: 181.042544ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:56.125303Z","caller":"traceutil/trace.go:171","msg":"trace[1533958042] transaction","detail":"{read_only:false; response_revision:8570; number_of_response:1; }","duration":"255.074845ms","start":"2026-03-26T18:10:55.870205Z","end":"2026-03-26T18:10:56.12528Z","steps":["trace[1533958042] 'process raft request' (duration: 254.944672ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:56.125336Z","caller":"traceutil/trace.go:171","msg":"trace[344879768] linearizableReadLoop","detail":"{readStateIndex:8871; appliedIndex:8871; }","duration":"254.577302ms","start":"2026-03-26T18:10:55.870734Z","end":"2026-03-26T18:10:56.125311Z","steps":["trace[344879768] 'read index received' (duration: 254.565402ms)","trace[344879768] 'applied index is now lower than readState.Index' (duration: 9.42µs)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:10:56.12574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.983193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:111900"} {"level":"info","ts":"2026-03-26T18:10:56.126377Z","caller":"traceutil/trace.go:171","msg":"trace[1172571475] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:8570; }","duration":"255.65133ms","start":"2026-03-26T18:10:55.870713Z","end":"2026-03-26T18:10:56.126364Z","steps":["trace[1172571475] 'agreement among raft nodes before linearized reading' (duration: 254.713975ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:56.21572Z","caller":"traceutil/trace.go:171","msg":"trace[1389611620] transaction","detail":"{read_only:false; response_revision:8571; number_of_response:1; }","duration":"153.964638ms","start":"2026-03-26T18:10:56.061733Z","end":"2026-03-26T18:10:56.215698Z","steps":["trace[1389611620] 'process raft request' (duration: 120.249015ms)","trace[1389611620] 'compare' (duration: 33.569209ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:10:56.215873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.267266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-b5858d8d-zkjsg.18a07689b3300b26\" ","response":"range_response_count:1 size:1129"} {"level":"info","ts":"2026-03-26T18:10:56.215952Z","caller":"traceutil/trace.go:171","msg":"trace[1646570277] range","detail":"{range_begin:/registry/events/openstack/barbican-api-b5858d8d-zkjsg.18a07689b3300b26; range_end:; response_count:1; response_revision:8571; }","duration":"296.383609ms","start":"2026-03-26T18:10:55.919549Z","end":"2026-03-26T18:10:56.215933Z","steps":["trace[1646570277] 'agreement among raft nodes before linearized reading' (duration: 296.152163ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:56.280251Z","caller":"traceutil/trace.go:171","msg":"trace[1936051088] transaction","detail":"{read_only:false; response_revision:8572; number_of_response:1; }","duration":"140.422348ms","start":"2026-03-26T18:10:56.1398Z","end":"2026-03-26T18:10:56.280222Z","steps":["trace[1936051088] 'process raft request' (duration: 140.171492ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:10:56.502318Z","caller":"traceutil/trace.go:171","msg":"trace[1658196496] linearizableReadLoop","detail":"{readStateIndex:8874; appliedIndex:8873; }","duration":"197.458209ms","start":"2026-03-26T18:10:56.304829Z","end":"2026-03-26T18:10:56.502287Z","steps":["trace[1658196496] 'read index received' (duration: 175.898208ms)","trace[1658196496] 'applied index is now lower than readState.Index' (duration: 21.558741ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:10:56.502387Z","caller":"traceutil/trace.go:171","msg":"trace[2034432306] transaction","detail":"{read_only:false; response_revision:8573; number_of_response:1; }","duration":"284.35144ms","start":"2026-03-26T18:10:56.21796Z","end":"2026-03-26T18:10:56.502312Z","steps":["trace[2034432306] 'process raft request' (duration: 262.885822ms)","trace[2034432306] 'compare' (duration: 21.218141ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:10:56.50245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.607513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:10:56.502484Z","caller":"traceutil/trace.go:171","msg":"trace[1113513698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8573; }","duration":"197.705176ms","start":"2026-03-26T18:10:56.304768Z","end":"2026-03-26T18:10:56.502473Z","steps":["trace[1113513698] 'agreement among raft nodes before linearized reading' (duration: 197.623054ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:11:44.724979Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000245c.wal"} {"level":"info","ts":"2026-03-26T18:12:01.52246Z","caller":"traceutil/trace.go:171","msg":"trace[1491793564] linearizableReadLoop","detail":"{readStateIndex:9573; appliedIndex:9571; }","duration":"217.300044ms","start":"2026-03-26T18:12:01.305131Z","end":"2026-03-26T18:12:01.522431Z","steps":["trace[1491793564] 'read index received' (duration: 209.13716ms)","trace[1491793564] 'applied index is now lower than readState.Index' (duration: 8.161294ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:12:01.52263Z","caller":"traceutil/trace.go:171","msg":"trace[737621995] transaction","detail":"{read_only:false; response_revision:9252; number_of_response:1; }","duration":"232.530384ms","start":"2026-03-26T18:12:01.290086Z","end":"2026-03-26T18:12:01.522617Z","steps":["trace[737621995] 'process raft request' (duration: 232.260547ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:12:01.522609Z","caller":"traceutil/trace.go:171","msg":"trace[1628494170] transaction","detail":"{read_only:false; response_revision:9251; number_of_response:1; }","duration":"237.673379ms","start":"2026-03-26T18:12:01.284913Z","end":"2026-03-26T18:12:01.522587Z","steps":["trace[1628494170] 'process raft request' (duration: 229.278149ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:12:01.523061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.90325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:12:01.52313Z","caller":"traceutil/trace.go:171","msg":"trace[1375757876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9252; }","duration":"218.012873ms","start":"2026-03-26T18:12:01.305095Z","end":"2026-03-26T18:12:01.523108Z","steps":["trace[1375757876] 'agreement among raft nodes before linearized reading' (duration: 217.688905ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:12:14.526967Z","caller":"traceutil/trace.go:171","msg":"trace[1759522372] transaction","detail":"{read_only:false; response_revision:9328; number_of_response:1; }","duration":"142.717265ms","start":"2026-03-26T18:12:14.384195Z","end":"2026-03-26T18:12:14.526913Z","steps":["trace[1759522372] 'process raft request' (duration: 142.51061ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:13:01.972089Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a742f9f5aa7326e5","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-26T18:13:01.978491Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-26T18:13:01.978613Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-26T18:15:15.571339Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8259} {"level":"info","ts":"2026-03-26T18:15:15.658566Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8259,"took":"84.993585ms","hash":1613873335,"current-db-size-bytes":58249216,"current-db-size":"58 MB","current-db-size-in-use-bytes":23465984,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-26T18:15:15.658646Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1613873335,"revision":8259,"compact-revision":6059} {"level":"info","ts":"2026-03-26T18:20:15.580164Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10476} {"level":"info","ts":"2026-03-26T18:20:15.667645Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10476,"took":"84.072798ms","hash":2231459153,"current-db-size-bytes":58249216,"current-db-size":"58 MB","current-db-size-in-use-bytes":20254720,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-03-26T18:20:15.66773Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2231459153,"revision":10476,"compact-revision":8259}