{"level":"warn","ts":"2026-10-26T21:19:44.724583Z","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-26T21:19:44.724685Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.10: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.10:2380","--initial-cluster=instance=https://162.253.55.10:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.10:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.10: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-26T21:19:44.724764Z","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-26T21:19:44.724774Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.10:2380"]} {"level":"info","ts":"2026-10-26T21:19:44.724797Z","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-26T21:19:44.725625Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"]} {"level":"info","ts":"2026-10-26T21:19:44.725736Z","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.10:2380"],"listen-peer-urls":["https://162.253.55.10:2380"],"advertise-client-urls":["https://162.253.55.10:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.10: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-26T21:19:44.730693Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.704313ms"} {"level":"info","ts":"2026-10-26T21:19:44.738639Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"373d5d49ce4adaff","cluster-id":"467836e907a15d60"} {"level":"info","ts":"2026-10-26T21:19:44.738687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T21:19:44.738705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became follower at term 0"} {"level":"info","ts":"2026-10-26T21:19:44.738713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 373d5d49ce4adaff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T21:19:44.73872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became follower at term 1"} {"level":"info","ts":"2026-10-26T21:19:44.738789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=(3980440217240394495)"} {"level":"warn","ts":"2026-10-26T21:19:44.749566Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T21:19:44.751076Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T21:19:44.752154Z","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-26T21:19:44.754072Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"373d5d49ce4adaff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T21:19:44.754176Z","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-26T21:19:44.754233Z","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-26T21:19:44.754242Z","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-26T21:19:44.7544Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"373d5d49ce4adaff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T21:19:44.756467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=(3980440217240394495)"} {"level":"info","ts":"2026-10-26T21:19:44.756704Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"467836e907a15d60","local-member-id":"373d5d49ce4adaff","added-peer-id":"373d5d49ce4adaff","added-peer-peer-urls":["https://162.253.55.10:2380"]} {"level":"info","ts":"2026-10-26T21:19:44.760343Z","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-26T21:19:44.760468Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.10:2380"} {"level":"info","ts":"2026-10-26T21:19:44.760584Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.10:2380"} {"level":"info","ts":"2026-10-26T21:19:44.760857Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"373d5d49ce4adaff","initial-advertise-peer-urls":["https://162.253.55.10:2380"],"listen-peer-urls":["https://162.253.55.10:2380"],"advertise-client-urls":["https://162.253.55.10:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T21:19:44.760927Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T21:19:44.839518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T21:19:44.839591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T21:19:44.839613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff received MsgPreVoteResp from 373d5d49ce4adaff at term 1"} {"level":"info","ts":"2026-10-26T21:19:44.839729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became candidate at term 2"} {"level":"info","ts":"2026-10-26T21:19:44.839741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff received MsgVoteResp from 373d5d49ce4adaff at term 2"} {"level":"info","ts":"2026-10-26T21:19:44.839801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became leader at term 2"} {"level":"info","ts":"2026-10-26T21:19:44.839815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 373d5d49ce4adaff elected leader 373d5d49ce4adaff at term 2"} {"level":"info","ts":"2026-10-26T21:19:44.840636Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T21:19:44.841389Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"373d5d49ce4adaff","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.10:2379]}","request-path":"/0/members/373d5d49ce4adaff/attributes","cluster-id":"467836e907a15d60","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T21:19:44.841397Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T21:19:44.841657Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T21:19:44.841673Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T21:19:44.841785Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"467836e907a15d60","local-member-id":"373d5d49ce4adaff","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T21:19:44.841873Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T21:19:44.841891Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T21:19:44.84328Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T21:19:44.843628Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T21:19:44.844956Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.10:2379"} {"level":"warn","ts":"2026-10-26T21:19:48.859307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.106859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T21:19:48.859509Z","caller":"traceutil/trace.go:171","msg":"trace[1262132408] linearizableReadLoop","detail":"{readStateIndex:231; appliedIndex:230; }","duration":"178.922711ms","start":"2026-10-26T21:19:48.680519Z","end":"2026-10-26T21:19:48.859442Z","steps":["trace[1262132408] 'read index received' (duration: 106.574µs)","trace[1262132408] 'applied index is now lower than readState.Index' (duration: 178.814897ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:19:48.85966Z","caller":"traceutil/trace.go:171","msg":"trace[1258913544] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"407.20435ms","start":"2026-10-26T21:19:48.452412Z","end":"2026-10-26T21:19:48.859616Z","steps":["trace[1258913544] 'process raft request' (duration: 166.158133ms)","trace[1258913544] 'compare' (duration: 239.892451ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:19:48.859724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.182081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:19:48.859756Z","caller":"traceutil/trace.go:171","msg":"trace[1705073534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:226; }","duration":"179.290236ms","start":"2026-10-26T21:19:48.680455Z","end":"2026-10-26T21:19:48.859745Z","steps":["trace[1705073534] 'agreement among raft nodes before linearized reading' (duration: 179.15131ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:19:48.859808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:19:48.452395Z","time spent":"407.344454ms","remote":"127.0.0.1:52264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":841,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T21:19:49.045569Z","caller":"traceutil/trace.go:171","msg":"trace[180543504] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"178.352466ms","start":"2026-10-26T21:19:48.867177Z","end":"2026-10-26T21:19:49.045529Z","steps":["trace[180543504] 'process raft request' (duration: 177.856336ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:20:08.247798Z","caller":"traceutil/trace.go:171","msg":"trace[2038678470] linearizableReadLoop","detail":"{readStateIndex:441; appliedIndex:440; }","duration":"120.180832ms","start":"2026-10-26T21:20:08.127582Z","end":"2026-10-26T21:20:08.247762Z","steps":["trace[2038678470] 'read index received' (duration: 119.919211ms)","trace[2038678470] 'applied index is now lower than readState.Index' (duration: 260.481µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:20:08.247869Z","caller":"traceutil/trace.go:171","msg":"trace[1270687089] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"174.414222ms","start":"2026-10-26T21:20:08.073379Z","end":"2026-10-26T21:20:08.247793Z","steps":["trace[1270687089] 'process raft request' (duration: 174.202653ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:20:08.24809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.479874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-26T21:20:08.248186Z","caller":"traceutil/trace.go:171","msg":"trace[1779016676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:431; }","duration":"120.631901ms","start":"2026-10-26T21:20:08.12753Z","end":"2026-10-26T21:20:08.248162Z","steps":["trace[1779016676] 'agreement among raft nodes before linearized reading' (duration: 120.35913ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:20:41.051246Z","caller":"traceutil/trace.go:171","msg":"trace[1971824053] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"372.934726ms","start":"2026-10-26T21:20:40.678282Z","end":"2026-10-26T21:20:41.051217Z","steps":["trace[1971824053] 'process raft request' (duration: 372.268945ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:20:41.051781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:20:40.678265Z","time spent":"373.069623ms","remote":"127.0.0.1:51998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036253,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T21:20:41.635679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.11488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:20:41.635767Z","caller":"traceutil/trace.go:171","msg":"trace[1059653659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:662; }","duration":"527.241985ms","start":"2026-10-26T21:20:41.108493Z","end":"2026-10-26T21:20:41.635735Z","steps":["trace[1059653659] 'range keys from in-memory index tree' (duration: 527.050376ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:20:41.635808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:20:41.108477Z","time spent":"527.322359ms","remote":"127.0.0.1:51922","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-10-26T21:20:41.635885Z","caller":"traceutil/trace.go:171","msg":"trace[234980429] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"453.605323ms","start":"2026-10-26T21:20:41.182244Z","end":"2026-10-26T21:20:41.635849Z","steps":["trace[234980429] 'read index received' (duration: 449.327469ms)","trace[234980429] 'applied index is now lower than readState.Index' (duration: 4.276454ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:20:41.635993Z","caller":"traceutil/trace.go:171","msg":"trace[2047722446] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"501.721801ms","start":"2026-10-26T21:20:41.134246Z","end":"2026-10-26T21:20:41.635967Z","steps":["trace[2047722446] 'process raft request' (duration: 497.450948ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:20:41.636127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:20:41.134209Z","time spent":"501.855948ms","remote":"127.0.0.1:52282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T21:20:41.636184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.940599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zvghp\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-26T21:20:41.636259Z","caller":"traceutil/trace.go:171","msg":"trace[1839833809] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zvghp; range_end:; response_count:1; response_revision:663; }","duration":"454.067105ms","start":"2026-10-26T21:20:41.182176Z","end":"2026-10-26T21:20:41.636243Z","steps":["trace[1839833809] 'agreement among raft nodes before linearized reading' (duration: 453.805763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:20:41.636278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.78409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-10-26T21:20:41.6363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:20:41.182159Z","time spent":"454.128718ms","remote":"127.0.0.1:52068","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-zvghp\" "} {"level":"info","ts":"2026-10-26T21:20:41.63631Z","caller":"traceutil/trace.go:171","msg":"trace[596003544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:663; }","duration":"388.917876ms","start":"2026-10-26T21:20:41.247382Z","end":"2026-10-26T21:20:41.6363Z","steps":["trace[596003544] 'agreement among raft nodes before linearized reading' (duration: 388.734277ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:20:41.636364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:20:41.247346Z","time spent":"388.98608ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-26T21:20:43.590304Z","caller":"traceutil/trace.go:171","msg":"trace[229246964] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"181.252594ms","start":"2026-10-26T21:20:43.409002Z","end":"2026-10-26T21:20:43.590255Z","steps":["trace[229246964] 'process raft request' (duration: 181.033673ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:20:43.611229Z","caller":"traceutil/trace.go:171","msg":"trace[1677291402] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"189.051526ms","start":"2026-10-26T21:20:43.422141Z","end":"2026-10-26T21:20:43.611193Z","steps":["trace[1677291402] 'process raft request' (duration: 188.836396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:20:56.690855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.420059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T21:20:56.691244Z","caller":"traceutil/trace.go:171","msg":"trace[695731401] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"381.11233ms","start":"2026-10-26T21:20:56.310112Z","end":"2026-10-26T21:20:56.691224Z","steps":["trace[695731401] 'process raft request' (duration: 203.206347ms)","trace[695731401] 'compare' (duration: 177.22865ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:20:56.691317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:20:56.310094Z","time spent":"381.187533ms","remote":"127.0.0.1:52068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T21:21:29.664055Z","caller":"traceutil/trace.go:171","msg":"trace[397708822] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"153.330974ms","start":"2026-10-26T21:21:29.510697Z","end":"2026-10-26T21:21:29.664028Z","steps":["trace[397708822] 'process raft request' (duration: 152.815257ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:21:45.117505Z","caller":"traceutil/trace.go:171","msg":"trace[684613345] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"101.051265ms","start":"2026-10-26T21:21:45.01643Z","end":"2026-10-26T21:21:45.117481Z","steps":["trace[684613345] 'process raft request' (duration: 100.234943ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:22:20.347787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.810205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T21:22:20.347987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.440542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-26T21:22:20.348085Z","caller":"traceutil/trace.go:171","msg":"trace[926366413] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1926; }","duration":"121.609291ms","start":"2026-10-26T21:22:20.226452Z","end":"2026-10-26T21:22:20.348061Z","steps":["trace[926366413] 'range keys from in-memory index tree' (duration: 121.236812ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:22:20.347978Z","caller":"traceutil/trace.go:171","msg":"trace[406274934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1926; }","duration":"245.061138ms","start":"2026-10-26T21:22:20.102898Z","end":"2026-10-26T21:22:20.347959Z","steps":["trace[406274934] 'range keys from in-memory index tree' (duration: 244.639966ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:22:20.348404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.868957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-26T21:22:20.348481Z","caller":"traceutil/trace.go:171","msg":"trace[693535333] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1926; }","duration":"294.003305ms","start":"2026-10-26T21:22:20.054462Z","end":"2026-10-26T21:22:20.348465Z","steps":["trace[693535333] 'range keys from in-memory index tree' (duration: 293.73439ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:22:32.758164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.868719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:22:32.758255Z","caller":"traceutil/trace.go:171","msg":"trace[453863149] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2032; }","duration":"132.027448ms","start":"2026-10-26T21:22:32.626208Z","end":"2026-10-26T21:22:32.758236Z","steps":["trace[453863149] 'count revisions from in-memory index tree' (duration: 131.768031ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:22:37.252633Z","caller":"traceutil/trace.go:171","msg":"trace[1536954697] linearizableReadLoop","detail":"{readStateIndex:2140; appliedIndex:2139; }","duration":"150.918845ms","start":"2026-10-26T21:22:37.101672Z","end":"2026-10-26T21:22:37.25259Z","steps":["trace[1536954697] 'read index received' (duration: 150.839486ms)","trace[1536954697] 'applied index is now lower than readState.Index' (duration: 77.929µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:22:37.252699Z","caller":"traceutil/trace.go:171","msg":"trace[1185150045] transaction","detail":"{read_only:false; response_revision:2061; number_of_response:1; }","duration":"164.313004ms","start":"2026-10-26T21:22:37.08835Z","end":"2026-10-26T21:22:37.252663Z","steps":["trace[1185150045] 'process raft request' (duration: 164.069587ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:22:37.252811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.124523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:22:37.253083Z","caller":"traceutil/trace.go:171","msg":"trace[1581757861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2061; }","duration":"151.461209ms","start":"2026-10-26T21:22:37.101608Z","end":"2026-10-26T21:22:37.253069Z","steps":["trace[1581757861] 'agreement among raft nodes before linearized reading' (duration: 151.140442ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:22:37.257863Z","caller":"traceutil/trace.go:171","msg":"trace[301599921] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"112.055501ms","start":"2026-10-26T21:22:37.145774Z","end":"2026-10-26T21:22:37.25783Z","steps":["trace[301599921] 'process raft request' (duration: 110.267651ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:22:37.258209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.737688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T21:22:37.258302Z","caller":"traceutil/trace.go:171","msg":"trace[2019742970] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2062; }","duration":"149.882636ms","start":"2026-10-26T21:22:37.108398Z","end":"2026-10-26T21:22:37.25828Z","steps":["trace[2019742970] 'agreement among raft nodes before linearized reading' (duration: 149.719688ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:22:59.534817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.395279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T21:22:59.534917Z","caller":"traceutil/trace.go:171","msg":"trace[1251602835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2186; }","duration":"126.532959ms","start":"2026-10-26T21:22:59.408362Z","end":"2026-10-26T21:22:59.534895Z","steps":["trace[1251602835] 'range keys from in-memory index tree' (duration: 126.23183ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:22:59.534929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.872785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T21:22:59.535329Z","caller":"traceutil/trace.go:171","msg":"trace[2031737033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2186; }","duration":"124.268614ms","start":"2026-10-26T21:22:59.410998Z","end":"2026-10-26T21:22:59.535267Z","steps":["trace[2031737033] 'range keys from in-memory index tree' (duration: 123.714585ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:22:59.649792Z","caller":"traceutil/trace.go:171","msg":"trace[591662045] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"109.636776ms","start":"2026-10-26T21:22:59.540129Z","end":"2026-10-26T21:22:59.649766Z","steps":["trace[591662045] 'process raft request' (duration: 109.483516ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:22:59.657915Z","caller":"traceutil/trace.go:171","msg":"trace[1572061279] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"116.222612ms","start":"2026-10-26T21:22:59.541674Z","end":"2026-10-26T21:22:59.657897Z","steps":["trace[1572061279] 'process raft request' (duration: 116.096742ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:23:00.089161Z","caller":"traceutil/trace.go:171","msg":"trace[671642220] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"103.469622ms","start":"2026-10-26T21:22:59.985636Z","end":"2026-10-26T21:23:00.089106Z","steps":["trace[671642220] 'process raft request' (duration: 103.175402ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:23:00.477553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.03346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T21:23:00.478091Z","caller":"traceutil/trace.go:171","msg":"trace[2082448429] linearizableReadLoop","detail":"{readStateIndex:2276; appliedIndex:2275; }","duration":"375.577587ms","start":"2026-10-26T21:23:00.102488Z","end":"2026-10-26T21:23:00.478066Z","steps":["trace[2082448429] 'read index received' (duration: 49.945578ms)","trace[2082448429] 'applied index is now lower than readState.Index' (duration: 325.630039ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:23:00.478083Z","caller":"traceutil/trace.go:171","msg":"trace[1888218277] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"384.367791ms","start":"2026-10-26T21:23:00.093657Z","end":"2026-10-26T21:23:00.478024Z","steps":["trace[1888218277] 'process raft request' (duration: 58.722702ms)","trace[1888218277] 'compare' (duration: 324.389981ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:23:00.478308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.838056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T21:23:00.478336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:23:00.09361Z","time spent":"384.5801ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T21:23:00.478375Z","caller":"traceutil/trace.go:171","msg":"trace[436208367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2192; }","duration":"375.954946ms","start":"2026-10-26T21:23:00.102399Z","end":"2026-10-26T21:23:00.478354Z","steps":["trace[436208367] 'agreement among raft nodes before linearized reading' (duration: 375.762476ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:23:00.47848Z","caller":"traceutil/trace.go:171","msg":"trace[1367980723] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"366.066674ms","start":"2026-10-26T21:23:00.112371Z","end":"2026-10-26T21:23:00.478438Z","steps":["trace[1367980723] 'process raft request' (duration: 365.606285ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:23:00.478412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:23:00.102373Z","time spent":"376.030526ms","remote":"127.0.0.1:51922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-26T21:23:00.478587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:23:00.112349Z","time spent":"366.180864ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T21:23:00.479635Z","caller":"traceutil/trace.go:171","msg":"trace[1074083819] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"108.62205ms","start":"2026-10-26T21:23:00.370997Z","end":"2026-10-26T21:23:00.479619Z","steps":["trace[1074083819] 'process raft request' (duration: 107.920911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:23:00.753362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.965449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-26T21:23:00.753384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.332632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-26T21:23:00.753493Z","caller":"traceutil/trace.go:171","msg":"trace[515276166] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2193; }","duration":"125.121249ms","start":"2026-10-26T21:23:00.628321Z","end":"2026-10-26T21:23:00.753442Z","steps":["trace[515276166] 'range keys from in-memory index tree' (duration: 124.74178ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:23:00.753506Z","caller":"traceutil/trace.go:171","msg":"trace[1830754195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2193; }","duration":"134.536951ms","start":"2026-10-26T21:23:00.618946Z","end":"2026-10-26T21:23:00.753483Z","steps":["trace[1830754195] 'range keys from in-memory index tree' (duration: 134.019733ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:25:02.715011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.782267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T21:25:02.715309Z","caller":"traceutil/trace.go:171","msg":"trace[1370395770] transaction","detail":"{read_only:false; response_revision:2949; number_of_response:1; }","duration":"156.585987ms","start":"2026-10-26T21:25:02.5587Z","end":"2026-10-26T21:25:02.715286Z","steps":["trace[1370395770] 'process raft request' (duration: 156.457333ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:25:02.715339Z","caller":"traceutil/trace.go:171","msg":"trace[1078576761] transaction","detail":"{read_only:false; response_revision:2948; number_of_response:1; }","duration":"313.057991ms","start":"2026-10-26T21:25:02.402246Z","end":"2026-10-26T21:25:02.715304Z","steps":["trace[1078576761] 'process raft request' (duration: 172.823692ms)","trace[1078576761] 'compare' (duration: 139.549701ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:25:02.715908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:25:02.402222Z","time spent":"313.600285ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T21:25:08.014628Z","caller":"traceutil/trace.go:171","msg":"trace[645798183] transaction","detail":"{read_only:false; response_revision:2978; number_of_response:1; }","duration":"197.720333ms","start":"2026-10-26T21:25:07.816882Z","end":"2026-10-26T21:25:08.014602Z","steps":["trace[645798183] 'process raft request' (duration: 197.582549ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:40.287156Z","caller":"traceutil/trace.go:171","msg":"trace[170107182] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"198.057574ms","start":"2026-10-26T21:29:40.089069Z","end":"2026-10-26T21:29:40.287126Z","steps":["trace[170107182] 'process raft request' (duration: 197.899137ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:40.287167Z","caller":"traceutil/trace.go:171","msg":"trace[950825390] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"201.108126ms","start":"2026-10-26T21:29:40.085988Z","end":"2026-10-26T21:29:40.287096Z","steps":["trace[950825390] 'process raft request' (duration: 183.838325ms)","trace[950825390] 'compare' (duration: 16.947137ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:29:40.287647Z","caller":"traceutil/trace.go:171","msg":"trace[1710298244] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"195.087745ms","start":"2026-10-26T21:29:40.092544Z","end":"2026-10-26T21:29:40.287632Z","steps":["trace[1710298244] 'process raft request' (duration: 194.52512ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:40.287817Z","caller":"traceutil/trace.go:171","msg":"trace[310319361] linearizableReadLoop","detail":"{readStateIndex:5101; appliedIndex:5099; }","duration":"195.510294ms","start":"2026-10-26T21:29:40.092279Z","end":"2026-10-26T21:29:40.28779Z","steps":["trace[310319361] 'read index received' (duration: 177.650586ms)","trace[310319361] 'applied index is now lower than readState.Index' (duration: 17.858348ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:29:40.288122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.811177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T21:29:40.288189Z","caller":"traceutil/trace.go:171","msg":"trace[1146481500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4909; }","duration":"195.918601ms","start":"2026-10-26T21:29:40.092252Z","end":"2026-10-26T21:29:40.288171Z","steps":["trace[1146481500] 'agreement among raft nodes before linearized reading' (duration: 195.638289ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:29:40.288203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.522328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:29:40.288287Z","caller":"traceutil/trace.go:171","msg":"trace[1385016762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4910; }","duration":"185.674794ms","start":"2026-10-26T21:29:40.102594Z","end":"2026-10-26T21:29:40.288268Z","steps":["trace[1385016762] 'agreement among raft nodes before linearized reading' (duration: 185.490776ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:29:40.28859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.023868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-tls\" ","response":"range_response_count:1 size:4316"} {"level":"info","ts":"2026-10-26T21:29:40.288617Z","caller":"traceutil/trace.go:171","msg":"trace[2010849833] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-tls; range_end:; response_count:1; response_revision:4910; }","duration":"115.107332ms","start":"2026-10-26T21:29:40.1735Z","end":"2026-10-26T21:29:40.288608Z","steps":["trace[2010849833] 'agreement among raft nodes before linearized reading' (duration: 114.949205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:29:40.288632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.197058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-tls-l48bl\" ","response":"range_response_count:1 size:2464"} {"level":"info","ts":"2026-10-26T21:29:40.288673Z","caller":"traceutil/trace.go:171","msg":"trace[2101314636] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-tls-l48bl; range_end:; response_count:1; response_revision:4910; }","duration":"169.297921ms","start":"2026-10-26T21:29:40.119363Z","end":"2026-10-26T21:29:40.288661Z","steps":["trace[2101314636] 'agreement among raft nodes before linearized reading' (duration: 169.178086ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:45.195877Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2857} {"level":"info","ts":"2026-10-26T21:29:45.273815Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2857,"took":"76.385962ms","hash":582356449,"current-db-size-bytes":49541120,"current-db-size":"50 MB","current-db-size-in-use-bytes":25903104,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-26T21:29:45.2739Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":582356449,"revision":2857,"compact-revision":-1} {"level":"info","ts":"2026-10-26T21:29:49.479194Z","caller":"traceutil/trace.go:171","msg":"trace[1286649967] linearizableReadLoop","detail":"{readStateIndex:5314; appliedIndex:5313; }","duration":"177.322116ms","start":"2026-10-26T21:29:49.301838Z","end":"2026-10-26T21:29:49.47916Z","steps":["trace[1286649967] 'read index received' (duration: 177.050514ms)","trace[1286649967] 'applied index is now lower than readState.Index' (duration: 269.902µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:29:49.479589Z","caller":"traceutil/trace.go:171","msg":"trace[992156707] transaction","detail":"{read_only:false; response_revision:5114; number_of_response:1; }","duration":"180.412631ms","start":"2026-10-26T21:29:49.299141Z","end":"2026-10-26T21:29:49.479553Z","steps":["trace[992156707] 'process raft request' (duration: 179.797994ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:29:49.480478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.579041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-26T21:29:49.480537Z","caller":"traceutil/trace.go:171","msg":"trace[1869178831] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5114; }","duration":"178.717297ms","start":"2026-10-26T21:29:49.301807Z","end":"2026-10-26T21:29:49.480524Z","steps":["trace[1869178831] 'agreement among raft nodes before linearized reading' (duration: 178.457166ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:55.348889Z","caller":"traceutil/trace.go:171","msg":"trace[1577034142] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"304.192274ms","start":"2026-10-26T21:29:55.04467Z","end":"2026-10-26T21:29:55.348862Z","steps":["trace[1577034142] 'process raft request' (duration: 304.007736ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:29:55.349265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:29:55.044658Z","time spent":"304.402514ms","remote":"127.0.0.1:40474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7638,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T21:29:55.349388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.685898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:29:55.349428Z","caller":"traceutil/trace.go:171","msg":"trace[2060097432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5241; }","duration":"246.790232ms","start":"2026-10-26T21:29:55.102629Z","end":"2026-10-26T21:29:55.349419Z","steps":["trace[2060097432] 'agreement among raft nodes before linearized reading' (duration: 246.696058ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:55.34927Z","caller":"traceutil/trace.go:171","msg":"trace[1025379531] linearizableReadLoop","detail":"{readStateIndex:5443; appliedIndex:5443; }","duration":"246.538041ms","start":"2026-10-26T21:29:55.102703Z","end":"2026-10-26T21:29:55.349241Z","steps":["trace[1025379531] 'read index received' (duration: 246.50605ms)","trace[1025379531] 'applied index is now lower than readState.Index' (duration: 29.431µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:29:55.418075Z","caller":"traceutil/trace.go:171","msg":"trace[1068975774] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"163.238625ms","start":"2026-10-26T21:29:55.254806Z","end":"2026-10-26T21:29:55.418044Z","steps":["trace[1068975774] 'process raft request' (duration: 121.899215ms)","trace[1068975774] 'compare' (duration: 41.016906ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:29:55.419616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.610755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-10-26T21:29:55.41971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.848455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-26T21:29:55.419729Z","caller":"traceutil/trace.go:171","msg":"trace[1963343236] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5242; }","duration":"132.761852ms","start":"2026-10-26T21:29:55.286941Z","end":"2026-10-26T21:29:55.419703Z","steps":["trace[1963343236] 'agreement among raft nodes before linearized reading' (duration: 132.405026ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:55.419802Z","caller":"traceutil/trace.go:171","msg":"trace[987514166] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5242; }","duration":"315.987961ms","start":"2026-10-26T21:29:55.103794Z","end":"2026-10-26T21:29:55.419782Z","steps":["trace[987514166] 'agreement among raft nodes before linearized reading' (duration: 315.678907ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:29:55.419848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:29:55.103775Z","time spent":"316.063704ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-26T21:29:55.419861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.958302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-26T21:29:55.419916Z","caller":"traceutil/trace.go:171","msg":"trace[1790532953] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5242; }","duration":"168.001584ms","start":"2026-10-26T21:29:55.251894Z","end":"2026-10-26T21:29:55.419895Z","steps":["trace[1790532953] 'agreement among raft nodes before linearized reading' (duration: 167.891769ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:29:55.419731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.193247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13119"} {"level":"info","ts":"2026-10-26T21:29:55.420088Z","caller":"traceutil/trace.go:171","msg":"trace[2112613589] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5242; }","duration":"180.588455ms","start":"2026-10-26T21:29:55.239486Z","end":"2026-10-26T21:29:55.420075Z","steps":["trace[2112613589] 'agreement among raft nodes before linearized reading' (duration: 180.02201ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:29:55.420111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.673278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-59599f4c9b-9l797.18e231a6d327ff38\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-10-26T21:29:55.420154Z","caller":"traceutil/trace.go:171","msg":"trace[459323144] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-59599f4c9b-9l797.18e231a6d327ff38; range_end:; response_count:1; response_revision:5242; }","duration":"180.713671ms","start":"2026-10-26T21:29:55.239425Z","end":"2026-10-26T21:29:55.420139Z","steps":["trace[459323144] 'agreement among raft nodes before linearized reading' (duration: 180.603386ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:55.566791Z","caller":"traceutil/trace.go:171","msg":"trace[339243606] transaction","detail":"{read_only:false; response_revision:5244; number_of_response:1; }","duration":"140.650806ms","start":"2026-10-26T21:29:55.42611Z","end":"2026-10-26T21:29:55.566761Z","steps":["trace[339243606] 'process raft request' (duration: 100.332252ms)","trace[339243606] 'compare' (duration: 40.011271ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:29:55.567109Z","caller":"traceutil/trace.go:171","msg":"trace[619351704] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"140.453588ms","start":"2026-10-26T21:29:55.426641Z","end":"2026-10-26T21:29:55.567095Z","steps":["trace[619351704] 'process raft request' (duration: 139.978607ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:55.56726Z","caller":"traceutil/trace.go:171","msg":"trace[1740196926] transaction","detail":"{read_only:false; response_revision:5246; number_of_response:1; }","duration":"132.451118ms","start":"2026-10-26T21:29:55.434751Z","end":"2026-10-26T21:29:55.567202Z","steps":["trace[1740196926] 'process raft request' (duration: 131.931005ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:55.567652Z","caller":"traceutil/trace.go:171","msg":"trace[2017945497] linearizableReadLoop","detail":"{readStateIndex:5446; appliedIndex:5444; }","duration":"141.042963ms","start":"2026-10-26T21:29:55.426587Z","end":"2026-10-26T21:29:55.56763Z","steps":["trace[2017945497] 'read index received' (duration: 49.839911ms)","trace[2017945497] 'applied index is now lower than readState.Index' (duration: 91.196862ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:29:55.567925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.316496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-4a453652-d2ca-4481-8cae-feb9c2110c67\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-10-26T21:29:55.567977Z","caller":"traceutil/trace.go:171","msg":"trace[90696790] range","detail":"{range_begin:/registry/persistentvolumes/pvc-4a453652-d2ca-4481-8cae-feb9c2110c67; range_end:; response_count:1; response_revision:5247; }","duration":"141.40594ms","start":"2026-10-26T21:29:55.426557Z","end":"2026-10-26T21:29:55.567963Z","steps":["trace[90696790] 'agreement among raft nodes before linearized reading' (duration: 141.17809ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:55.694716Z","caller":"traceutil/trace.go:171","msg":"trace[954974274] transaction","detail":"{read_only:false; response_revision:5249; number_of_response:1; }","duration":"116.649606ms","start":"2026-10-26T21:29:55.578036Z","end":"2026-10-26T21:29:55.694685Z","steps":["trace[954974274] 'process raft request' (duration: 107.917204ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:55.695203Z","caller":"traceutil/trace.go:171","msg":"trace[1589361143] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"115.396421ms","start":"2026-10-26T21:29:55.579767Z","end":"2026-10-26T21:29:55.695163Z","steps":["trace[1589361143] 'process raft request' (duration: 115.091448ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:55.695975Z","caller":"traceutil/trace.go:171","msg":"trace[1529667961] transaction","detail":"{read_only:false; response_revision:5252; number_of_response:1; }","duration":"114.6758ms","start":"2026-10-26T21:29:55.581279Z","end":"2026-10-26T21:29:55.695955Z","steps":["trace[1529667961] 'process raft request' (duration: 114.091084ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:55.695046Z","caller":"traceutil/trace.go:171","msg":"trace[1735012217] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"115.718155ms","start":"2026-10-26T21:29:55.579282Z","end":"2026-10-26T21:29:55.695Z","steps":["trace[1735012217] 'process raft request' (duration: 115.335758ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:55.885199Z","caller":"traceutil/trace.go:171","msg":"trace[672779919] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"177.765301ms","start":"2026-10-26T21:29:55.707406Z","end":"2026-10-26T21:29:55.885172Z","steps":["trace[672779919] 'process raft request' (duration: 177.556962ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:55.928364Z","caller":"traceutil/trace.go:171","msg":"trace[1123392442] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"220.474981ms","start":"2026-10-26T21:29:55.70786Z","end":"2026-10-26T21:29:55.928335Z","steps":["trace[1123392442] 'process raft request' (duration: 213.499636ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:55.928452Z","caller":"traceutil/trace.go:171","msg":"trace[781641035] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"111.879857ms","start":"2026-10-26T21:29:55.816525Z","end":"2026-10-26T21:29:55.928405Z","steps":["trace[781641035] 'process raft request' (duration: 104.907402ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:55.928612Z","caller":"traceutil/trace.go:171","msg":"trace[1108957815] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"221.115979ms","start":"2026-10-26T21:29:55.707464Z","end":"2026-10-26T21:29:55.92858Z","steps":["trace[1108957815] 'process raft request' (duration: 213.789338ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:30:09.170547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.727398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3315"} {"level":"info","ts":"2026-10-26T21:30:09.170683Z","caller":"traceutil/trace.go:171","msg":"trace[1700772775] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5428; }","duration":"186.938518ms","start":"2026-10-26T21:30:08.983717Z","end":"2026-10-26T21:30:09.170656Z","steps":["trace[1700772775] 'range keys from in-memory index tree' (duration: 186.358472ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:30:09.414143Z","caller":"traceutil/trace.go:171","msg":"trace[563601609] linearizableReadLoop","detail":"{readStateIndex:5637; appliedIndex:5636; }","duration":"130.221294ms","start":"2026-10-26T21:30:09.28389Z","end":"2026-10-26T21:30:09.414111Z","steps":["trace[563601609] 'read index received' (duration: 129.997495ms)","trace[563601609] 'applied index is now lower than readState.Index' (duration: 222.409µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:30:09.414899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.399012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:30:09.415033Z","caller":"traceutil/trace.go:171","msg":"trace[758295691] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5428; }","duration":"131.189527ms","start":"2026-10-26T21:30:09.283821Z","end":"2026-10-26T21:30:09.415011Z","steps":["trace[758295691] 'agreement among raft nodes before linearized reading' (duration: 130.365541ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:30:09.657532Z","caller":"traceutil/trace.go:171","msg":"trace[236632215] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"110.807081ms","start":"2026-10-26T21:30:09.54669Z","end":"2026-10-26T21:30:09.657497Z","steps":["trace[236632215] 'process raft request' (duration: 110.482357ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:30:09.657552Z","caller":"traceutil/trace.go:171","msg":"trace[17356717] transaction","detail":"{read_only:false; response_revision:5437; number_of_response:1; }","duration":"109.204291ms","start":"2026-10-26T21:30:09.548313Z","end":"2026-10-26T21:30:09.657518Z","steps":["trace[17356717] 'process raft request' (duration: 109.050694ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:30:09.657919Z","caller":"traceutil/trace.go:171","msg":"trace[144768581] transaction","detail":"{read_only:false; response_revision:5434; number_of_response:1; }","duration":"111.45664ms","start":"2026-10-26T21:30:09.546437Z","end":"2026-10-26T21:30:09.657894Z","steps":["trace[144768581] 'process raft request' (duration: 99.936413ms)","trace[144768581] 'compare' (duration: 10.504172ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:30:09.657554Z","caller":"traceutil/trace.go:171","msg":"trace[1065999893] transaction","detail":"{read_only:false; response_revision:5436; number_of_response:1; }","duration":"109.953924ms","start":"2026-10-26T21:30:09.547566Z","end":"2026-10-26T21:30:09.65752Z","steps":["trace[1065999893] 'process raft request' (duration: 109.734215ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:33:56.008001Z","caller":"traceutil/trace.go:171","msg":"trace[848631673] transaction","detail":"{read_only:false; response_revision:7057; number_of_response:1; }","duration":"160.218852ms","start":"2026-10-26T21:33:55.847742Z","end":"2026-10-26T21:33:56.007961Z","steps":["trace[848631673] 'process raft request' (duration: 159.991817ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:34:23.383998Z","caller":"traceutil/trace.go:171","msg":"trace[1350939605] linearizableReadLoop","detail":"{readStateIndex:7523; appliedIndex:7522; }","duration":"120.136826ms","start":"2026-10-26T21:34:23.263838Z","end":"2026-10-26T21:34:23.383975Z","steps":["trace[1350939605] 'read index received' (duration: 68.444276ms)","trace[1350939605] 'applied index is now lower than readState.Index' (duration: 51.69162ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:34:23.38444Z","caller":"traceutil/trace.go:171","msg":"trace[1434311200] transaction","detail":"{read_only:false; response_revision:7253; number_of_response:1; }","duration":"127.130247ms","start":"2026-10-26T21:34:23.257293Z","end":"2026-10-26T21:34:23.384423Z","steps":["trace[1434311200] 'process raft request' (duration: 75.096539ms)","trace[1434311200] 'compare' (duration: 51.477785ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:34:23.38487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.979605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:34:23.385946Z","caller":"traceutil/trace.go:171","msg":"trace[1128213526] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:7253; }","duration":"121.172169ms","start":"2026-10-26T21:34:23.26377Z","end":"2026-10-26T21:34:23.384943Z","steps":["trace[1128213526] 'agreement among raft nodes before linearized reading' (duration: 121.004105ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:34:45.211587Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4993} {"level":"info","ts":"2026-10-26T21:34:45.293439Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4993,"took":"80.344256ms","hash":508865822,"current-db-size-bytes":49541120,"current-db-size":"50 MB","current-db-size-in-use-bytes":18944000,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-26T21:34:45.29351Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":508865822,"revision":4993,"compact-revision":2857}