{"level":"warn","ts":"2026-10-09T05:17:57.307339Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T05:17:57.30743Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.36: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.36:2380","--initial-cluster=instance=https://162.253.55.36:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.36:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.36:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-09T05:17:57.307496Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T05:17:57.307504Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-10-09T05:17:57.307536Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-09T05:17:57.308112Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"]} {"level":"info","ts":"2026-10-09T05:17:57.308195Z","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.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.36:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-09T05:17:57.314832Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.389287ms"} {"level":"info","ts":"2026-10-09T05:17:57.322471Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cb89ec34a9687f0b","cluster-id":"d8e0d5e222e856c9"} {"level":"info","ts":"2026-10-09T05:17:57.322565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=()"} {"level":"info","ts":"2026-10-09T05:17:57.322586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 0"} {"level":"info","ts":"2026-10-09T05:17:57.322599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cb89ec34a9687f0b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-09T05:17:57.322604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 1"} {"level":"info","ts":"2026-10-09T05:17:57.322639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"warn","ts":"2026-10-09T05:17:57.328071Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-09T05:17:57.329731Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-09T05:17:57.331543Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-09T05:17:57.333773Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cb89ec34a9687f0b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-09T05:17:57.334834Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cb89ec34a9687f0b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-09T05:17:57.336191Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-09T05:17:57.336351Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-09T05:17:57.336372Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-09T05:17:57.336857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"info","ts":"2026-10-09T05:17:57.342878Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","added-peer-id":"cb89ec34a9687f0b","added-peer-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-10-09T05:17:57.343672Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-09T05:17:57.343776Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-10-09T05:17:57.343818Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-10-09T05:17:57.343959Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cb89ec34a9687f0b","initial-advertise-peer-urls":["https://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-09T05:17:57.34401Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-09T05:17:57.523307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b is starting a new election at term 1"} {"level":"info","ts":"2026-10-09T05:17:57.523454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-09T05:17:57.523519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgPreVoteResp from cb89ec34a9687f0b at term 1"} {"level":"info","ts":"2026-10-09T05:17:57.523604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became candidate at term 2"} {"level":"info","ts":"2026-10-09T05:17:57.523644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgVoteResp from cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-10-09T05:17:57.523686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became leader at term 2"} {"level":"info","ts":"2026-10-09T05:17:57.523729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cb89ec34a9687f0b elected leader cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-10-09T05:17:57.524616Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cb89ec34a9687f0b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.36:2379]}","request-path":"/0/members/cb89ec34a9687f0b/attributes","cluster-id":"d8e0d5e222e856c9","publish-timeout":"7s"} {"level":"info","ts":"2026-10-09T05:17:57.524826Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T05:17:57.524974Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T05:17:57.525468Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:17:57.525703Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-09T05:17:57.525737Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-09T05:17:57.527065Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:17:57.527218Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:17:57.527288Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:17:57.528903Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-09T05:17:57.537751Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.36:2379"} {"level":"info","ts":"2026-10-09T05:18:01.724926Z","caller":"traceutil/trace.go:171","msg":"trace[332198641] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"263.227211ms","start":"2026-10-09T05:18:01.46167Z","end":"2026-10-09T05:18:01.724897Z","steps":["trace[332198641] 'process raft request' (duration: 263.072548ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:18:02.111231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.012255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-09T05:18:02.111427Z","caller":"traceutil/trace.go:171","msg":"trace[1646662088] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"553.390172ms","start":"2026-10-09T05:18:01.557998Z","end":"2026-10-09T05:18:02.111388Z","steps":["trace[1646662088] 'process raft request' (duration: 395.574301ms)","trace[1646662088] 'compare' (duration: 156.855342ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:18:02.111589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:18:01.557973Z","time spent":"553.529296ms","remote":"127.0.0.1:40760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-09T05:18:02.111776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.466356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-3sui5x\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:18:02.11182Z","caller":"traceutil/trace.go:171","msg":"trace[1609683412] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-3sui5x; range_end:; response_count:0; response_revision:205; }","duration":"383.552768ms","start":"2026-10-09T05:18:01.728254Z","end":"2026-10-09T05:18:02.111806Z","steps":["trace[1609683412] 'agreement among raft nodes before linearized reading' (duration: 383.309343ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:18:02.111856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:18:01.728238Z","time spent":"383.60971ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-3sui5x\" "} {"level":"info","ts":"2026-10-09T05:18:02.111356Z","caller":"traceutil/trace.go:171","msg":"trace[2078453273] linearizableReadLoop","detail":"{readStateIndex:210; appliedIndex:209; }","duration":"383.047657ms","start":"2026-10-09T05:18:01.728292Z","end":"2026-10-09T05:18:02.11134Z","steps":["trace[2078453273] 'read index received' (duration: 225.189705ms)","trace[2078453273] 'applied index is now lower than readState.Index' (duration: 157.856892ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:18:41.684844Z","caller":"traceutil/trace.go:171","msg":"trace[1876366308] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"121.07531ms","start":"2026-10-09T05:18:41.563751Z","end":"2026-10-09T05:18:41.684826Z","steps":["trace[1876366308] 'process raft request' (duration: 120.966868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:18:43.561284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.841543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-09T05:18:43.56141Z","caller":"traceutil/trace.go:171","msg":"trace[1219653162] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"220.376625ms","start":"2026-10-09T05:18:43.340995Z","end":"2026-10-09T05:18:43.561372Z","steps":["trace[1219653162] 'read index received' (duration: 31.246757ms)","trace[1219653162] 'applied index is now lower than readState.Index' (duration: 189.128508ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:18:43.561455Z","caller":"traceutil/trace.go:171","msg":"trace[1183627254] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"340.194502ms","start":"2026-10-09T05:18:43.221224Z","end":"2026-10-09T05:18:43.561418Z","steps":["trace[1183627254] 'process raft request' (duration: 151.132784ms)","trace[1183627254] 'compare' (duration: 188.470996ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:18:43.561608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:18:43.221207Z","time spent":"340.324664ms","remote":"127.0.0.1:40774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-09T05:18:43.561664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.643451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-10-09T05:18:43.561743Z","caller":"traceutil/trace.go:171","msg":"trace[1082251406] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:625; }","duration":"220.757313ms","start":"2026-10-09T05:18:43.340966Z","end":"2026-10-09T05:18:43.561723Z","steps":["trace[1082251406] 'agreement among raft nodes before linearized reading' (duration: 220.485348ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:18:48.535893Z","caller":"traceutil/trace.go:171","msg":"trace[2077816114] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"246.332432ms","start":"2026-10-09T05:18:48.289535Z","end":"2026-10-09T05:18:48.535868Z","steps":["trace[2077816114] 'process raft request' (duration: 246.175059ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:19:05.170931Z","caller":"traceutil/trace.go:171","msg":"trace[1604522753] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"167.576789ms","start":"2026-10-09T05:19:05.003331Z","end":"2026-10-09T05:19:05.170908Z","steps":["trace[1604522753] 'read index received' (duration: 159.810392ms)","trace[1604522753] 'applied index is now lower than readState.Index' (duration: 7.765457ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:19:05.171149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.806363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-09T05:19:05.171231Z","caller":"traceutil/trace.go:171","msg":"trace[1255360042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:786; }","duration":"167.954726ms","start":"2026-10-09T05:19:05.003242Z","end":"2026-10-09T05:19:05.171197Z","steps":["trace[1255360042] 'agreement among raft nodes before linearized reading' (duration: 167.757281ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:19:05.171252Z","caller":"traceutil/trace.go:171","msg":"trace[161054039] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"174.283848ms","start":"2026-10-09T05:19:04.99694Z","end":"2026-10-09T05:19:05.171224Z","steps":["trace[161054039] 'process raft request' (duration: 166.289947ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:20:25.295865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.69362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a53ec5a6-9123-4c43-99fe-bbd8b9a3b5ab\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-10-09T05:20:25.296065Z","caller":"traceutil/trace.go:171","msg":"trace[1613628672] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a53ec5a6-9123-4c43-99fe-bbd8b9a3b5ab; range_end:; response_count:1; response_revision:1853; }","duration":"188.934364ms","start":"2026-10-09T05:20:25.10711Z","end":"2026-10-09T05:20:25.296044Z","steps":["trace[1613628672] 'range keys from in-memory index tree' (duration: 188.553648ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:20:31.968261Z","caller":"traceutil/trace.go:171","msg":"trace[1609815135] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"140.300587ms","start":"2026-10-09T05:20:31.8279Z","end":"2026-10-09T05:20:31.9682Z","steps":["trace[1609815135] 'process raft request' (duration: 140.126285ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:20:32.087818Z","caller":"traceutil/trace.go:171","msg":"trace[1859117291] linearizableReadLoop","detail":"{readStateIndex:1998; appliedIndex:1997; }","duration":"112.587569ms","start":"2026-10-09T05:20:31.975178Z","end":"2026-10-09T05:20:32.087765Z","steps":["trace[1859117291] 'read index received' (duration: 66.921161ms)","trace[1859117291] 'applied index is now lower than readState.Index' (duration: 45.665368ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:20:32.087938Z","caller":"traceutil/trace.go:171","msg":"trace[907122395] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"113.464034ms","start":"2026-10-09T05:20:31.974459Z","end":"2026-10-09T05:20:32.087923Z","steps":["trace[907122395] 'process raft request' (duration: 67.590102ms)","trace[907122395] 'compare' (duration: 45.596917ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:20:32.088092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.892664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-a53ec5a6-9123-4c43-99fe-bbd8b9a3b5ab\" ","response":"range_response_count:1 size:1303"} {"level":"info","ts":"2026-10-09T05:20:32.088179Z","caller":"traceutil/trace.go:171","msg":"trace[1640191521] range","detail":"{range_begin:/registry/persistentvolumes/pvc-a53ec5a6-9123-4c43-99fe-bbd8b9a3b5ab; range_end:; response_count:1; response_revision:1937; }","duration":"113.008125ms","start":"2026-10-09T05:20:31.975146Z","end":"2026-10-09T05:20:32.088154Z","steps":["trace[1640191521] 'agreement among raft nodes before linearized reading' (duration: 112.814612ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:20:32.098229Z","caller":"traceutil/trace.go:171","msg":"trace[145619203] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"122.885356ms","start":"2026-10-09T05:20:31.975318Z","end":"2026-10-09T05:20:32.098204Z","steps":["trace[145619203] 'process raft request' (duration: 122.706042ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:20:32.09837Z","caller":"traceutil/trace.go:171","msg":"trace[413149972] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"122.653112ms","start":"2026-10-09T05:20:31.975697Z","end":"2026-10-09T05:20:32.098351Z","steps":["trace[413149972] 'process raft request' (duration: 122.407239ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:20:32.098421Z","caller":"traceutil/trace.go:171","msg":"trace[1088039813] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"122.361547ms","start":"2026-10-09T05:20:31.976039Z","end":"2026-10-09T05:20:32.098401Z","steps":["trace[1088039813] 'process raft request' (duration: 122.113273ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:20:32.098415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.178467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-10-09T05:20:32.098544Z","caller":"traceutil/trace.go:171","msg":"trace[203567034] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1940; }","duration":"103.34639ms","start":"2026-10-09T05:20:31.995183Z","end":"2026-10-09T05:20:32.098529Z","steps":["trace[203567034] 'agreement among raft nodes before linearized reading' (duration: 103.010445ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:20:34.655598Z","caller":"traceutil/trace.go:171","msg":"trace[815655149] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"186.580011ms","start":"2026-10-09T05:20:34.468995Z","end":"2026-10-09T05:20:34.655575Z","steps":["trace[815655149] 'process raft request' (duration: 183.582313ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:20:34.657254Z","caller":"traceutil/trace.go:171","msg":"trace[1964066062] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"186.948157ms","start":"2026-10-09T05:20:34.470298Z","end":"2026-10-09T05:20:34.657246Z","steps":["trace[1964066062] 'process raft request' (duration: 186.729964ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:20:58.556292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.510328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-10-09T05:20:58.556375Z","caller":"traceutil/trace.go:171","msg":"trace[1288575166] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2130; }","duration":"142.680854ms","start":"2026-10-09T05:20:58.413677Z","end":"2026-10-09T05:20:58.556357Z","steps":["trace[1288575166] 'range keys from in-memory index tree' (duration: 142.319722ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:20:58.781383Z","caller":"traceutil/trace.go:171","msg":"trace[829930088] linearizableReadLoop","detail":"{readStateIndex:2205; appliedIndex:2203; }","duration":"144.46028ms","start":"2026-10-09T05:20:58.636897Z","end":"2026-10-09T05:20:58.781357Z","steps":["trace[829930088] 'read index received' (duration: 545.297µs)","trace[829930088] 'applied index is now lower than readState.Index' (duration: 143.913363ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:20:58.781753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.80286ms","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-10-09T05:20:58.781842Z","caller":"traceutil/trace.go:171","msg":"trace[1011745156] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2134; }","duration":"145.006106ms","start":"2026-10-09T05:20:58.636813Z","end":"2026-10-09T05:20:58.78182Z","steps":["trace[1011745156] 'agreement among raft nodes before linearized reading' (duration: 144.822951ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:20:58.782033Z","caller":"traceutil/trace.go:171","msg":"trace[842708750] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"177.978559ms","start":"2026-10-09T05:20:58.604032Z","end":"2026-10-09T05:20:58.782011Z","steps":["trace[842708750] 'process raft request' (duration: 176.717109ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:20:58.782582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.373611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T05:20:58.78264Z","caller":"traceutil/trace.go:171","msg":"trace[1575318780] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2134; }","duration":"139.510435ms","start":"2026-10-09T05:20:58.643117Z","end":"2026-10-09T05:20:58.782628Z","steps":["trace[1575318780] 'agreement among raft nodes before linearized reading' (duration: 138.702769ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:22:17.645981Z","caller":"traceutil/trace.go:171","msg":"trace[1439461628] transaction","detail":"{read_only:false; response_revision:2780; number_of_response:1; }","duration":"176.629789ms","start":"2026-10-09T05:22:17.469314Z","end":"2026-10-09T05:22:17.645944Z","steps":["trace[1439461628] 'process raft request' (duration: 152.631691ms)","trace[1439461628] 'compare' (duration: 23.398813ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:22:17.823012Z","caller":"traceutil/trace.go:171","msg":"trace[4635106] linearizableReadLoop","detail":"{readStateIndex:2889; appliedIndex:2888; }","duration":"149.640597ms","start":"2026-10-09T05:22:17.673347Z","end":"2026-10-09T05:22:17.822987Z","steps":["trace[4635106] 'read index received' (duration: 149.41452ms)","trace[4635106] 'applied index is now lower than readState.Index' (duration: 224.877µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:22:17.823153Z","caller":"traceutil/trace.go:171","msg":"trace[15004726] transaction","detail":"{read_only:false; response_revision:2781; number_of_response:1; }","duration":"169.930163ms","start":"2026-10-09T05:22:17.653179Z","end":"2026-10-09T05:22:17.823109Z","steps":["trace[15004726] 'process raft request' (duration: 169.652675ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:22:17.823292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.922543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-10-09T05:22:17.82354Z","caller":"traceutil/trace.go:171","msg":"trace[1168057662] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2781; }","duration":"150.206281ms","start":"2026-10-09T05:22:17.673311Z","end":"2026-10-09T05:22:17.823517Z","steps":["trace[1168057662] 'agreement among raft nodes before linearized reading' (duration: 149.854432ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:24:48.922448Z","caller":"traceutil/trace.go:171","msg":"trace[1192320508] linearizableReadLoop","detail":"{readStateIndex:4757; appliedIndex:4756; }","duration":"144.988752ms","start":"2026-10-09T05:24:48.777436Z","end":"2026-10-09T05:24:48.922424Z","steps":["trace[1192320508] 'read index received' (duration: 144.778608ms)","trace[1192320508] 'applied index is now lower than readState.Index' (duration: 209.214µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:24:48.922536Z","caller":"traceutil/trace.go:171","msg":"trace[874845228] transaction","detail":"{read_only:false; response_revision:4564; number_of_response:1; }","duration":"154.983079ms","start":"2026-10-09T05:24:48.767527Z","end":"2026-10-09T05:24:48.92251Z","steps":["trace[874845228] 'process raft request' (duration: 154.715344ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:24:48.922698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.209417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:24:48.922746Z","caller":"traceutil/trace.go:171","msg":"trace[6083629] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4564; }","duration":"145.348849ms","start":"2026-10-09T05:24:48.777382Z","end":"2026-10-09T05:24:48.922731Z","steps":["trace[6083629] 'agreement among raft nodes before linearized reading' (duration: 145.204246ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:24:48.923465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.836342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-866731d4-7089-4c6b-abd4-6451b4619778\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-09T05:24:48.923508Z","caller":"traceutil/trace.go:171","msg":"trace[1208108792] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-866731d4-7089-4c6b-abd4-6451b4619778; range_end:; response_count:1; response_revision:4564; }","duration":"132.903904ms","start":"2026-10-09T05:24:48.790591Z","end":"2026-10-09T05:24:48.923495Z","steps":["trace[1208108792] 'agreement among raft nodes before linearized reading' (duration: 132.554508ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:25:01.958981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.292042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-10-09T05:25:01.959072Z","caller":"traceutil/trace.go:171","msg":"trace[842910469] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4759; }","duration":"154.391724ms","start":"2026-10-09T05:25:01.80466Z","end":"2026-10-09T05:25:01.959051Z","steps":["trace[842910469] 'range keys from in-memory index tree' (duration: 154.182491ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:25:01.959484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.179758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-10-09T05:25:01.959546Z","caller":"traceutil/trace.go:171","msg":"trace[934015089] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4759; }","duration":"178.928333ms","start":"2026-10-09T05:25:01.780602Z","end":"2026-10-09T05:25:01.959531Z","steps":["trace[934015089] 'range keys from in-memory index tree' (duration: 178.036466ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:25:02.310347Z","caller":"traceutil/trace.go:171","msg":"trace[1952863808] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"317.520685ms","start":"2026-10-09T05:25:01.992802Z","end":"2026-10-09T05:25:02.310322Z","steps":["trace[1952863808] 'process raft request' (duration: 317.340531ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:25:02.310866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:25:01.992781Z","time spent":"317.658797ms","remote":"127.0.0.1:40676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T05:25:02.327038Z","caller":"traceutil/trace.go:171","msg":"trace[35158512] linearizableReadLoop","detail":"{readStateIndex:4965; appliedIndex:4963; }","duration":"279.711756ms","start":"2026-10-09T05:25:02.047303Z","end":"2026-10-09T05:25:02.327014Z","steps":["trace[35158512] 'read index received' (duration: 262.905108ms)","trace[35158512] 'applied index is now lower than readState.Index' (duration: 16.805448ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:25:02.327363Z","caller":"traceutil/trace.go:171","msg":"trace[1663844509] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"333.796032ms","start":"2026-10-09T05:25:01.993541Z","end":"2026-10-09T05:25:02.327337Z","steps":["trace[1663844509] 'process raft request' (duration: 333.340683ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:25:02.327546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:25:01.993522Z","time spent":"333.890363ms","remote":"127.0.0.1:40676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T05:25:02.32812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.772568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-09T05:25:02.328268Z","caller":"traceutil/trace.go:171","msg":"trace[778273338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4762; }","duration":"280.966092ms","start":"2026-10-09T05:25:02.047276Z","end":"2026-10-09T05:25:02.328242Z","steps":["trace[778273338] 'agreement among raft nodes before linearized reading' (duration: 280.028053ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:25:02.327375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.654873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10515"} {"level":"info","ts":"2026-10-09T05:25:02.32876Z","caller":"traceutil/trace.go:171","msg":"trace[924821696] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4762; }","duration":"163.087061ms","start":"2026-10-09T05:25:02.165658Z","end":"2026-10-09T05:25:02.328745Z","steps":["trace[924821696] 'agreement among raft nodes before linearized reading' (duration: 161.614952ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:25:02.435218Z","caller":"traceutil/trace.go:171","msg":"trace[661756085] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"101.47921ms","start":"2026-10-09T05:25:02.33371Z","end":"2026-10-09T05:25:02.435189Z","steps":["trace[661756085] 'process raft request' (duration: 92.520875ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:25:02.435275Z","caller":"traceutil/trace.go:171","msg":"trace[1662020908] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"101.468589ms","start":"2026-10-09T05:25:02.33378Z","end":"2026-10-09T05:25:02.435249Z","steps":["trace[1662020908] 'process raft request' (duration: 101.230894ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:25:02.576216Z","caller":"traceutil/trace.go:171","msg":"trace[1992678477] linearizableReadLoop","detail":"{readStateIndex:4971; appliedIndex:4970; }","duration":"131.70399ms","start":"2026-10-09T05:25:02.444489Z","end":"2026-10-09T05:25:02.576193Z","steps":["trace[1992678477] 'read index received' (duration: 4.375136ms)","trace[1992678477] 'applied index is now lower than readState.Index' (duration: 127.325624ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:25:02.57635Z","caller":"traceutil/trace.go:171","msg":"trace[891916643] transaction","detail":"{read_only:false; response_revision:4770; number_of_response:1; }","duration":"131.620857ms","start":"2026-10-09T05:25:02.444716Z","end":"2026-10-09T05:25:02.576337Z","steps":["trace[891916643] 'process raft request' (duration: 131.389643ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:25:02.576479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.968905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:25:02.576672Z","caller":"traceutil/trace.go:171","msg":"trace[1473206133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4771; }","duration":"132.173058ms","start":"2026-10-09T05:25:02.444448Z","end":"2026-10-09T05:25:02.576621Z","steps":["trace[1473206133] 'agreement among raft nodes before linearized reading' (duration: 131.916923ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:25:02.576909Z","caller":"traceutil/trace.go:171","msg":"trace[1890441577] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"132.478064ms","start":"2026-10-09T05:25:02.444414Z","end":"2026-10-09T05:25:02.576892Z","steps":["trace[1890441577] 'process raft request' (duration: 104.585189ms)","trace[1890441577] 'compare' (duration: 26.905166ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:25:02.577077Z","caller":"traceutil/trace.go:171","msg":"trace[517195460] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"131.18545ms","start":"2026-10-09T05:25:02.445875Z","end":"2026-10-09T05:25:02.577061Z","steps":["trace[517195460] 'process raft request' (duration: 130.276612ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:25:02.57724Z","caller":"traceutil/trace.go:171","msg":"trace[1576434833] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"132.73926ms","start":"2026-10-09T05:25:02.444482Z","end":"2026-10-09T05:25:02.577221Z","steps":["trace[1576434833] 'process raft request' (duration: 131.579117ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:27:57.62769Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3122} {"level":"info","ts":"2026-10-09T05:27:57.755553Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3122,"took":"126.428344ms","hash":3225626368,"current-db-size-bytes":57892864,"current-db-size":"58 MB","current-db-size-in-use-bytes":31309824,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T05:27:57.755617Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3225626368,"revision":3122,"compact-revision":-1}