{"level":"warn","ts":"2026-09-09T06:11:08.423791Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T06:11:08.423887Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.71: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://162.253.55.71:2380","--initial-cluster=instance=https://162.253.55.71:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.71:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.71: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-09-09T06:11:08.423965Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T06:11:08.423979Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.71:2380"]} {"level":"info","ts":"2026-09-09T06:11:08.424006Z","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-09-09T06:11:08.424852Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.71:2379"]} {"level":"info","ts":"2026-09-09T06:11:08.424942Z","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://162.253.55.71:2380"],"listen-peer-urls":["https://162.253.55.71:2380"],"advertise-client-urls":["https://162.253.55.71:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.71:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.71: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-09-09T06:11:08.4913Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"66.059996ms"} {"level":"info","ts":"2026-09-09T06:11:08.611596Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"54232768b5552f01","cluster-id":"66db39a47e9fb23b"} {"level":"info","ts":"2026-09-09T06:11:08.611739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 switched to configuration voters=()"} {"level":"info","ts":"2026-09-09T06:11:08.611776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became follower at term 0"} {"level":"info","ts":"2026-09-09T06:11:08.611796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 54232768b5552f01 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-09T06:11:08.611812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became follower at term 1"} {"level":"info","ts":"2026-09-09T06:11:08.611869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 switched to configuration voters=(6062732854043160321)"} {"level":"warn","ts":"2026-09-09T06:11:08.758291Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-09T06:11:08.780255Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-09T06:11:08.821481Z","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-09-09T06:11:08.851232Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"54232768b5552f01","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-09T06:11:08.851498Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"54232768b5552f01","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"warn","ts":"2026-09-09T06:11:08.851703Z","logger":"raft","caller":"etcdserver/zap_raft.go:85","msg":"54232768b5552f01 cannot campaign at term 1 since there are still 1 pending configuration changes to apply"} {"level":"info","ts":"2026-09-09T06:11:08.851734Z","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-09-09T06:11:08.851873Z","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-09-09T06:11:08.851915Z","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-09-09T06:11:08.856404Z","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-09-09T06:11:08.856693Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"54232768b5552f01","initial-advertise-peer-urls":["https://162.253.55.71:2380"],"listen-peer-urls":["https://162.253.55.71:2380"],"advertise-client-urls":["https://162.253.55.71:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.71:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-09T06:11:08.856739Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-09T06:11:08.856812Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.71:2380"} {"level":"info","ts":"2026-09-09T06:11:08.856873Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.71:2380"} {"level":"info","ts":"2026-09-09T06:11:08.858657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 switched to configuration voters=(6062732854043160321)"} {"level":"info","ts":"2026-09-09T06:11:08.858799Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"66db39a47e9fb23b","local-member-id":"54232768b5552f01","added-peer-id":"54232768b5552f01","added-peer-peer-urls":["https://162.253.55.71:2380"]} {"level":"info","ts":"2026-09-09T06:11:09.820868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 is starting a new election at term 1"} {"level":"info","ts":"2026-09-09T06:11:09.820923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-09T06:11:09.820953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 received MsgPreVoteResp from 54232768b5552f01 at term 1"} {"level":"info","ts":"2026-09-09T06:11:09.820966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became candidate at term 2"} {"level":"info","ts":"2026-09-09T06:11:09.820974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 received MsgVoteResp from 54232768b5552f01 at term 2"} {"level":"info","ts":"2026-09-09T06:11:09.820985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became leader at term 2"} {"level":"info","ts":"2026-09-09T06:11:09.820994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 54232768b5552f01 elected leader 54232768b5552f01 at term 2"} {"level":"info","ts":"2026-09-09T06:11:09.822008Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:11:09.822949Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T06:11:09.822946Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"54232768b5552f01","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.71:2379]}","request-path":"/0/members/54232768b5552f01/attributes","cluster-id":"66db39a47e9fb23b","publish-timeout":"7s"} {"level":"info","ts":"2026-09-09T06:11:09.823001Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T06:11:09.823392Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-09T06:11:09.823532Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-09T06:11:09.824348Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"66db39a47e9fb23b","local-member-id":"54232768b5552f01","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:11:09.824576Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:11:09.82462Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:11:09.825005Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-09T06:11:09.826971Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.71:2379"} {"level":"info","ts":"2026-09-09T06:11:51.523811Z","caller":"traceutil/trace.go:171","msg":"trace[1059970618] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"101.161146ms","start":"2026-09-09T06:11:51.422583Z","end":"2026-09-09T06:11:51.523744Z","steps":["trace[1059970618] 'process raft request' (duration: 99.466001ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:11:58.272054Z","caller":"traceutil/trace.go:171","msg":"trace[2061684791] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"124.187888ms","start":"2026-09-09T06:11:58.147828Z","end":"2026-09-09T06:11:58.272016Z","steps":["trace[2061684791] 'process raft request' (duration: 123.982462ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:11:58.458458Z","caller":"traceutil/trace.go:171","msg":"trace[1040457864] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"226.598389ms","start":"2026-09-09T06:11:58.231828Z","end":"2026-09-09T06:11:58.458426Z","steps":["trace[1040457864] 'process raft request' (duration: 225.87801ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:11:58.980681Z","caller":"traceutil/trace.go:171","msg":"trace[1911355814] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"307.204284ms","start":"2026-09-09T06:11:58.673452Z","end":"2026-09-09T06:11:58.980656Z","steps":["trace[1911355814] 'read index received' (duration: 306.944107ms)","trace[1911355814] 'applied index is now lower than readState.Index' (duration: 258.956µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:11:58.980761Z","caller":"traceutil/trace.go:171","msg":"trace[1671717805] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"446.719791ms","start":"2026-09-09T06:11:58.534018Z","end":"2026-09-09T06:11:58.980738Z","steps":["trace[1671717805] 'process raft request' (duration: 446.485284ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:11:58.981015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.531294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-k484d\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-09T06:11:58.981113Z","caller":"traceutil/trace.go:171","msg":"trace[1058826306] range","detail":"{range_begin:/registry/pods/kube-system/cilium-k484d; range_end:; response_count:1; response_revision:604; }","duration":"307.710378ms","start":"2026-09-09T06:11:58.673386Z","end":"2026-09-09T06:11:58.981097Z","steps":["trace[1058826306] 'agreement among raft nodes before linearized reading' (duration: 307.42202ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:11:58.981222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:11:58.673359Z","time spent":"307.850222ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-k484d\" "} {"level":"warn","ts":"2026-09-09T06:11:58.981791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:11:58.533983Z","time spent":"446.831014ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-09T06:12:15.874389Z","caller":"traceutil/trace.go:171","msg":"trace[1979468116] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:803; }","duration":"123.581074ms","start":"2026-09-09T06:12:15.750785Z","end":"2026-09-09T06:12:15.874366Z","steps":["trace[1979468116] 'read index received' (duration: 123.415189ms)","trace[1979468116] 'applied index is now lower than readState.Index' (duration: 165.225µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:12:15.874565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.760609ms","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-09-09T06:12:15.874605Z","caller":"traceutil/trace.go:171","msg":"trace[1251326896] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:772; }","duration":"123.843082ms","start":"2026-09-09T06:12:15.750753Z","end":"2026-09-09T06:12:15.874596Z","steps":["trace[1251326896] 'agreement among raft nodes before linearized reading' (duration: 123.715908ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:12:15.874718Z","caller":"traceutil/trace.go:171","msg":"trace[1054208755] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"138.757296ms","start":"2026-09-09T06:12:15.735937Z","end":"2026-09-09T06:12:15.874695Z","steps":["trace[1054208755] 'process raft request' (duration: 138.315804ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:12:16.040964Z","caller":"traceutil/trace.go:171","msg":"trace[1898819152] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"162.491913ms","start":"2026-09-09T06:12:15.87845Z","end":"2026-09-09T06:12:16.040942Z","steps":["trace[1898819152] 'process raft request' (duration: 123.848802ms)","trace[1898819152] 'compare' (duration: 38.545618ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:12:16.047027Z","caller":"traceutil/trace.go:171","msg":"trace[1310547264] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:804; }","duration":"134.929409ms","start":"2026-09-09T06:12:15.912081Z","end":"2026-09-09T06:12:16.04701Z","steps":["trace[1310547264] 'read index received' (duration: 90.294283ms)","trace[1310547264] 'applied index is now lower than readState.Index' (duration: 44.634466ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:12:16.047087Z","caller":"traceutil/trace.go:171","msg":"trace[2012062289] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"168.348643ms","start":"2026-09-09T06:12:15.878714Z","end":"2026-09-09T06:12:16.047062Z","steps":["trace[2012062289] 'process raft request' (duration: 168.152118ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:12:16.047188Z","caller":"traceutil/trace.go:171","msg":"trace[1683763149] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"162.961277ms","start":"2026-09-09T06:12:15.884219Z","end":"2026-09-09T06:12:16.047181Z","steps":["trace[1683763149] 'process raft request' (duration: 162.745011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:12:16.047157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.063912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:12:16.047371Z","caller":"traceutil/trace.go:171","msg":"trace[1880193308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"135.321611ms","start":"2026-09-09T06:12:15.912041Z","end":"2026-09-09T06:12:16.047363Z","steps":["trace[1880193308] 'agreement among raft nodes before linearized reading' (duration: 135.034152ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:13:10.190734Z","caller":"traceutil/trace.go:171","msg":"trace[1566151139] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"128.049631ms","start":"2026-09-09T06:13:10.06265Z","end":"2026-09-09T06:13:10.190699Z","steps":["trace[1566151139] 'process raft request' (duration: 127.807203ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:13:10.737029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.315683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:13:10.737164Z","caller":"traceutil/trace.go:171","msg":"trace[1587847754] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1437; }","duration":"327.476598ms","start":"2026-09-09T06:13:10.409634Z","end":"2026-09-09T06:13:10.73711Z","steps":["trace[1587847754] 'count revisions from in-memory index tree' (duration: 327.23549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:13:10.737218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:13:10.409618Z","time spent":"327.587272ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-09-09T06:13:10.737191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.520256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:13:10.737488Z","caller":"traceutil/trace.go:171","msg":"trace[2089877096] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1437; }","duration":"221.881529ms","start":"2026-09-09T06:13:10.515587Z","end":"2026-09-09T06:13:10.737469Z","steps":["trace[2089877096] 'count revisions from in-memory index tree' (duration: 221.430434ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:13:10.7375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.480897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-09T06:13:10.737557Z","caller":"traceutil/trace.go:171","msg":"trace[205988941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1437; }","duration":"322.553949ms","start":"2026-09-09T06:13:10.414969Z","end":"2026-09-09T06:13:10.737523Z","steps":["trace[205988941] 'range keys from in-memory index tree' (duration: 322.360513ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:13:10.737581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:13:10.414951Z","time spent":"322.623772ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-09T06:13:20.702702Z","caller":"traceutil/trace.go:171","msg":"trace[495358181] transaction","detail":"{read_only:false; response_revision:1569; number_of_response:1; }","duration":"113.742776ms","start":"2026-09-09T06:13:20.588924Z","end":"2026-09-09T06:13:20.702666Z","steps":["trace[495358181] 'process raft request' (duration: 62.308612ms)","trace[495358181] 'compare' (duration: 51.261329ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:13:37.914386Z","caller":"traceutil/trace.go:171","msg":"trace[611640506] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"253.749835ms","start":"2026-09-09T06:13:37.660609Z","end":"2026-09-09T06:13:37.914358Z","steps":["trace[611640506] 'process raft request' (duration: 253.562499ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:13:38.099755Z","caller":"traceutil/trace.go:171","msg":"trace[1864886203] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"385.167931ms","start":"2026-09-09T06:13:37.714556Z","end":"2026-09-09T06:13:38.099724Z","steps":["trace[1864886203] 'process raft request' (duration: 385.019686ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:13:38.100155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:13:37.714532Z","time spent":"385.311566ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:13:38.414366Z","caller":"traceutil/trace.go:171","msg":"trace[20101990] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"249.570972ms","start":"2026-09-09T06:13:38.164766Z","end":"2026-09-09T06:13:38.414337Z","steps":["trace[20101990] 'process raft request' (duration: 249.351615ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:13:38.420066Z","caller":"traceutil/trace.go:171","msg":"trace[1405933676] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1934; }","duration":"206.967826ms","start":"2026-09-09T06:13:38.213072Z","end":"2026-09-09T06:13:38.42004Z","steps":["trace[1405933676] 'read index received' (duration: 201.148331ms)","trace[1405933676] 'applied index is now lower than readState.Index' (duration: 5.818065ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:13:38.420445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.360819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eca483d7-0631-4fad-9249-baa65a4fb8b1\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-09T06:13:38.420506Z","caller":"traceutil/trace.go:171","msg":"trace[1565131028] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-eca483d7-0631-4fad-9249-baa65a4fb8b1; range_end:; response_count:1; response_revision:1860; }","duration":"207.478093ms","start":"2026-09-09T06:13:38.213015Z","end":"2026-09-09T06:13:38.420493Z","steps":["trace[1565131028] 'agreement among raft nodes before linearized reading' (duration: 207.161502ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:13:38.42028Z","caller":"traceutil/trace.go:171","msg":"trace[582280117] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"242.214656ms","start":"2026-09-09T06:13:38.177882Z","end":"2026-09-09T06:13:38.420097Z","steps":["trace[582280117] 'process raft request' (duration: 241.778121ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:13:50.619684Z","caller":"traceutil/trace.go:171","msg":"trace[1699443523] linearizableReadLoop","detail":"{readStateIndex:2039; appliedIndex:2038; }","duration":"117.960319ms","start":"2026-09-09T06:13:50.501691Z","end":"2026-09-09T06:13:50.619651Z","steps":["trace[1699443523] 'read index received' (duration: 117.660549ms)","trace[1699443523] 'applied index is now lower than readState.Index' (duration: 298.66µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:13:50.620104Z","caller":"traceutil/trace.go:171","msg":"trace[1939658973] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"146.585696ms","start":"2026-09-09T06:13:50.473501Z","end":"2026-09-09T06:13:50.620086Z","steps":["trace[1939658973] 'process raft request' (duration: 145.946095ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:13:50.620541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.826058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-09T06:13:50.62059Z","caller":"traceutil/trace.go:171","msg":"trace[2028671883] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1961; }","duration":"118.933723ms","start":"2026-09-09T06:13:50.501642Z","end":"2026-09-09T06:13:50.620575Z","steps":["trace[2028671883] 'agreement among raft nodes before linearized reading' (duration: 118.751116ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:13:50.881908Z","caller":"traceutil/trace.go:171","msg":"trace[1410366113] linearizableReadLoop","detail":"{readStateIndex:2041; appliedIndex:2040; }","duration":"107.626052ms","start":"2026-09-09T06:13:50.774252Z","end":"2026-09-09T06:13:50.881878Z","steps":["trace[1410366113] 'read index received' (duration: 107.437225ms)","trace[1410366113] 'applied index is now lower than readState.Index' (duration: 186.906µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:13:50.881965Z","caller":"traceutil/trace.go:171","msg":"trace[1601517026] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"116.566373ms","start":"2026-09-09T06:13:50.765348Z","end":"2026-09-09T06:13:50.881915Z","steps":["trace[1601517026] 'process raft request' (duration: 116.316504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:13:50.882203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.920021ms","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-09-09T06:13:50.882263Z","caller":"traceutil/trace.go:171","msg":"trace[1973299950] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1963; }","duration":"108.072606ms","start":"2026-09-09T06:13:50.77418Z","end":"2026-09-09T06:13:50.882252Z","steps":["trace[1973299950] 'agreement among raft nodes before linearized reading' (duration: 107.808837ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:14:07.076846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.125365ms","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-09-09T06:14:07.076923Z","caller":"traceutil/trace.go:171","msg":"trace[118436451] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2067; }","duration":"119.252389ms","start":"2026-09-09T06:14:06.957651Z","end":"2026-09-09T06:14:07.076903Z","steps":["trace[118436451] 'agreement among raft nodes before linearized reading' (duration: 73.367947ms)","trace[118436451] 'range keys from in-memory index tree' (duration: 45.681866ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:14:13.426464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.844991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:14:13.426608Z","caller":"traceutil/trace.go:171","msg":"trace[1003630486] linearizableReadLoop","detail":"{readStateIndex:2185; appliedIndex:2184; }","duration":"224.67862ms","start":"2026-09-09T06:14:13.201909Z","end":"2026-09-09T06:14:13.426588Z","steps":["trace[1003630486] 'read index received' (duration: 46.336791ms)","trace[1003630486] 'applied index is now lower than readState.Index' (duration: 178.340349ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:14:13.426649Z","caller":"traceutil/trace.go:171","msg":"trace[830301982] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"229.444223ms","start":"2026-09-09T06:14:13.197166Z","end":"2026-09-09T06:14:13.426611Z","steps":["trace[830301982] 'process raft request' (duration: 51.105724ms)","trace[830301982] 'compare' (duration: 177.650525ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:14:13.426804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.888318ms","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-09-09T06:14:13.426846Z","caller":"traceutil/trace.go:171","msg":"trace[448984301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2102; }","duration":"224.97799ms","start":"2026-09-09T06:14:13.201855Z","end":"2026-09-09T06:14:13.426833Z","steps":["trace[448984301] 'agreement among raft nodes before linearized reading' (duration: 224.831786ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:14:13.426903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.065735ms","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-09-09T06:14:13.426975Z","caller":"traceutil/trace.go:171","msg":"trace[18829107] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2102; }","duration":"223.185689ms","start":"2026-09-09T06:14:13.203772Z","end":"2026-09-09T06:14:13.426958Z","steps":["trace[18829107] 'agreement among raft nodes before linearized reading' (duration: 222.968151ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:14:13.742988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.197613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:14:13.743132Z","caller":"traceutil/trace.go:171","msg":"trace[1695865291] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"311.317818ms","start":"2026-09-09T06:14:13.43179Z","end":"2026-09-09T06:14:13.743108Z","steps":["trace[1695865291] 'process raft request' (duration: 173.920758ms)","trace[1695865291] 'compare' (duration: 137.08541ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:14:13.743232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:14:13.431763Z","time spent":"311.412742ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:15:52.500491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.28127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-09T06:15:52.500574Z","caller":"traceutil/trace.go:171","msg":"trace[627551507] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2740; }","duration":"187.409224ms","start":"2026-09-09T06:15:52.313145Z","end":"2026-09-09T06:15:52.500555Z","steps":["trace[627551507] 'range keys from in-memory index tree' (duration: 187.113384ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:15:52.501071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.843804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:15:52.501152Z","caller":"traceutil/trace.go:171","msg":"trace[705767976] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2740; }","duration":"148.005559ms","start":"2026-09-09T06:15:52.353129Z","end":"2026-09-09T06:15:52.501134Z","steps":["trace[705767976] 'count revisions from in-memory index tree' (duration: 147.75228ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:15:57.750193Z","caller":"traceutil/trace.go:171","msg":"trace[1794487128] transaction","detail":"{read_only:false; response_revision:2770; number_of_response:1; }","duration":"106.097776ms","start":"2026-09-09T06:15:57.644073Z","end":"2026-09-09T06:15:57.750171Z","steps":["trace[1794487128] 'process raft request' (duration: 105.868292ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:15:57.905815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.683856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7460"} {"level":"info","ts":"2026-09-09T06:15:57.905896Z","caller":"traceutil/trace.go:171","msg":"trace[2046214474] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2770; }","duration":"106.798458ms","start":"2026-09-09T06:15:57.799079Z","end":"2026-09-09T06:15:57.905878Z","steps":["trace[2046214474] 'range keys from in-memory index tree' (duration: 106.468012ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:11.982985Z","caller":"traceutil/trace.go:171","msg":"trace[1852289120] transaction","detail":"{read_only:false; response_revision:4566; number_of_response:1; }","duration":"103.329333ms","start":"2026-09-09T06:20:11.879642Z","end":"2026-09-09T06:20:11.982971Z","steps":["trace[1852289120] 'process raft request' (duration: 103.131447ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:11.983199Z","caller":"traceutil/trace.go:171","msg":"trace[150660160] transaction","detail":"{read_only:false; response_revision:4567; number_of_response:1; }","duration":"101.405583ms","start":"2026-09-09T06:20:11.881788Z","end":"2026-09-09T06:20:11.983194Z","steps":["trace[150660160] 'process raft request' (duration: 101.063303ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:11.98324Z","caller":"traceutil/trace.go:171","msg":"trace[277995475] transaction","detail":"{read_only:false; response_revision:4568; number_of_response:1; }","duration":"101.493576ms","start":"2026-09-09T06:20:11.881733Z","end":"2026-09-09T06:20:11.983227Z","steps":["trace[277995475] 'process raft request' (duration: 101.365053ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:11.983368Z","caller":"traceutil/trace.go:171","msg":"trace[1303942094] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4568; }","duration":"101.433935ms","start":"2026-09-09T06:20:11.881916Z","end":"2026-09-09T06:20:11.983349Z","steps":["trace[1303942094] 'process raft request' (duration: 101.2535ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:15.203158Z","caller":"traceutil/trace.go:171","msg":"trace[2140084479] transaction","detail":"{read_only:false; response_revision:4706; number_of_response:1; }","duration":"111.172432ms","start":"2026-09-09T06:20:15.091968Z","end":"2026-09-09T06:20:15.203141Z","steps":["trace[2140084479] 'process raft request' (duration: 111.048187ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:15.203353Z","caller":"traceutil/trace.go:171","msg":"trace[1446652505] transaction","detail":"{read_only:false; response_revision:4707; number_of_response:1; }","duration":"111.290855ms","start":"2026-09-09T06:20:15.092055Z","end":"2026-09-09T06:20:15.203346Z","steps":["trace[1446652505] 'process raft request' (duration: 111.036407ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:15.20366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.623357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:15236"} {"level":"info","ts":"2026-09-09T06:20:15.203825Z","caller":"traceutil/trace.go:171","msg":"trace[1520970275] range","detail":"{range_begin:/registry/statefulsets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4707; }","duration":"114.807643ms","start":"2026-09-09T06:20:15.089007Z","end":"2026-09-09T06:20:15.203814Z","steps":["trace[1520970275] 'agreement among raft nodes before linearized reading' (duration: 114.590637ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:15.203919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.962128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:7 size:61054"} {"level":"info","ts":"2026-09-09T06:20:15.203985Z","caller":"traceutil/trace.go:171","msg":"trace[353871888] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:7; response_revision:4707; }","duration":"115.04314ms","start":"2026-09-09T06:20:15.088929Z","end":"2026-09-09T06:20:15.203973Z","steps":["trace[353871888] 'agreement among raft nodes before linearized reading' (duration: 114.754891ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:21.407256Z","caller":"traceutil/trace.go:171","msg":"trace[1673090745] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"104.253269ms","start":"2026-09-09T06:20:21.302969Z","end":"2026-09-09T06:20:21.407222Z","steps":["trace[1673090745] 'process raft request' (duration: 104.039342ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:27.076328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.101541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:20:27.076433Z","caller":"traceutil/trace.go:171","msg":"trace[1159774977] linearizableReadLoop","detail":"{readStateIndex:5131; appliedIndex:5130; }","duration":"336.502487ms","start":"2026-09-09T06:20:26.739913Z","end":"2026-09-09T06:20:27.076415Z","steps":["trace[1159774977] 'read index received' (duration: 170.154607ms)","trace[1159774977] 'applied index is now lower than readState.Index' (duration: 166.34666ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:20:27.076862Z","caller":"traceutil/trace.go:171","msg":"trace[1964601811] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"378.236968ms","start":"2026-09-09T06:20:26.69859Z","end":"2026-09-09T06:20:27.076827Z","steps":["trace[1964601811] 'process raft request' (duration: 211.549158ms)","trace[1964601811] 'compare' (duration: 165.951907ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:20:27.076801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.387235ms","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-09-09T06:20:27.077032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:20:26.698574Z","time spent":"378.388472ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:20:27.07706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.142516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-09T06:20:27.077139Z","caller":"traceutil/trace.go:171","msg":"trace[1706808194] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4941; }","duration":"337.237509ms","start":"2026-09-09T06:20:26.739888Z","end":"2026-09-09T06:20:27.077126Z","steps":["trace[1706808194] 'agreement among raft nodes before linearized reading' (duration: 336.882019ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:27.077525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:20:26.739874Z","time spent":"337.60288ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-09T06:20:27.077076Z","caller":"traceutil/trace.go:171","msg":"trace[1147434749] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:4941; }","duration":"317.728565ms","start":"2026-09-09T06:20:26.759332Z","end":"2026-09-09T06:20:27.077061Z","steps":["trace[1147434749] 'agreement among raft nodes before linearized reading' (duration: 317.376585ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:27.077824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:20:26.759308Z","time spent":"318.467469ms","remote":"127.0.0.1:37998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-09-09T06:20:27.077361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.345617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:20:27.077985Z","caller":"traceutil/trace.go:171","msg":"trace[276392399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4941; }","duration":"165.001689ms","start":"2026-09-09T06:20:26.912968Z","end":"2026-09-09T06:20:27.077969Z","steps":["trace[276392399] 'agreement among raft nodes before linearized reading' (duration: 164.353358ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:27.323677Z","caller":"traceutil/trace.go:171","msg":"trace[1316810513] linearizableReadLoop","detail":"{readStateIndex:5133; appliedIndex:5132; }","duration":"166.865088ms","start":"2026-09-09T06:20:27.156786Z","end":"2026-09-09T06:20:27.323651Z","steps":["trace[1316810513] 'read index received' (duration: 166.641311ms)","trace[1316810513] 'applied index is now lower than readState.Index' (duration: 222.917µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:20:27.323994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.188517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-586ab19b-d6ce-479b-8856-9ade4d9474b4\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-09T06:20:27.323975Z","caller":"traceutil/trace.go:171","msg":"trace[1165371097] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"240.898251ms","start":"2026-09-09T06:20:27.083049Z","end":"2026-09-09T06:20:27.323947Z","steps":["trace[1165371097] 'process raft request' (duration: 240.454857ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:27.324064Z","caller":"traceutil/trace.go:171","msg":"trace[920274930] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-586ab19b-d6ce-479b-8856-9ade4d9474b4; range_end:; response_count:1; response_revision:4943; }","duration":"167.27988ms","start":"2026-09-09T06:20:27.156742Z","end":"2026-09-09T06:20:27.324022Z","steps":["trace[920274930] 'agreement among raft nodes before linearized reading' (duration: 167.003451ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:27.658628Z","caller":"traceutil/trace.go:171","msg":"trace[1205074299] linearizableReadLoop","detail":"{readStateIndex:5135; appliedIndex:5134; }","duration":"174.342029ms","start":"2026-09-09T06:20:27.484262Z","end":"2026-09-09T06:20:27.658604Z","steps":["trace[1205074299] 'read index received' (duration: 173.920696ms)","trace[1205074299] 'applied index is now lower than readState.Index' (duration: 420.273µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:20:27.658718Z","caller":"traceutil/trace.go:171","msg":"trace[1662449980] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"193.894824ms","start":"2026-09-09T06:20:27.464774Z","end":"2026-09-09T06:20:27.658668Z","steps":["trace[1662449980] 'process raft request' (duration: 193.667927ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:27.659791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.383801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-09T06:20:27.659883Z","caller":"traceutil/trace.go:171","msg":"trace[897017060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4945; }","duration":"175.652589ms","start":"2026-09-09T06:20:27.484218Z","end":"2026-09-09T06:20:27.65987Z","steps":["trace[897017060] 'agreement among raft nodes before linearized reading' (duration: 174.502914ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:31.646782Z","caller":"traceutil/trace.go:171","msg":"trace[626051326] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"119.051204ms","start":"2026-09-09T06:20:31.52771Z","end":"2026-09-09T06:20:31.646761Z","steps":["trace[626051326] 'process raft request' (duration: 118.92996ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:31.64697Z","caller":"traceutil/trace.go:171","msg":"trace[1016462912] linearizableReadLoop","detail":"{readStateIndex:5199; appliedIndex:5199; }","duration":"109.046634ms","start":"2026-09-09T06:20:31.537891Z","end":"2026-09-09T06:20:31.646938Z","steps":["trace[1016462912] 'read index received' (duration: 109.033574ms)","trace[1016462912] 'applied index is now lower than readState.Index' (duration: 10.63µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:20:31.647131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.205249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:20:31.647194Z","caller":"traceutil/trace.go:171","msg":"trace[1877442193] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:5007; }","duration":"109.305872ms","start":"2026-09-09T06:20:31.537868Z","end":"2026-09-09T06:20:31.647173Z","steps":["trace[1877442193] 'agreement among raft nodes before linearized reading' (duration: 109.185319ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:31.653935Z","caller":"traceutil/trace.go:171","msg":"trace[2048158622] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"104.650167ms","start":"2026-09-09T06:20:31.549258Z","end":"2026-09-09T06:20:31.653908Z","steps":["trace[2048158622] 'process raft request' (duration: 104.493662ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:41.223993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.420778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:20:41.224097Z","caller":"traceutil/trace.go:171","msg":"trace[635243390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5129; }","duration":"311.574063ms","start":"2026-09-09T06:20:40.912506Z","end":"2026-09-09T06:20:41.22408Z","steps":["trace[635243390] 'range keys from in-memory index tree' (duration: 311.300503ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:41.224133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:20:40.912484Z","time spent":"311.640354ms","remote":"127.0.0.1:42792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-09T06:20:41.224172Z","caller":"traceutil/trace.go:171","msg":"trace[1095415886] transaction","detail":"{read_only:false; response_revision:5130; number_of_response:1; }","duration":"211.492613ms","start":"2026-09-09T06:20:41.012657Z","end":"2026-09-09T06:20:41.22415Z","steps":["trace[1095415886] 'process raft request' (duration: 171.510476ms)","trace[1095415886] 'compare' (duration: 39.684438ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:20:41.421601Z","caller":"traceutil/trace.go:171","msg":"trace[1568993048] linearizableReadLoop","detail":"{readStateIndex:5331; appliedIndex:5330; }","duration":"101.879496ms","start":"2026-09-09T06:20:41.319686Z","end":"2026-09-09T06:20:41.421566Z","steps":["trace[1568993048] 'read index received' (duration: 43.126585ms)","trace[1568993048] 'applied index is now lower than readState.Index' (duration: 58.750861ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:20:41.421735Z","caller":"traceutil/trace.go:171","msg":"trace[1353473044] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"105.190589ms","start":"2026-09-09T06:20:41.316528Z","end":"2026-09-09T06:20:41.421718Z","steps":["trace[1353473044] 'process raft request' (duration: 46.559071ms)","trace[1353473044] 'compare' (duration: 58.214285ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:20:41.422014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.308339ms","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-09-09T06:20:41.422066Z","caller":"traceutil/trace.go:171","msg":"trace[2030470427] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5134; }","duration":"102.372281ms","start":"2026-09-09T06:20:41.319676Z","end":"2026-09-09T06:20:41.422048Z","steps":["trace[2030470427] 'agreement among raft nodes before linearized reading' (duration: 102.192476ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:41.636847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.817443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:20:41.637096Z","caller":"traceutil/trace.go:171","msg":"trace[180898701] linearizableReadLoop","detail":"{readStateIndex:5334; appliedIndex:5333; }","duration":"130.175798ms","start":"2026-09-09T06:20:41.506859Z","end":"2026-09-09T06:20:41.637034Z","steps":["trace[180898701] 'read index received' (duration: 20.971353ms)","trace[180898701] 'applied index is now lower than readState.Index' (duration: 109.202585ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:20:41.637293Z","caller":"traceutil/trace.go:171","msg":"trace[2071284817] transaction","detail":"{read_only:false; response_revision:5137; number_of_response:1; }","duration":"186.916887ms","start":"2026-09-09T06:20:41.450226Z","end":"2026-09-09T06:20:41.637143Z","steps":["trace[2071284817] 'process raft request' (duration: 77.726743ms)","trace[2071284817] 'compare' (duration: 108.691499ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:20:41.637508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.586621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-8b3cbd4e-9ad8-432c-8d67-1e12f718febe\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-09-09T06:20:41.637618Z","caller":"traceutil/trace.go:171","msg":"trace[2127287807] range","detail":"{range_begin:/registry/persistentvolumes/pvc-8b3cbd4e-9ad8-432c-8d67-1e12f718febe; range_end:; response_count:1; response_revision:5137; }","duration":"130.777726ms","start":"2026-09-09T06:20:41.506818Z","end":"2026-09-09T06:20:41.637596Z","steps":["trace[2127287807] 'agreement among raft nodes before linearized reading' (duration: 130.420385ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:21:09.851938Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2834} {"level":"info","ts":"2026-09-09T06:21:09.941196Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2834,"took":"88.025335ms","hash":2162230708,"current-db-size-bytes":51269632,"current-db-size":"51 MB","current-db-size-in-use-bytes":27557888,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T06:21:09.941261Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2162230708,"revision":2834,"compact-revision":-1} {"level":"info","ts":"2026-09-09T06:21:25.530951Z","caller":"traceutil/trace.go:171","msg":"trace[597630111] transaction","detail":"{read_only:false; response_revision:5495; number_of_response:1; }","duration":"102.680975ms","start":"2026-09-09T06:21:25.428237Z","end":"2026-09-09T06:21:25.530918Z","steps":["trace[597630111] 'process raft request' (duration: 85.516421ms)","trace[597630111] 'compare' (duration: 16.555365ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:24:47.679996Z","caller":"traceutil/trace.go:171","msg":"trace[344032778] transaction","detail":"{read_only:false; response_revision:6919; number_of_response:1; }","duration":"135.802455ms","start":"2026-09-09T06:24:47.54417Z","end":"2026-09-09T06:24:47.679972Z","steps":["trace[344032778] 'process raft request' (duration: 135.700522ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:09.859985Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5317} {"level":"info","ts":"2026-09-09T06:26:09.942252Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5317,"took":"81.295075ms","hash":965622866,"current-db-size-bytes":51269632,"current-db-size":"51 MB","current-db-size-in-use-bytes":18456576,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-09-09T06:26:09.942304Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":965622866,"revision":5317,"compact-revision":2834}