{"level":"warn","ts":"2026-09-19T01:46:38.810769Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T01:46:38.810863Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.5: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.5:2380","--initial-cluster=instance=https://162.253.55.5:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.5:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.5:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-19T01:46:38.810941Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T01:46:38.810959Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.5:2380"]} {"level":"info","ts":"2026-09-19T01:46:38.810979Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-19T01:46:38.811784Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.5:2379"]} {"level":"info","ts":"2026-09-19T01:46:38.81189Z","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.5:2380"],"listen-peer-urls":["https://162.253.55.5:2380"],"advertise-client-urls":["https://162.253.55.5:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.5:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.5:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-19T01:46:38.82002Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.822923ms"} {"level":"info","ts":"2026-09-19T01:46:38.831633Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b512f7d8ac93be25","cluster-id":"e1953d9a3e7368e5"} {"level":"info","ts":"2026-09-19T01:46:38.831695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T01:46:38.831712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became follower at term 0"} {"level":"info","ts":"2026-09-19T01:46:38.831723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b512f7d8ac93be25 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T01:46:38.831729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became follower at term 1"} {"level":"info","ts":"2026-09-19T01:46:38.831758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 switched to configuration voters=(13047763580426108453)"} {"level":"warn","ts":"2026-09-19T01:46:38.840436Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T01:46:38.842533Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T01:46:38.843735Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-19T01:46:38.845709Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b512f7d8ac93be25","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T01:46:38.845947Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b512f7d8ac93be25","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T01:46:38.846069Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T01:46:38.846173Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T01:46:38.846184Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T01:46:38.848137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 switched to configuration voters=(13047763580426108453)"} {"level":"info","ts":"2026-09-19T01:46:38.84831Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e1953d9a3e7368e5","local-member-id":"b512f7d8ac93be25","added-peer-id":"b512f7d8ac93be25","added-peer-peer-urls":["https://162.253.55.5:2380"]} {"level":"info","ts":"2026-09-19T01:46:38.850542Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-19T01:46:38.850812Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b512f7d8ac93be25","initial-advertise-peer-urls":["https://162.253.55.5:2380"],"listen-peer-urls":["https://162.253.55.5:2380"],"advertise-client-urls":["https://162.253.55.5:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.5:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T01:46:38.850862Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T01:46:38.850904Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.5:2380"} {"level":"info","ts":"2026-09-19T01:46:38.850953Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.5:2380"} {"level":"info","ts":"2026-09-19T01:46:39.831949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T01:46:39.83204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T01:46:39.832091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 received MsgPreVoteResp from b512f7d8ac93be25 at term 1"} {"level":"info","ts":"2026-09-19T01:46:39.832109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became candidate at term 2"} {"level":"info","ts":"2026-09-19T01:46:39.832121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 received MsgVoteResp from b512f7d8ac93be25 at term 2"} {"level":"info","ts":"2026-09-19T01:46:39.832184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became leader at term 2"} {"level":"info","ts":"2026-09-19T01:46:39.832205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b512f7d8ac93be25 elected leader b512f7d8ac93be25 at term 2"} {"level":"info","ts":"2026-09-19T01:46:39.83359Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b512f7d8ac93be25","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.5:2379]}","request-path":"/0/members/b512f7d8ac93be25/attributes","cluster-id":"e1953d9a3e7368e5","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T01:46:39.833724Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T01:46:39.833639Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:46:39.833817Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T01:46:39.834103Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T01:46:39.834203Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T01:46:39.834958Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e1953d9a3e7368e5","local-member-id":"b512f7d8ac93be25","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:46:39.835202Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:46:39.835258Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:46:39.836908Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T01:46:39.837189Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.5:2379"} {"level":"info","ts":"2026-09-19T01:47:18.681492Z","caller":"traceutil/trace.go:171","msg":"trace[100878625] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"144.546897ms","start":"2026-09-19T01:47:18.536923Z","end":"2026-09-19T01:47:18.68147Z","steps":["trace[100878625] 'read index received' (duration: 144.300045ms)","trace[100878625] 'applied index is now lower than readState.Index' (duration: 245.782µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:47:18.681575Z","caller":"traceutil/trace.go:171","msg":"trace[1336088132] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"153.657422ms","start":"2026-09-19T01:47:18.52785Z","end":"2026-09-19T01:47:18.681507Z","steps":["trace[1336088132] 'process raft request' (duration: 153.376158ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:47:18.68178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.830422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-09-19T01:47:18.68185Z","caller":"traceutil/trace.go:171","msg":"trace[1002885652] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:589; }","duration":"144.915646ms","start":"2026-09-19T01:47:18.536919Z","end":"2026-09-19T01:47:18.681835Z","steps":["trace[1002885652] 'agreement among raft nodes before linearized reading' (duration: 144.695475ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:47:25.113582Z","caller":"traceutil/trace.go:171","msg":"trace[900122525] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"291.967115ms","start":"2026-09-19T01:47:24.821576Z","end":"2026-09-19T01:47:25.113543Z","steps":["trace[900122525] 'read index received' (duration: 290.873345ms)","trace[900122525] 'applied index is now lower than readState.Index' (duration: 1.09242ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:47:25.113761Z","caller":"traceutil/trace.go:171","msg":"trace[1357842724] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"333.77997ms","start":"2026-09-19T01:47:24.779898Z","end":"2026-09-19T01:47:25.113678Z","steps":["trace[1357842724] 'process raft request' (duration: 332.769114ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:47:25.113849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.226937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:47:25.114164Z","caller":"traceutil/trace.go:171","msg":"trace[1904512118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"292.598243ms","start":"2026-09-19T01:47:24.821523Z","end":"2026-09-19T01:47:25.114121Z","steps":["trace[1904512118] 'agreement among raft nodes before linearized reading' (duration: 292.208736ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:47:25.115392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:47:24.779857Z","time spent":"334.133446ms","remote":"127.0.0.1:58224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:47:25.374513Z","caller":"traceutil/trace.go:171","msg":"trace[601929685] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"174.738192ms","start":"2026-09-19T01:47:25.199719Z","end":"2026-09-19T01:47:25.374457Z","steps":["trace[601929685] 'process raft request' (duration: 143.493304ms)","trace[601929685] 'compare' (duration: 31.094431ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:47:25.374667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.426594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-khhvs\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-09-19T01:47:25.375059Z","caller":"traceutil/trace.go:171","msg":"trace[1054576311] range","detail":"{range_begin:/registry/pods/kube-system/cilium-khhvs; range_end:; response_count:1; response_revision:642; }","duration":"187.688785ms","start":"2026-09-19T01:47:25.187063Z","end":"2026-09-19T01:47:25.374751Z","steps":["trace[1054576311] 'range keys from in-memory index tree' (duration: 187.105189ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:47:25.807159Z","caller":"traceutil/trace.go:171","msg":"trace[1726961272] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"129.542055ms","start":"2026-09-19T01:47:25.677589Z","end":"2026-09-19T01:47:25.807131Z","steps":["trace[1726961272] 'read index received' (duration: 77.354115ms)","trace[1726961272] 'applied index is now lower than readState.Index' (duration: 52.18692ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:47:25.807227Z","caller":"traceutil/trace.go:171","msg":"trace[1927522807] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"409.695287ms","start":"2026-09-19T01:47:25.397509Z","end":"2026-09-19T01:47:25.807204Z","steps":["trace[1927522807] 'process raft request' (duration: 357.484135ms)","trace[1927522807] 'compare' (duration: 52.030414ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:47:25.807414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.802028ms","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":"info","ts":"2026-09-19T01:47:25.807469Z","caller":"traceutil/trace.go:171","msg":"trace[1370892400] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:644; }","duration":"129.916933ms","start":"2026-09-19T01:47:25.677539Z","end":"2026-09-19T01:47:25.807456Z","steps":["trace[1370892400] 'agreement among raft nodes before linearized reading' (duration: 129.732445ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:47:25.807603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:47:25.397429Z","time spent":"409.99663ms","remote":"127.0.0.1:58336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:47:25.807746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.045268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-19T01:47:25.807825Z","caller":"traceutil/trace.go:171","msg":"trace[1467677792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:644; }","duration":"110.147743ms","start":"2026-09-19T01:47:25.697656Z","end":"2026-09-19T01:47:25.807804Z","steps":["trace[1467677792] 'agreement among raft nodes before linearized reading' (duration: 109.952644ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:47:42.48647Z","caller":"traceutil/trace.go:171","msg":"trace[1579871902] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:803; }","duration":"164.050062ms","start":"2026-09-19T01:47:42.322389Z","end":"2026-09-19T01:47:42.486439Z","steps":["trace[1579871902] 'read index received' (duration: 163.856715ms)","trace[1579871902] 'applied index is now lower than readState.Index' (duration: 192.147µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:47:42.486601Z","caller":"traceutil/trace.go:171","msg":"trace[1127800571] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"166.684081ms","start":"2026-09-19T01:47:42.319881Z","end":"2026-09-19T01:47:42.486565Z","steps":["trace[1127800571] 'process raft request' (duration: 166.369679ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:47:42.486778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.746424ms","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-09-19T01:47:42.486858Z","caller":"traceutil/trace.go:171","msg":"trace[218887428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:773; }","duration":"150.859578ms","start":"2026-09-19T01:47:42.335953Z","end":"2026-09-19T01:47:42.486813Z","steps":["trace[218887428] 'agreement among raft nodes before linearized reading' (duration: 150.700332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:47:42.486868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.434737ms","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-09-19T01:47:42.486965Z","caller":"traceutil/trace.go:171","msg":"trace[1455815027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:773; }","duration":"164.641974ms","start":"2026-09-19T01:47:42.322302Z","end":"2026-09-19T01:47:42.486944Z","steps":["trace[1455815027] 'agreement among raft nodes before linearized reading' (duration: 164.350063ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:48:12.935674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.371418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:48:12.935807Z","caller":"traceutil/trace.go:171","msg":"trace[2064935323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1175; }","duration":"113.559894ms","start":"2026-09-19T01:48:12.822224Z","end":"2026-09-19T01:48:12.935784Z","steps":["trace[2064935323] 'range keys from in-memory index tree' (duration: 113.274636ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:49:02.031169Z","caller":"traceutil/trace.go:171","msg":"trace[2093991389] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1936; }","duration":"112.973425ms","start":"2026-09-19T01:49:01.918167Z","end":"2026-09-19T01:49:02.031141Z","steps":["trace[2093991389] 'read index received' (duration: 112.806432ms)","trace[2093991389] 'applied index is now lower than readState.Index' (duration: 165.433µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:49:02.031292Z","caller":"traceutil/trace.go:171","msg":"trace[2130163305] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"115.885103ms","start":"2026-09-19T01:49:01.915362Z","end":"2026-09-19T01:49:02.031247Z","steps":["trace[2130163305] 'process raft request' (duration: 115.594448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:49:02.031503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.345933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-19T01:49:02.031558Z","caller":"traceutil/trace.go:171","msg":"trace[1040181642] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1860; }","duration":"113.460905ms","start":"2026-09-19T01:49:01.918082Z","end":"2026-09-19T01:49:02.031543Z","steps":["trace[1040181642] 'agreement among raft nodes before linearized reading' (duration: 113.167729ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:49:02.228007Z","caller":"traceutil/trace.go:171","msg":"trace[1133593748] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"267.888313ms","start":"2026-09-19T01:49:01.960019Z","end":"2026-09-19T01:49:02.227907Z","steps":["trace[1133593748] 'process raft request' (duration: 223.430227ms)","trace[1133593748] 'compare' (duration: 44.16662ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:49:02.580731Z","caller":"traceutil/trace.go:171","msg":"trace[412240238] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1938; }","duration":"159.183247ms","start":"2026-09-19T01:49:02.421522Z","end":"2026-09-19T01:49:02.580705Z","steps":["trace[412240238] 'read index received' (duration: 158.953972ms)","trace[412240238] 'applied index is now lower than readState.Index' (duration: 228.435µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:49:02.580836Z","caller":"traceutil/trace.go:171","msg":"trace[727928328] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"186.819ms","start":"2026-09-19T01:49:02.393964Z","end":"2026-09-19T01:49:02.580783Z","steps":["trace[727928328] 'process raft request' (duration: 186.575665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:49:02.581102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.548584ms","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":"info","ts":"2026-09-19T01:49:02.581161Z","caller":"traceutil/trace.go:171","msg":"trace[976594550] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1862; }","duration":"159.682856ms","start":"2026-09-19T01:49:02.421465Z","end":"2026-09-19T01:49:02.581148Z","steps":["trace[976594550] 'agreement among raft nodes before linearized reading' (duration: 159.34741ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:49:15.918536Z","caller":"traceutil/trace.go:171","msg":"trace[62489683] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"142.763057ms","start":"2026-09-19T01:49:15.775488Z","end":"2026-09-19T01:49:15.918251Z","steps":["trace[62489683] 'process raft request' (duration: 142.541913ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:49:32.523524Z","caller":"traceutil/trace.go:171","msg":"trace[1617907698] transaction","detail":"{read_only:false; response_revision:2078; number_of_response:1; }","duration":"168.748258ms","start":"2026-09-19T01:49:32.354748Z","end":"2026-09-19T01:49:32.523496Z","steps":["trace[1617907698] 'process raft request' (duration: 168.551205ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:49:32.922463Z","caller":"traceutil/trace.go:171","msg":"trace[2024225437] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"129.259235ms","start":"2026-09-19T01:49:32.793172Z","end":"2026-09-19T01:49:32.922431Z","steps":["trace[2024225437] 'process raft request' (duration: 84.301568ms)","trace[2024225437] 'compare' (duration: 44.794474ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:51:21.582028Z","caller":"traceutil/trace.go:171","msg":"trace[1155214679] transaction","detail":"{read_only:false; response_revision:2771; number_of_response:1; }","duration":"140.558725ms","start":"2026-09-19T01:51:21.441419Z","end":"2026-09-19T01:51:21.581978Z","steps":["trace[1155214679] 'process raft request' (duration: 140.301127ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:12.358826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.459364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"warn","ts":"2026-09-19T01:56:12.358851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.576945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b3eda01f-c4e5-4735-a05a-e9d3818fba38\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-09-19T01:56:12.358916Z","caller":"traceutil/trace.go:171","msg":"trace[1263465763] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:5057; }","duration":"278.597275ms","start":"2026-09-19T01:56:12.0803Z","end":"2026-09-19T01:56:12.358897Z","steps":["trace[1263465763] 'range keys from in-memory index tree' (duration: 278.284989ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:12.358932Z","caller":"traceutil/trace.go:171","msg":"trace[955930878] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b3eda01f-c4e5-4735-a05a-e9d3818fba38; range_end:; response_count:1; response_revision:5057; }","duration":"278.675407ms","start":"2026-09-19T01:56:12.080235Z","end":"2026-09-19T01:56:12.35891Z","steps":["trace[955930878] 'range keys from in-memory index tree' (duration: 278.403871ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:12.359299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.752798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:56:12.359644Z","caller":"traceutil/trace.go:171","msg":"trace[1593409402] transaction","detail":"{read_only:false; response_revision:5058; number_of_response:1; }","duration":"276.46894ms","start":"2026-09-19T01:56:12.083154Z","end":"2026-09-19T01:56:12.359623Z","steps":["trace[1593409402] 'process raft request' (duration: 78.076433ms)","trace[1593409402] 'compare' (duration: 197.131986ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:56:12.359776Z","caller":"traceutil/trace.go:171","msg":"trace[1316843456] transaction","detail":"{read_only:false; response_revision:5059; number_of_response:1; }","duration":"276.393288ms","start":"2026-09-19T01:56:12.083359Z","end":"2026-09-19T01:56:12.359753Z","steps":["trace[1316843456] 'process raft request' (duration: 276.121954ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:12.360052Z","caller":"traceutil/trace.go:171","msg":"trace[1385799675] transaction","detail":"{read_only:false; response_revision:5060; number_of_response:1; }","duration":"275.946611ms","start":"2026-09-19T01:56:12.084093Z","end":"2026-09-19T01:56:12.36004Z","steps":["trace[1385799675] 'process raft request' (duration: 275.526774ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:12.360329Z","caller":"traceutil/trace.go:171","msg":"trace[1655491502] transaction","detail":"{read_only:false; response_revision:5061; number_of_response:1; }","duration":"275.90915ms","start":"2026-09-19T01:56:12.084409Z","end":"2026-09-19T01:56:12.360318Z","steps":["trace[1655491502] 'process raft request' (duration: 275.251459ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:12.36034Z","caller":"traceutil/trace.go:171","msg":"trace[730232435] linearizableReadLoop","detail":"{readStateIndex:5257; appliedIndex:5253; }","duration":"210.887946ms","start":"2026-09-19T01:56:12.14944Z","end":"2026-09-19T01:56:12.360328Z","steps":["trace[730232435] 'read index received' (duration: 11.811517ms)","trace[730232435] 'applied index is now lower than readState.Index' (duration: 199.075569ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:56:12.36049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.056738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-09-19T01:56:12.360516Z","caller":"traceutil/trace.go:171","msg":"trace[1192712224] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5062; }","duration":"211.236632ms","start":"2026-09-19T01:56:12.149271Z","end":"2026-09-19T01:56:12.360508Z","steps":["trace[1192712224] 'agreement among raft nodes before linearized reading' (duration: 211.099369ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:12.360687Z","caller":"traceutil/trace.go:171","msg":"trace[1969443486] transaction","detail":"{read_only:false; response_revision:5062; number_of_response:1; }","duration":"208.833131ms","start":"2026-09-19T01:56:12.151843Z","end":"2026-09-19T01:56:12.360676Z","steps":["trace[1969443486] 'process raft request' (duration: 207.852545ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:39.865958Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2868} {"level":"info","ts":"2026-09-19T01:56:39.946639Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2868,"took":"79.216486ms","hash":1980056698,"current-db-size-bytes":53329920,"current-db-size":"53 MB","current-db-size-in-use-bytes":27783168,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-19T01:56:39.946713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1980056698,"revision":2868,"compact-revision":-1} {"level":"info","ts":"2026-09-19T02:01:39.874761Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5336} {"level":"info","ts":"2026-09-19T02:01:40.011916Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5336,"took":"134.301532ms","hash":3961117896,"current-db-size-bytes":53329920,"current-db-size":"53 MB","current-db-size-in-use-bytes":18898944,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-19T02:01:40.012017Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3961117896,"revision":5336,"compact-revision":2868} {"level":"info","ts":"2026-09-19T02:06:39.881686Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7401} {"level":"info","ts":"2026-09-19T02:06:39.951262Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7401,"took":"67.862891ms","hash":2024052170,"current-db-size-bytes":53329920,"current-db-size":"53 MB","current-db-size-in-use-bytes":15499264,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-09-19T02:06:39.951325Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2024052170,"revision":7401,"compact-revision":5336}