{"level":"warn","ts":"2026-04-20T08:05:09.921456Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T08:05:09.921535Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.163: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.163:2380","--initial-cluster=instance=https://199.19.213.163:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.163:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.163: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-04-20T08:05:09.921732Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T08:05:09.921748Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.163:2380"]} {"level":"info","ts":"2026-04-20T08:05:09.921779Z","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-04-20T08:05:09.923953Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"]} {"level":"info","ts":"2026-04-20T08:05:09.924063Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.163:2380"],"listen-peer-urls":["https://199.19.213.163:2380"],"advertise-client-urls":["https://199.19.213.163:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.163: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-04-20T08:05:09.928907Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.622664ms"} {"level":"info","ts":"2026-04-20T08:05:09.940044Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e7941a908d261618","cluster-id":"5b6cb15db6ea8c10"} {"level":"info","ts":"2026-04-20T08:05:09.940138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T08:05:09.940256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became follower at term 0"} {"level":"info","ts":"2026-04-20T08:05:09.940273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e7941a908d261618 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T08:05:09.940342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became follower at term 1"} {"level":"info","ts":"2026-04-20T08:05:09.940397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=(16686991727460226584)"} {"level":"warn","ts":"2026-04-20T08:05:09.945844Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T08:05:09.947705Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T08:05:09.950449Z","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-04-20T08:05:09.952955Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e7941a908d261618","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T08:05:09.954083Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e7941a908d261618","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T08:05:09.954328Z","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-04-20T08:05:09.95439Z","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-04-20T08:05:09.954457Z","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-04-20T08:05:09.9558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=(16686991727460226584)"} {"level":"info","ts":"2026-04-20T08:05:09.95696Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5b6cb15db6ea8c10","local-member-id":"e7941a908d261618","added-peer-id":"e7941a908d261618","added-peer-peer-urls":["https://199.19.213.163:2380"]} {"level":"info","ts":"2026-04-20T08:05:09.959025Z","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-04-20T08:05:09.959231Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e7941a908d261618","initial-advertise-peer-urls":["https://199.19.213.163:2380"],"listen-peer-urls":["https://199.19.213.163:2380"],"advertise-client-urls":["https://199.19.213.163:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T08:05:09.959276Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T08:05:09.959391Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.163:2380"} {"level":"info","ts":"2026-04-20T08:05:09.959466Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.163:2380"} {"level":"info","ts":"2026-04-20T08:05:10.041023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T08:05:10.041139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T08:05:10.04118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 received MsgPreVoteResp from e7941a908d261618 at term 1"} {"level":"info","ts":"2026-04-20T08:05:10.041232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became candidate at term 2"} {"level":"info","ts":"2026-04-20T08:05:10.041265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 received MsgVoteResp from e7941a908d261618 at term 2"} {"level":"info","ts":"2026-04-20T08:05:10.041314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became leader at term 2"} {"level":"info","ts":"2026-04-20T08:05:10.041358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e7941a908d261618 elected leader e7941a908d261618 at term 2"} {"level":"info","ts":"2026-04-20T08:05:10.042234Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:05:10.043364Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T08:05:10.043452Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5b6cb15db6ea8c10","local-member-id":"e7941a908d261618","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:05:10.043543Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T08:05:10.043571Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:05:10.04368Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:05:10.04394Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T08:05:10.04401Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T08:05:10.047068Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.163:2379"} {"level":"info","ts":"2026-04-20T08:05:10.04338Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e7941a908d261618","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.163:2379]}","request-path":"/0/members/e7941a908d261618/attributes","cluster-id":"5b6cb15db6ea8c10","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T08:05:10.048287Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T08:05:40.833075Z","caller":"traceutil/trace.go:171","msg":"trace[1747034708] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"314.933206ms","start":"2026-04-20T08:05:40.518104Z","end":"2026-04-20T08:05:40.833038Z","steps":["trace[1747034708] 'process raft request' (duration: 314.743021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:40.834227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:40.518081Z","time spent":"315.153811ms","remote":"127.0.0.1:58328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:05:41.040269Z","caller":"traceutil/trace.go:171","msg":"trace[35490895] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"479.329494ms","start":"2026-04-20T08:05:40.560901Z","end":"2026-04-20T08:05:41.040231Z","steps":["trace[35490895] 'process raft request' (duration: 479.181541ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.040451Z","caller":"traceutil/trace.go:171","msg":"trace[1283754634] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"471.119094ms","start":"2026-04-20T08:05:40.569308Z","end":"2026-04-20T08:05:41.040427Z","steps":["trace[1283754634] 'process raft request' (duration: 470.896078ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.040385Z","caller":"traceutil/trace.go:171","msg":"trace[1998551441] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"484.225023ms","start":"2026-04-20T08:05:40.556109Z","end":"2026-04-20T08:05:41.040334Z","steps":["trace[1998551441] 'process raft request' (duration: 483.824144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:41.040486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:40.560868Z","time spent":"479.507018ms","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T08:05:41.040495Z","caller":"traceutil/trace.go:171","msg":"trace[1643338711] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"472.784695ms","start":"2026-04-20T08:05:40.56765Z","end":"2026-04-20T08:05:41.040434Z","steps":["trace[1643338711] 'process raft request' (duration: 472.473917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:41.040545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:40.569298Z","time spent":"471.195945ms","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T08:05:41.04062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:40.567622Z","time spent":"472.939758ms","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T08:05:41.040662Z","caller":"traceutil/trace.go:171","msg":"trace[311408895] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"472.681092ms","start":"2026-04-20T08:05:40.567971Z","end":"2026-04-20T08:05:41.040652Z","steps":["trace[311408895] 'process raft request' (duration: 472.19415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:41.040654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:40.556088Z","time spent":"484.456278ms","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T08:05:41.040707Z","caller":"traceutil/trace.go:171","msg":"trace[272990127] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"470.762905ms","start":"2026-04-20T08:05:40.569937Z","end":"2026-04-20T08:05:41.0407Z","steps":["trace[272990127] 'process raft request' (duration: 470.306124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:41.040705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:40.567959Z","time spent":"472.723693ms","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T08:05:41.040767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:40.569905Z","time spent":"470.822996ms","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T08:05:41.040676Z","caller":"traceutil/trace.go:171","msg":"trace[445253508] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"469.900614ms","start":"2026-04-20T08:05:40.570763Z","end":"2026-04-20T08:05:41.040664Z","steps":["trace[445253508] 'process raft request' (duration: 469.515955ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.04081Z","caller":"traceutil/trace.go:171","msg":"trace[1759519810] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"463.838997ms","start":"2026-04-20T08:05:40.576962Z","end":"2026-04-20T08:05:41.040801Z","steps":["trace[1759519810] 'process raft request' (duration: 463.352765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:41.040841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:40.570751Z","time spent":"470.051548ms","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T08:05:41.040856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:40.576953Z","time spent":"463.879457ms","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T08:05:41.040852Z","caller":"traceutil/trace.go:171","msg":"trace[2046282630] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"458.511967ms","start":"2026-04-20T08:05:40.582306Z","end":"2026-04-20T08:05:41.040818Z","steps":["trace[2046282630] 'process raft request' (duration: 458.054786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:41.041007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:40.58225Z","time spent":"458.67473ms","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T08:05:41.041021Z","caller":"traceutil/trace.go:171","msg":"trace[38024224] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"452.751846ms","start":"2026-04-20T08:05:40.588235Z","end":"2026-04-20T08:05:41.040987Z","steps":["trace[38024224] 'process raft request' (duration: 452.258014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:41.041132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:40.588219Z","time spent":"452.869709ms","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T08:05:41.041223Z","caller":"traceutil/trace.go:171","msg":"trace[206141749] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"442.489796ms","start":"2026-04-20T08:05:40.598719Z","end":"2026-04-20T08:05:41.041209Z","steps":["trace[206141749] 'process raft request' (duration: 441.891732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:41.04129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:40.598691Z","time spent":"442.556687ms","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T08:05:41.041454Z","caller":"traceutil/trace.go:171","msg":"trace[1285011013] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:485; }","duration":"392.883537ms","start":"2026-04-20T08:05:40.648552Z","end":"2026-04-20T08:05:41.041436Z","steps":["trace[1285011013] 'read index received' (duration: 184.335663ms)","trace[1285011013] 'applied index is now lower than readState.Index' (duration: 208.546854ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:05:41.04147Z","caller":"traceutil/trace.go:171","msg":"trace[1689272597] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"303.906458ms","start":"2026-04-20T08:05:40.737552Z","end":"2026-04-20T08:05:41.041459Z","steps":["trace[1689272597] 'process raft request' (duration: 303.679943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:41.04153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:40.737529Z","time spent":"303.978579ms","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T08:05:41.041668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.098342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T08:05:41.041716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.247265ms","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-04-20T08:05:41.041733Z","caller":"traceutil/trace.go:171","msg":"trace[977618859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:482; }","duration":"393.223254ms","start":"2026-04-20T08:05:40.648499Z","end":"2026-04-20T08:05:41.041722Z","steps":["trace[977618859] 'agreement among raft nodes before linearized reading' (duration: 393.063281ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.041766Z","caller":"traceutil/trace.go:171","msg":"trace[767652402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:482; }","duration":"242.322577ms","start":"2026-04-20T08:05:40.799417Z","end":"2026-04-20T08:05:41.04174Z","steps":["trace[767652402] 'agreement among raft nodes before linearized reading' (duration: 242.199484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:41.041799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:40.648472Z","time spent":"393.316367ms","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T08:05:41.23359Z","caller":"traceutil/trace.go:171","msg":"trace[661528003] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"177.447795ms","start":"2026-04-20T08:05:41.056116Z","end":"2026-04-20T08:05:41.233564Z","steps":["trace[661528003] 'process raft request' (duration: 177.377684ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.234185Z","caller":"traceutil/trace.go:171","msg":"trace[486388545] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"179.028532ms","start":"2026-04-20T08:05:41.055136Z","end":"2026-04-20T08:05:41.234164Z","steps":["trace[486388545] 'process raft request' (duration: 137.317855ms)","trace[486388545] 'compare' (duration: 40.884098ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:05:41.234468Z","caller":"traceutil/trace.go:171","msg":"trace[478430516] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"178.272385ms","start":"2026-04-20T08:05:41.056155Z","end":"2026-04-20T08:05:41.234428Z","steps":["trace[478430516] 'process raft request' (duration: 177.378084ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.234597Z","caller":"traceutil/trace.go:171","msg":"trace[865386214] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"177.905666ms","start":"2026-04-20T08:05:41.056639Z","end":"2026-04-20T08:05:41.234545Z","steps":["trace[865386214] 'process raft request' (duration: 177.434054ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.234665Z","caller":"traceutil/trace.go:171","msg":"trace[1079382456] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"175.278702ms","start":"2026-04-20T08:05:41.059375Z","end":"2026-04-20T08:05:41.234654Z","steps":["trace[1079382456] 'process raft request' (duration: 174.940264ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.23472Z","caller":"traceutil/trace.go:171","msg":"trace[1559435855] transaction","detail":"{read_only:false; number_of_response:1; response_revision:489; }","duration":"173.10412ms","start":"2026-04-20T08:05:41.061579Z","end":"2026-04-20T08:05:41.234683Z","steps":["trace[1559435855] 'process raft request' (duration: 172.919235ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.234832Z","caller":"traceutil/trace.go:171","msg":"trace[116172565] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"177.760482ms","start":"2026-04-20T08:05:41.057026Z","end":"2026-04-20T08:05:41.234786Z","steps":["trace[116172565] 'process raft request' (duration: 177.23596ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.234936Z","caller":"traceutil/trace.go:171","msg":"trace[1486543292] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"173.229343ms","start":"2026-04-20T08:05:41.061673Z","end":"2026-04-20T08:05:41.234902Z","steps":["trace[1486543292] 'process raft request' (duration: 172.880455ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.271231Z","caller":"traceutil/trace.go:171","msg":"trace[405007660] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"208.425973ms","start":"2026-04-20T08:05:41.062785Z","end":"2026-04-20T08:05:41.271211Z","steps":["trace[405007660] 'process raft request' (duration: 208.182267ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.271373Z","caller":"traceutil/trace.go:171","msg":"trace[153752424] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"174.35674ms","start":"2026-04-20T08:05:41.096988Z","end":"2026-04-20T08:05:41.271345Z","steps":["trace[153752424] 'process raft request' (duration: 174.319109ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.271444Z","caller":"traceutil/trace.go:171","msg":"trace[219499704] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"206.747351ms","start":"2026-04-20T08:05:41.064687Z","end":"2026-04-20T08:05:41.271434Z","steps":["trace[219499704] 'process raft request' (duration: 206.427724ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.271585Z","caller":"traceutil/trace.go:171","msg":"trace[1029419325] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"205.171584ms","start":"2026-04-20T08:05:41.066402Z","end":"2026-04-20T08:05:41.271574Z","steps":["trace[1029419325] 'process raft request' (duration: 204.812385ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.466035Z","caller":"traceutil/trace.go:171","msg":"trace[1192834522] transaction","detail":"{read_only:false; number_of_response:0; response_revision:494; }","duration":"226.765646ms","start":"2026-04-20T08:05:41.239244Z","end":"2026-04-20T08:05:41.46601Z","steps":["trace[1192834522] 'process raft request' (duration: 226.632913ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.466394Z","caller":"traceutil/trace.go:171","msg":"trace[1745064486] transaction","detail":"{read_only:false; number_of_response:0; response_revision:494; }","duration":"227.021512ms","start":"2026-04-20T08:05:41.239345Z","end":"2026-04-20T08:05:41.466366Z","steps":["trace[1745064486] 'process raft request' (duration: 226.595461ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.466489Z","caller":"traceutil/trace.go:171","msg":"trace[238893398] transaction","detail":"{read_only:false; number_of_response:0; response_revision:494; }","duration":"227.231357ms","start":"2026-04-20T08:05:41.239227Z","end":"2026-04-20T08:05:41.466458Z","steps":["trace[238893398] 'process raft request' (duration: 147.762578ms)","trace[238893398] 'compare' (duration: 78.800763ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:05:41.466501Z","caller":"traceutil/trace.go:171","msg":"trace[411915071] transaction","detail":"{read_only:false; number_of_response:0; response_revision:494; }","duration":"227.159985ms","start":"2026-04-20T08:05:41.239315Z","end":"2026-04-20T08:05:41.466475Z","steps":["trace[411915071] 'process raft request' (duration: 226.598732ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.466565Z","caller":"traceutil/trace.go:171","msg":"trace[497844903] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"210.580485ms","start":"2026-04-20T08:05:41.255953Z","end":"2026-04-20T08:05:41.466534Z","steps":["trace[497844903] 'process raft request' (duration: 210.312468ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.466687Z","caller":"traceutil/trace.go:171","msg":"trace[1416097199] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"222.714187ms","start":"2026-04-20T08:05:41.243965Z","end":"2026-04-20T08:05:41.466679Z","steps":["trace[1416097199] 'process raft request' (duration: 222.00392ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.466769Z","caller":"traceutil/trace.go:171","msg":"trace[807068839] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"199.071777ms","start":"2026-04-20T08:05:41.267691Z","end":"2026-04-20T08:05:41.466762Z","steps":["trace[807068839] 'process raft request' (duration: 198.686648ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.466851Z","caller":"traceutil/trace.go:171","msg":"trace[1776925958] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"185.55731ms","start":"2026-04-20T08:05:41.281282Z","end":"2026-04-20T08:05:41.466839Z","steps":["trace[1776925958] 'process raft request' (duration: 185.171951ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.467044Z","caller":"traceutil/trace.go:171","msg":"trace[1461229709] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"175.116928ms","start":"2026-04-20T08:05:41.291899Z","end":"2026-04-20T08:05:41.467016Z","steps":["trace[1461229709] 'process raft request' (duration: 174.665227ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.467357Z","caller":"traceutil/trace.go:171","msg":"trace[784068971] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"125.393808ms","start":"2026-04-20T08:05:41.341946Z","end":"2026-04-20T08:05:41.46734Z","steps":["trace[784068971] 'process raft request' (duration: 125.178443ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.46741Z","caller":"traceutil/trace.go:171","msg":"trace[1010588216] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"147.262446ms","start":"2026-04-20T08:05:41.320029Z","end":"2026-04-20T08:05:41.467292Z","steps":["trace[1010588216] 'process raft request' (duration: 146.835745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:41.840123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.247735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:05:41.840244Z","caller":"traceutil/trace.go:171","msg":"trace[326004977] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"364.282166ms","start":"2026-04-20T08:05:41.47594Z","end":"2026-04-20T08:05:41.840222Z","steps":["trace[326004977] 'process raft request' (duration: 163.651272ms)","trace[326004977] 'compare' (duration: 200.080741ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:05:41.84033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.475923Z","time spent":"364.365498ms","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:05:42.448529Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592195821410105421,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:05:42.795293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.535277ms","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-04-20T08:05:42.795394Z","caller":"traceutil/trace.go:171","msg":"trace[873963460] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:502; }","duration":"447.66411ms","start":"2026-04-20T08:05:42.347713Z","end":"2026-04-20T08:05:42.795377Z","steps":["trace[873963460] 'range keys from in-memory index tree' (duration: 447.521186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:42.797338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.705933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:05:42.79787Z","caller":"traceutil/trace.go:171","msg":"trace[95211200] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"1.321556166s","start":"2026-04-20T08:05:41.476276Z","end":"2026-04-20T08:05:42.797832Z","steps":["trace[95211200] 'process raft request' (duration: 1.01326859s)","trace[95211200] 'compare' (duration: 307.138818ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:05:42.798009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.47626Z","time spent":"1.321657989s","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:05:42.802036Z","caller":"traceutil/trace.go:171","msg":"trace[884055075] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.271164208s","start":"2026-04-20T08:05:41.530852Z","end":"2026-04-20T08:05:42.802016Z","steps":["trace[884055075] 'process raft request' (duration: 1.270611984s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:42.802097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.530825Z","time spent":"1.271243821s","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T08:05:42.802358Z","caller":"traceutil/trace.go:171","msg":"trace[2090689757] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:523; }","duration":"854.301182ms","start":"2026-04-20T08:05:41.948012Z","end":"2026-04-20T08:05:42.802313Z","steps":["trace[2090689757] 'read index received' (duration: 541.545449ms)","trace[2090689757] 'applied index is now lower than readState.Index' (duration: 312.752333ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:05:42.80259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.583179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T08:05:42.802625Z","caller":"traceutil/trace.go:171","msg":"trace[275181372] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:505; }","duration":"854.64453ms","start":"2026-04-20T08:05:41.947966Z","end":"2026-04-20T08:05:42.80261Z","steps":["trace[275181372] 'agreement among raft nodes before linearized reading' (duration: 854.579199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:42.802647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.947945Z","time spent":"854.696311ms","remote":"127.0.0.1:58328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T08:05:42.802729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.247463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T08:05:42.80278Z","caller":"traceutil/trace.go:171","msg":"trace[147742442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:505; }","duration":"603.320905ms","start":"2026-04-20T08:05:42.199437Z","end":"2026-04-20T08:05:42.802758Z","steps":["trace[147742442] 'agreement among raft nodes before linearized reading' (duration: 603.221292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:42.802808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:42.199416Z","time spent":"603.385686ms","remote":"127.0.0.1:58328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T08:05:42.802929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.155774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:05:42.802962Z","caller":"traceutil/trace.go:171","msg":"trace[943527809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:505; }","duration":"158.226025ms","start":"2026-04-20T08:05:42.644728Z","end":"2026-04-20T08:05:42.802954Z","steps":["trace[943527809] 'agreement among raft nodes before linearized reading' (duration: 158.171164ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:48.097628Z","caller":"traceutil/trace.go:171","msg":"trace[1401844225] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"101.586114ms","start":"2026-04-20T08:05:47.996007Z","end":"2026-04-20T08:05:48.097593Z","steps":["trace[1401844225] 'read index received' (duration: 100.791487ms)","trace[1401844225] 'applied index is now lower than readState.Index' (duration: 793.557µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:05:48.097942Z","caller":"traceutil/trace.go:171","msg":"trace[782074132] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"258.155056ms","start":"2026-04-20T08:05:47.839772Z","end":"2026-04-20T08:05:48.097927Z","steps":["trace[782074132] 'process raft request' (duration: 257.255186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:48.098379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.363152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:05:48.098422Z","caller":"traceutil/trace.go:171","msg":"trace[683612028] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"102.456014ms","start":"2026-04-20T08:05:47.995954Z","end":"2026-04-20T08:05:48.09841Z","steps":["trace[683612028] 'agreement among raft nodes before linearized reading' (duration: 102.375012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:48.798785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.626543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:05:48.798903Z","caller":"traceutil/trace.go:171","msg":"trace[1407854924] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"154.783929ms","start":"2026-04-20T08:05:48.644099Z","end":"2026-04-20T08:05:48.798883Z","steps":["trace[1407854924] 'read index received' (duration: 57.441µs)","trace[1407854924] 'applied index is now lower than readState.Index' (duration: 154.724838ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:05:48.799005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.907432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:05:48.799038Z","caller":"traceutil/trace.go:171","msg":"trace[11413882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"154.977343ms","start":"2026-04-20T08:05:48.644049Z","end":"2026-04-20T08:05:48.799026Z","steps":["trace[11413882] 'agreement among raft nodes before linearized reading' (duration: 154.886291ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:48.799025Z","caller":"traceutil/trace.go:171","msg":"trace[254837999] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"642.315691ms","start":"2026-04-20T08:05:48.156672Z","end":"2026-04-20T08:05:48.798988Z","steps":["trace[254837999] 'process raft request' (duration: 379.368201ms)","trace[254837999] 'compare' (duration: 262.49763ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:05:48.799166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:48.156646Z","time spent":"642.450805ms","remote":"127.0.0.1:58406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T08:06:52.481215Z","caller":"traceutil/trace.go:171","msg":"trace[1057277442] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"117.129214ms","start":"2026-04-20T08:06:52.364061Z","end":"2026-04-20T08:06:52.481191Z","steps":["trace[1057277442] 'process raft request' (duration: 116.700208ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:06:59.595996Z","caller":"traceutil/trace.go:171","msg":"trace[1092921249] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"150.917015ms","start":"2026-04-20T08:06:59.445052Z","end":"2026-04-20T08:06:59.595969Z","steps":["trace[1092921249] 'process raft request' (duration: 150.788493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:23.952853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.140012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T08:07:23.952915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.07539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-04-20T08:07:23.952946Z","caller":"traceutil/trace.go:171","msg":"trace[468149957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1832; }","duration":"310.287575ms","start":"2026-04-20T08:07:23.642633Z","end":"2026-04-20T08:07:23.952921Z","steps":["trace[468149957] 'range keys from in-memory index tree' (duration: 310.046691ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:23.952971Z","caller":"traceutil/trace.go:171","msg":"trace[1316470324] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1832; }","duration":"436.162321ms","start":"2026-04-20T08:07:23.516793Z","end":"2026-04-20T08:07:23.952955Z","steps":["trace[1316470324] 'range keys from in-memory index tree' (duration: 435.904787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:23.952985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:07:23.642609Z","time spent":"310.368625ms","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T08:07:23.953004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:07:23.516779Z","time spent":"436.217742ms","remote":"127.0.0.1:58226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10691,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"warn","ts":"2026-04-20T08:07:23.953302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.802468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:07:23.95335Z","caller":"traceutil/trace.go:171","msg":"trace[211541163] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1832; }","duration":"249.87788ms","start":"2026-04-20T08:07:23.703458Z","end":"2026-04-20T08:07:23.953336Z","steps":["trace[211541163] 'count revisions from in-memory index tree' (duration: 249.648256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:23.953553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.616302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-20T08:07:23.953563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.45025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-340abd78-9633-4b9e-b911-1a0453aaa271\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-20T08:07:23.953631Z","caller":"traceutil/trace.go:171","msg":"trace[548640410] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-340abd78-9633-4b9e-b911-1a0453aaa271; range_end:; response_count:1; response_revision:1832; }","duration":"357.575292ms","start":"2026-04-20T08:07:23.596039Z","end":"2026-04-20T08:07:23.953614Z","steps":["trace[548640410] 'range keys from in-memory index tree' (duration: 357.289628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:23.953672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:07:23.596014Z","time spent":"357.651713ms","remote":"127.0.0.1:58226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-340abd78-9633-4b9e-b911-1a0453aaa271\" "} {"level":"info","ts":"2026-04-20T08:07:23.953591Z","caller":"traceutil/trace.go:171","msg":"trace[580263308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1832; }","duration":"248.667154ms","start":"2026-04-20T08:07:23.704914Z","end":"2026-04-20T08:07:23.953581Z","steps":["trace[580263308] 'range keys from in-memory index tree' (duration: 248.506232ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:23.962063Z","caller":"traceutil/trace.go:171","msg":"trace[698182934] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"190.979982ms","start":"2026-04-20T08:07:23.77105Z","end":"2026-04-20T08:07:23.96203Z","steps":["trace[698182934] 'process raft request' (duration: 190.685229ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:24.189128Z","caller":"traceutil/trace.go:171","msg":"trace[1125561933] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"163.574634ms","start":"2026-04-20T08:07:24.02553Z","end":"2026-04-20T08:07:24.189105Z","steps":["trace[1125561933] 'process raft request' (duration: 163.408162ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:24.189137Z","caller":"traceutil/trace.go:171","msg":"trace[1175209765] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"163.694455ms","start":"2026-04-20T08:07:24.025395Z","end":"2026-04-20T08:07:24.189089Z","steps":["trace[1175209765] 'process raft request' (duration: 157.680388ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:24.189185Z","caller":"traceutil/trace.go:171","msg":"trace[1255419192] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"163.624775ms","start":"2026-04-20T08:07:24.025544Z","end":"2026-04-20T08:07:24.189169Z","steps":["trace[1255419192] 'process raft request' (duration: 163.479503ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:24.189637Z","caller":"traceutil/trace.go:171","msg":"trace[1468230978] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"164.04616ms","start":"2026-04-20T08:07:24.025582Z","end":"2026-04-20T08:07:24.189628Z","steps":["trace[1468230978] 'process raft request' (duration: 163.489403ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:24.473366Z","caller":"traceutil/trace.go:171","msg":"trace[128870621] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"142.885268ms","start":"2026-04-20T08:07:24.330455Z","end":"2026-04-20T08:07:24.47334Z","steps":["trace[128870621] 'process raft request' (duration: 142.717886ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:56.951763Z","caller":"traceutil/trace.go:171","msg":"trace[1660520856] linearizableReadLoop","detail":"{readStateIndex:2150; appliedIndex:2149; }","duration":"138.57181ms","start":"2026-04-20T08:07:56.813167Z","end":"2026-04-20T08:07:56.951738Z","steps":["trace[1660520856] 'read index received' (duration: 138.300387ms)","trace[1660520856] 'applied index is now lower than readState.Index' (duration: 270.503µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:07:56.951966Z","caller":"traceutil/trace.go:171","msg":"trace[1394096654] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"186.329358ms","start":"2026-04-20T08:07:56.765523Z","end":"2026-04-20T08:07:56.951852Z","steps":["trace[1394096654] 'process raft request' (duration: 186.015684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:56.952264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.074385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T08:07:56.952323Z","caller":"traceutil/trace.go:171","msg":"trace[1639444361] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2070; }","duration":"139.182466ms","start":"2026-04-20T08:07:56.813128Z","end":"2026-04-20T08:07:56.952311Z","steps":["trace[1639444361] 'agreement among raft nodes before linearized reading' (duration: 138.796242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:56.95229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.882001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a802332de949d0\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-04-20T08:07:56.952384Z","caller":"traceutil/trace.go:171","msg":"trace[1337803735] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a802332de949d0; range_end:; response_count:1; response_revision:2070; }","duration":"114.026913ms","start":"2026-04-20T08:07:56.838335Z","end":"2026-04-20T08:07:56.952362Z","steps":["trace[1337803735] 'agreement among raft nodes before linearized reading' (duration: 113.680249ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:57.060611Z","caller":"traceutil/trace.go:171","msg":"trace[894238487] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"105.551142ms","start":"2026-04-20T08:07:56.955043Z","end":"2026-04-20T08:07:57.060594Z","steps":["trace[894238487] 'compare' (duration: 98.043924ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:57.061589Z","caller":"traceutil/trace.go:171","msg":"trace[557823125] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"104.121225ms","start":"2026-04-20T08:07:56.956754Z","end":"2026-04-20T08:07:57.060875Z","steps":["trace[557823125] 'process raft request' (duration: 103.801772ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:58.557473Z","caller":"traceutil/trace.go:171","msg":"trace[1514079129] linearizableReadLoop","detail":"{readStateIndex:2158; appliedIndex:2157; }","duration":"139.99091ms","start":"2026-04-20T08:07:58.41746Z","end":"2026-04-20T08:07:58.557451Z","steps":["trace[1514079129] 'read index received' (duration: 135.011012ms)","trace[1514079129] 'applied index is now lower than readState.Index' (duration: 4.978918ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:07:58.557932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.420365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:07:58.557983Z","caller":"traceutil/trace.go:171","msg":"trace[1212954056] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2078; }","duration":"140.556637ms","start":"2026-04-20T08:07:58.417412Z","end":"2026-04-20T08:07:58.557968Z","steps":["trace[1212954056] 'agreement among raft nodes before linearized reading' (duration: 140.179491ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:58.558247Z","caller":"traceutil/trace.go:171","msg":"trace[54584713] transaction","detail":"{read_only:false; response_revision:2078; number_of_response:1; }","duration":"292.943181ms","start":"2026-04-20T08:07:58.265283Z","end":"2026-04-20T08:07:58.558226Z","steps":["trace[54584713] 'process raft request' (duration: 287.174953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:59.193329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.773196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:07:59.193437Z","caller":"traceutil/trace.go:171","msg":"trace[693292827] transaction","detail":"{read_only:false; response_revision:2079; number_of_response:1; }","duration":"529.569829ms","start":"2026-04-20T08:07:58.663848Z","end":"2026-04-20T08:07:59.193418Z","steps":["trace[693292827] 'process raft request' (duration: 418.574551ms)","trace[693292827] 'compare' (duration: 110.665544ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:07:59.193753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:07:58.663822Z","time spent":"529.860172ms","remote":"127.0.0.1:58328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:07:59.509313Z","caller":"traceutil/trace.go:171","msg":"trace[1466447914] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"552.518828ms","start":"2026-04-20T08:07:58.956768Z","end":"2026-04-20T08:07:59.509287Z","steps":["trace[1466447914] 'process raft request' (duration: 550.436903ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:59.509485Z","caller":"traceutil/trace.go:171","msg":"trace[1100690721] linearizableReadLoop","detail":"{readStateIndex:2161; appliedIndex:2158; }","duration":"443.652342ms","start":"2026-04-20T08:07:59.065808Z","end":"2026-04-20T08:07:59.50946Z","steps":["trace[1100690721] 'read index received' (duration: 16.561802ms)","trace[1100690721] 'applied index is now lower than readState.Index' (duration: 427.08866ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:07:59.509527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:07:58.956742Z","time spent":"552.65213ms","remote":"127.0.0.1:58328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:07:59.509661Z","caller":"traceutil/trace.go:171","msg":"trace[1123458083] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"443.794404ms","start":"2026-04-20T08:07:59.065762Z","end":"2026-04-20T08:07:59.509556Z","steps":["trace[1123458083] 'process raft request' (duration: 443.47156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:59.509709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.887955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T08:07:59.509765Z","caller":"traceutil/trace.go:171","msg":"trace[1352405271] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2081; }","duration":"443.981626ms","start":"2026-04-20T08:07:59.065765Z","end":"2026-04-20T08:07:59.509747Z","steps":["trace[1352405271] 'agreement among raft nodes before linearized reading' (duration: 443.769634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:59.509821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:07:59.065742Z","time spent":"444.067307ms","remote":"127.0.0.1:58328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T08:07:59.510031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:07:59.065739Z","time spent":"443.999296ms","remote":"127.0.0.1:58328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:09:12.707549Z","caller":"traceutil/trace.go:171","msg":"trace[51779071] transaction","detail":"{read_only:false; response_revision:2578; number_of_response:1; }","duration":"199.966647ms","start":"2026-04-20T08:09:12.507551Z","end":"2026-04-20T08:09:12.707518Z","steps":["trace[51779071] 'process raft request' (duration: 199.727339ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:09:50.993117Z","caller":"traceutil/trace.go:171","msg":"trace[202428423] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"148.176208ms","start":"2026-04-20T08:09:50.84492Z","end":"2026-04-20T08:09:50.993096Z","steps":["trace[202428423] 'process raft request' (duration: 148.007373ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:43.658069Z","caller":"traceutil/trace.go:171","msg":"trace[216107976] linearizableReadLoop","detail":"{readStateIndex:4882; appliedIndex:4881; }","duration":"157.135633ms","start":"2026-04-20T08:13:43.500906Z","end":"2026-04-20T08:13:43.658042Z","steps":["trace[216107976] 'read index received' (duration: 63.960903ms)","trace[216107976] 'applied index is now lower than readState.Index' (duration: 93.17345ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:13:43.658194Z","caller":"traceutil/trace.go:171","msg":"trace[515466024] transaction","detail":"{read_only:false; response_revision:4702; number_of_response:1; }","duration":"159.842853ms","start":"2026-04-20T08:13:43.498332Z","end":"2026-04-20T08:13:43.658175Z","steps":["trace[515466024] 'process raft request' (duration: 66.637613ms)","trace[515466024] 'compare' (duration: 92.879176ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:13:43.658245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.322426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:13:43.658281Z","caller":"traceutil/trace.go:171","msg":"trace[839825621] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:0; response_revision:4702; }","duration":"157.396778ms","start":"2026-04-20T08:13:43.500872Z","end":"2026-04-20T08:13:43.658269Z","steps":["trace[839825621] 'agreement among raft nodes before linearized reading' (duration: 157.318317ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:43.999248Z","caller":"traceutil/trace.go:171","msg":"trace[1423307013] transaction","detail":"{read_only:false; response_revision:4704; number_of_response:1; }","duration":"143.285266ms","start":"2026-04-20T08:13:43.85594Z","end":"2026-04-20T08:13:43.999225Z","steps":["trace[1423307013] 'process raft request' (duration: 143.127033ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:44.185725Z","caller":"traceutil/trace.go:171","msg":"trace[1485349606] linearizableReadLoop","detail":"{readStateIndex:4885; appliedIndex:4884; }","duration":"159.263461ms","start":"2026-04-20T08:13:44.026443Z","end":"2026-04-20T08:13:44.185707Z","steps":["trace[1485349606] 'read index received' (duration: 159.16122ms)","trace[1485349606] 'applied index is now lower than readState.Index' (duration: 101.401µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:13:44.185795Z","caller":"traceutil/trace.go:171","msg":"trace[377554727] transaction","detail":"{read_only:false; response_revision:4705; number_of_response:1; }","duration":"289.38365ms","start":"2026-04-20T08:13:43.896385Z","end":"2026-04-20T08:13:44.185768Z","steps":["trace[377554727] 'process raft request' (duration: 289.190117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:44.185884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.421594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-20T08:13:44.185925Z","caller":"traceutil/trace.go:171","msg":"trace[68386329] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4705; }","duration":"159.509795ms","start":"2026-04-20T08:13:44.026406Z","end":"2026-04-20T08:13:44.185916Z","steps":["trace[68386329] 'agreement among raft nodes before linearized reading' (duration: 159.373273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:46.057064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.33254ms","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-04-20T08:13:46.057132Z","caller":"traceutil/trace.go:171","msg":"trace[922313604] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:4774; }","duration":"270.444182ms","start":"2026-04-20T08:13:45.78667Z","end":"2026-04-20T08:13:46.057114Z","steps":["trace[922313604] 'count revisions from in-memory index tree' (duration: 270.263579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:46.057365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.129884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5682826a-08bb-49b1-8f40-f09ddd0b560b\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-20T08:13:46.057456Z","caller":"traceutil/trace.go:171","msg":"trace[1852041868] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5682826a-08bb-49b1-8f40-f09ddd0b560b; range_end:; response_count:1; response_revision:4774; }","duration":"130.255176ms","start":"2026-04-20T08:13:45.927179Z","end":"2026-04-20T08:13:46.057434Z","steps":["trace[1852041868] 'range keys from in-memory index tree' (duration: 129.90508ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:01.014744Z","caller":"traceutil/trace.go:171","msg":"trace[1968243533] linearizableReadLoop","detail":"{readStateIndex:5136; appliedIndex:5135; }","duration":"437.209404ms","start":"2026-04-20T08:14:00.577509Z","end":"2026-04-20T08:14:01.014718Z","steps":["trace[1968243533] 'read index received' (duration: 437.009412ms)","trace[1968243533] 'applied index is now lower than readState.Index' (duration: 198.762µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:14:01.014813Z","caller":"traceutil/trace.go:171","msg":"trace[1569499944] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"501.372673ms","start":"2026-04-20T08:14:00.513404Z","end":"2026-04-20T08:14:01.014776Z","steps":["trace[1569499944] 'process raft request' (duration: 501.15239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:14:01.014977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.443038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-20T08:14:01.014989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:14:00.513383Z","time spent":"501.507055ms","remote":"127.0.0.1:58226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:14:01.01502Z","caller":"traceutil/trace.go:171","msg":"trace[1751361445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4947; }","duration":"437.541481ms","start":"2026-04-20T08:14:00.577466Z","end":"2026-04-20T08:14:01.015008Z","steps":["trace[1751361445] 'agreement among raft nodes before linearized reading' (duration: 437.381607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:14:01.015054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:14:00.577447Z","time spent":"437.598631ms","remote":"127.0.0.1:58328","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-04-20T08:14:01.035634Z","caller":"traceutil/trace.go:171","msg":"trace[1971140148] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"407.571641ms","start":"2026-04-20T08:14:00.628032Z","end":"2026-04-20T08:14:01.035604Z","steps":["trace[1971140148] 'process raft request' (duration: 407.370908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:14:01.035709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.892272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:14:01.035758Z","caller":"traceutil/trace.go:171","msg":"trace[2032933219] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:4950; }","duration":"203.988943ms","start":"2026-04-20T08:14:00.831756Z","end":"2026-04-20T08:14:01.035745Z","steps":["trace[2032933219] 'agreement among raft nodes before linearized reading' (duration: 203.854031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:14:01.035787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:14:00.628013Z","time spent":"407.694572ms","remote":"127.0.0.1:58328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:14:01.035923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.002612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-20T08:14:01.035944Z","caller":"traceutil/trace.go:171","msg":"trace[180632724] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4950; }","duration":"446.044573ms","start":"2026-04-20T08:14:00.589893Z","end":"2026-04-20T08:14:01.035938Z","steps":["trace[180632724] 'agreement among raft nodes before linearized reading' (duration: 445.975732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:14:01.035962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:14:00.589883Z","time spent":"446.074084ms","remote":"127.0.0.1:58200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-04-20T08:14:01.147907Z","caller":"traceutil/trace.go:171","msg":"trace[162885619] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"125.660386ms","start":"2026-04-20T08:14:01.022216Z","end":"2026-04-20T08:14:01.147877Z","steps":["trace[162885619] 'process raft request' (duration: 64.615754ms)","trace[162885619] 'compare' (duration: 60.822689ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:14:01.148378Z","caller":"traceutil/trace.go:171","msg":"trace[88866428] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"125.974971ms","start":"2026-04-20T08:14:01.022361Z","end":"2026-04-20T08:14:01.148336Z","steps":["trace[88866428] 'process raft request' (duration: 125.452553ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:01.148458Z","caller":"traceutil/trace.go:171","msg":"trace[1711459355] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"126.015291ms","start":"2026-04-20T08:14:01.022414Z","end":"2026-04-20T08:14:01.148429Z","steps":["trace[1711459355] 'process raft request' (duration: 125.756727ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:01.148532Z","caller":"traceutil/trace.go:171","msg":"trace[1288413231] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"124.820973ms","start":"2026-04-20T08:14:01.023697Z","end":"2026-04-20T08:14:01.148518Z","steps":["trace[1288413231] 'process raft request' (duration: 124.61563ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:01.148521Z","caller":"traceutil/trace.go:171","msg":"trace[1103944598] linearizableReadLoop","detail":"{readStateIndex:5144; appliedIndex:5139; }","duration":"112.886939ms","start":"2026-04-20T08:14:01.035595Z","end":"2026-04-20T08:14:01.148482Z","steps":["trace[1103944598] 'read index received' (duration: 51.240218ms)","trace[1103944598] 'applied index is now lower than readState.Index' (duration: 61.645351ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:14:01.148767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.941373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-6658c44945-r56fp\" ","response":"range_response_count:1 size:11845"} {"level":"info","ts":"2026-04-20T08:14:01.150914Z","caller":"traceutil/trace.go:171","msg":"trace[1521424140] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-6658c44945-r56fp; range_end:; response_count:1; response_revision:4955; }","duration":"131.359859ms","start":"2026-04-20T08:14:01.019532Z","end":"2026-04-20T08:14:01.150892Z","steps":["trace[1521424140] 'agreement among raft nodes before linearized reading' (duration: 129.051235ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:01.148781Z","caller":"traceutil/trace.go:171","msg":"trace[1065256635] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"124.61556ms","start":"2026-04-20T08:14:01.024141Z","end":"2026-04-20T08:14:01.148757Z","steps":["trace[1065256635] 'process raft request' (duration: 124.239774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:14:01.151068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.529764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-5682826a-08bb-49b1-8f40-f09ddd0b560b\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-04-20T08:14:01.151125Z","caller":"traceutil/trace.go:171","msg":"trace[968497605] range","detail":"{range_begin:/registry/persistentvolumes/pvc-5682826a-08bb-49b1-8f40-f09ddd0b560b; range_end:; response_count:1; response_revision:4955; }","duration":"112.633145ms","start":"2026-04-20T08:14:01.038474Z","end":"2026-04-20T08:14:01.151107Z","steps":["trace[968497605] 'agreement among raft nodes before linearized reading' (duration: 112.426692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:14:01.458134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.073935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:14:01.458297Z","caller":"traceutil/trace.go:171","msg":"trace[882107871] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"231.105877ms","start":"2026-04-20T08:14:01.227173Z","end":"2026-04-20T08:14:01.458279Z","steps":["trace[882107871] 'process raft request' (duration: 86.805009ms)","trace[882107871] 'compare' (duration: 143.977634ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:14:01.459003Z","caller":"traceutil/trace.go:171","msg":"trace[548198260] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"227.037057ms","start":"2026-04-20T08:14:01.23194Z","end":"2026-04-20T08:14:01.458977Z","steps":["trace[548198260] 'process raft request' (duration: 226.295147ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:10.420766Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2893} {"level":"info","ts":"2026-04-20T08:15:10.495924Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2893,"took":"74.125122ms","hash":1029165483,"current-db-size-bytes":53776384,"current-db-size":"54 MB","current-db-size-in-use-bytes":28835840,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-20T08:15:10.495977Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1029165483,"revision":2893,"compact-revision":-1} {"level":"info","ts":"2026-04-20T08:20:10.427096Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5519} {"level":"info","ts":"2026-04-20T08:20:10.522119Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5519,"took":"93.542508ms","hash":3469677166,"current-db-size-bytes":53776384,"current-db-size":"54 MB","current-db-size-in-use-bytes":18305024,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-20T08:20:10.522179Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3469677166,"revision":5519,"compact-revision":2893}