{"level":"warn","ts":"2026-10-14T17:30:01.796219Z","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-14T17:30:01.796348Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.66: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.66:2380","--initial-cluster=instance=https://162.253.55.66:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.66:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.66: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-14T17:30:01.79667Z","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-14T17:30:01.796729Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.66:2380"]} {"level":"info","ts":"2026-10-14T17:30:01.796869Z","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-14T17:30:01.79777Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.66:2379"]} {"level":"info","ts":"2026-10-14T17:30:01.798037Z","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.66:2380"],"listen-peer-urls":["https://162.253.55.66:2380"],"advertise-client-urls":["https://162.253.55.66:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.66:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.66: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-14T17:30:02.222744Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"423.241311ms"} {"level":"info","ts":"2026-10-14T17:30:02.365447Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"48afe2e91b68f008","cluster-id":"5894dcc7313657d8"} {"level":"info","ts":"2026-10-14T17:30:02.365615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T17:30:02.365659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 became follower at term 0"} {"level":"info","ts":"2026-10-14T17:30:02.365811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 48afe2e91b68f008 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T17:30:02.365843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 became follower at term 1"} {"level":"info","ts":"2026-10-14T17:30:02.365907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 switched to configuration voters=(5237654382470295560)"} {"level":"warn","ts":"2026-10-14T17:30:02.377144Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T17:30:02.379244Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T17:30:02.380538Z","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-14T17:30:02.383587Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"48afe2e91b68f008","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T17:30:02.383904Z","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-14T17:30:02.384044Z","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-14T17:30:02.38406Z","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-14T17:30:02.384109Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"48afe2e91b68f008","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T17:30:02.384733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 switched to configuration voters=(5237654382470295560)"} {"level":"info","ts":"2026-10-14T17:30:02.384815Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5894dcc7313657d8","local-member-id":"48afe2e91b68f008","added-peer-id":"48afe2e91b68f008","added-peer-peer-urls":["https://162.253.55.66:2380"]} {"level":"info","ts":"2026-10-14T17:30:02.388828Z","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-14T17:30:02.389028Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.66:2380"} {"level":"info","ts":"2026-10-14T17:30:02.38908Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.66:2380"} {"level":"info","ts":"2026-10-14T17:30:02.390347Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"48afe2e91b68f008","initial-advertise-peer-urls":["https://162.253.55.66:2380"],"listen-peer-urls":["https://162.253.55.66:2380"],"advertise-client-urls":["https://162.253.55.66:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.66:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T17:30:02.390396Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T17:30:03.266979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T17:30:03.267087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T17:30:03.267126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 received MsgPreVoteResp from 48afe2e91b68f008 at term 1"} {"level":"info","ts":"2026-10-14T17:30:03.267176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 became candidate at term 2"} {"level":"info","ts":"2026-10-14T17:30:03.267205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 received MsgVoteResp from 48afe2e91b68f008 at term 2"} {"level":"info","ts":"2026-10-14T17:30:03.267253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 became leader at term 2"} {"level":"info","ts":"2026-10-14T17:30:03.267274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 48afe2e91b68f008 elected leader 48afe2e91b68f008 at term 2"} {"level":"info","ts":"2026-10-14T17:30:03.268649Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:30:03.269742Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"48afe2e91b68f008","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.66:2379]}","request-path":"/0/members/48afe2e91b68f008/attributes","cluster-id":"5894dcc7313657d8","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T17:30:03.269779Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:30:03.27022Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:30:03.27051Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T17:30:03.270558Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T17:30:03.271819Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5894dcc7313657d8","local-member-id":"48afe2e91b68f008","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:30:03.271984Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:30:03.27202Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:30:03.272886Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.66:2379"} {"level":"info","ts":"2026-10-14T17:30:03.273592Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T17:30:46.362813Z","caller":"traceutil/trace.go:171","msg":"trace[1316197058] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"112.630454ms","start":"2026-10-14T17:30:46.250156Z","end":"2026-10-14T17:30:46.362787Z","steps":["trace[1316197058] 'process raft request' (duration: 112.452921ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:30:52.802511Z","caller":"traceutil/trace.go:171","msg":"trace[1782204704] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:667; }","duration":"100.912388ms","start":"2026-10-14T17:30:52.701573Z","end":"2026-10-14T17:30:52.802485Z","steps":["trace[1782204704] 'read index received' (duration: 33.532774ms)","trace[1782204704] 'applied index is now lower than readState.Index' (duration: 67.378704ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:30:52.802785Z","caller":"traceutil/trace.go:171","msg":"trace[218371790] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"211.951949ms","start":"2026-10-14T17:30:52.590777Z","end":"2026-10-14T17:30:52.802729Z","steps":["trace[218371790] 'process raft request' (duration: 211.666065ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:30:52.803124Z","caller":"traceutil/trace.go:171","msg":"trace[651731600] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"267.158105ms","start":"2026-10-14T17:30:52.535914Z","end":"2026-10-14T17:30:52.803072Z","steps":["trace[651731600] 'process raft request' (duration: 199.178263ms)","trace[651731600] 'compare' (duration: 67.254933ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:30:52.803365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.780201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-14T17:30:52.803456Z","caller":"traceutil/trace.go:171","msg":"trace[1107498551] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"101.886302ms","start":"2026-10-14T17:30:52.701555Z","end":"2026-10-14T17:30:52.803442Z","steps":["trace[1107498551] 'agreement among raft nodes before linearized reading' (duration: 101.647469ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:30:53.304706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.487795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-14T17:30:53.304797Z","caller":"traceutil/trace.go:171","msg":"trace[1015870060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:650; }","duration":"165.655557ms","start":"2026-10-14T17:30:53.13912Z","end":"2026-10-14T17:30:53.304776Z","steps":["trace[1015870060] 'range keys from in-memory index tree' (duration: 165.315343ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:30:53.305132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.804649ms","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-14T17:30:53.305318Z","caller":"traceutil/trace.go:171","msg":"trace[1660063640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:650; }","duration":"165.985732ms","start":"2026-10-14T17:30:53.139244Z","end":"2026-10-14T17:30:53.30523Z","steps":["trace[1660063640] 'range keys from in-memory index tree' (duration: 165.354393ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:30:53.308565Z","caller":"traceutil/trace.go:171","msg":"trace[1159164683] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"116.179079ms","start":"2026-10-14T17:30:53.192367Z","end":"2026-10-14T17:30:53.308546Z","steps":["trace[1159164683] 'process raft request' (duration: 115.593971ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:32:29.589437Z","caller":"traceutil/trace.go:171","msg":"trace[349181139] linearizableReadLoop","detail":"{readStateIndex:1961; appliedIndex:1960; }","duration":"287.753217ms","start":"2026-10-14T17:32:29.301652Z","end":"2026-10-14T17:32:29.589405Z","steps":["trace[349181139] 'read index received' (duration: 287.650986ms)","trace[349181139] 'applied index is now lower than readState.Index' (duration: 101.02µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:32:29.58949Z","caller":"traceutil/trace.go:171","msg":"trace[178085671] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"318.261341ms","start":"2026-10-14T17:32:29.271198Z","end":"2026-10-14T17:32:29.58946Z","steps":["trace[178085671] 'process raft request' (duration: 318.008549ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:32:29.589691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.00207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-14T17:32:29.589747Z","caller":"traceutil/trace.go:171","msg":"trace[2079452752] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1892; }","duration":"288.11272ms","start":"2026-10-14T17:32:29.301619Z","end":"2026-10-14T17:32:29.589731Z","steps":["trace[2079452752] 'agreement among raft nodes before linearized reading' (duration: 287.904379ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:32:29.590221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:32:29.271181Z","time spent":"318.375922ms","remote":"127.0.0.1:36570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:32:29.611592Z","caller":"traceutil/trace.go:171","msg":"trace[207458095] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"111.978692ms","start":"2026-10-14T17:32:29.499589Z","end":"2026-10-14T17:32:29.611568Z","steps":["trace[207458095] 'process raft request' (duration: 111.86325ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:32:46.372993Z","caller":"traceutil/trace.go:171","msg":"trace[412176237] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"178.578698ms","start":"2026-10-14T17:32:46.194399Z","end":"2026-10-14T17:32:46.372978Z","steps":["trace[412176237] 'process raft request' (duration: 177.234227ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:32:46.373259Z","caller":"traceutil/trace.go:171","msg":"trace[1909065835] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"149.350313ms","start":"2026-10-14T17:32:46.223871Z","end":"2026-10-14T17:32:46.373221Z","steps":["trace[1909065835] 'process raft request' (duration: 149.189821ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:33:09.620998Z","caller":"traceutil/trace.go:171","msg":"trace[838224688] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"143.217786ms","start":"2026-10-14T17:33:09.477729Z","end":"2026-10-14T17:33:09.620947Z","steps":["trace[838224688] 'process raft request' (duration: 143.05429ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:33:09.880806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.633809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T17:33:09.880929Z","caller":"traceutil/trace.go:171","msg":"trace[1882546833] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2158; }","duration":"165.778934ms","start":"2026-10-14T17:33:09.71511Z","end":"2026-10-14T17:33:09.880889Z","steps":["trace[1882546833] 'count revisions from in-memory index tree' (duration: 165.451433ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:33:09.880918Z","caller":"traceutil/trace.go:171","msg":"trace[1189527564] linearizableReadLoop","detail":"{readStateIndex:2236; appliedIndex:2235; }","duration":"111.34015ms","start":"2026-10-14T17:33:09.769556Z","end":"2026-10-14T17:33:09.880896Z","steps":["trace[1189527564] 'read index received' (duration: 13.759463ms)","trace[1189527564] 'applied index is now lower than readState.Index' (duration: 97.579787ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:33:09.881034Z","caller":"traceutil/trace.go:171","msg":"trace[1992309536] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"157.2533ms","start":"2026-10-14T17:33:09.723756Z","end":"2026-10-14T17:33:09.881009Z","steps":["trace[1992309536] 'process raft request' (duration: 59.571729ms)","trace[1992309536] 'compare' (duration: 97.452122ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:33:09.881341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.762546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:33:09.881867Z","caller":"traceutil/trace.go:171","msg":"trace[766967217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2159; }","duration":"112.333358ms","start":"2026-10-14T17:33:09.76951Z","end":"2026-10-14T17:33:09.881844Z","steps":["trace[766967217] 'agreement among raft nodes before linearized reading' (duration: 111.503495ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:33:10.047165Z","caller":"traceutil/trace.go:171","msg":"trace[551213765] linearizableReadLoop","detail":"{readStateIndex:2237; appliedIndex:2236; }","duration":"136.367837ms","start":"2026-10-14T17:33:09.910753Z","end":"2026-10-14T17:33:10.04712Z","steps":["trace[551213765] 'read index received' (duration: 108.872159ms)","trace[551213765] 'applied index is now lower than readState.Index' (duration: 27.494768ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:33:10.047283Z","caller":"traceutil/trace.go:171","msg":"trace[2111700610] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"162.623532ms","start":"2026-10-14T17:33:09.884644Z","end":"2026-10-14T17:33:10.047267Z","steps":["trace[2111700610] 'process raft request' (duration: 135.065581ms)","trace[2111700610] 'compare' (duration: 27.309921ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:33:10.047605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.830715ms","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-10-14T17:33:10.047715Z","caller":"traceutil/trace.go:171","msg":"trace[60921227] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2160; }","duration":"136.968531ms","start":"2026-10-14T17:33:09.910728Z","end":"2026-10-14T17:33:10.047696Z","steps":["trace[60921227] 'agreement among raft nodes before linearized reading' (duration: 136.740442ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:33:10.206248Z","caller":"traceutil/trace.go:171","msg":"trace[663182178] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"106.35232ms","start":"2026-10-14T17:33:10.099872Z","end":"2026-10-14T17:33:10.206225Z","steps":["trace[663182178] 'process raft request' (duration: 102.901574ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:33:10.206641Z","caller":"traceutil/trace.go:171","msg":"trace[644541353] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"104.850582ms","start":"2026-10-14T17:33:10.101771Z","end":"2026-10-14T17:33:10.206622Z","steps":["trace[644541353] 'process raft request' (duration: 104.634413ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:38:52.15704Z","caller":"traceutil/trace.go:171","msg":"trace[1830968117] linearizableReadLoop","detail":"{readStateIndex:4862; appliedIndex:4861; }","duration":"111.399754ms","start":"2026-10-14T17:38:52.045607Z","end":"2026-10-14T17:38:52.157007Z","steps":["trace[1830968117] 'read index received' (duration: 31.187467ms)","trace[1830968117] 'applied index is now lower than readState.Index' (duration: 80.210207ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:38:52.157327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.689778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-14T17:38:52.157425Z","caller":"traceutil/trace.go:171","msg":"trace[1469231847] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4688; }","duration":"111.854891ms","start":"2026-10-14T17:38:52.045551Z","end":"2026-10-14T17:38:52.157406Z","steps":["trace[1469231847] 'agreement among raft nodes before linearized reading' (duration: 111.565417ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:38:52.291564Z","caller":"traceutil/trace.go:171","msg":"trace[724568294] transaction","detail":"{read_only:false; response_revision:4691; number_of_response:1; }","duration":"127.11587ms","start":"2026-10-14T17:38:52.164421Z","end":"2026-10-14T17:38:52.291537Z","steps":["trace[724568294] 'process raft request' (duration: 127.026759ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:38:52.291597Z","caller":"traceutil/trace.go:171","msg":"trace[2109599582] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"127.826621ms","start":"2026-10-14T17:38:52.163739Z","end":"2026-10-14T17:38:52.291565Z","steps":["trace[2109599582] 'process raft request' (duration: 123.164466ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:38:52.291898Z","caller":"traceutil/trace.go:171","msg":"trace[1725879507] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"127.05351ms","start":"2026-10-14T17:38:52.164825Z","end":"2026-10-14T17:38:52.291879Z","steps":["trace[1725879507] 'process raft request' (duration: 126.673965ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:39:02.480466Z","caller":"traceutil/trace.go:171","msg":"trace[812927988] linearizableReadLoop","detail":"{readStateIndex:5080; appliedIndex:5079; }","duration":"120.448461ms","start":"2026-10-14T17:39:02.359994Z","end":"2026-10-14T17:39:02.480443Z","steps":["trace[812927988] 'read index received' (duration: 120.276829ms)","trace[812927988] 'applied index is now lower than readState.Index' (duration: 168.222µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:39:02.480631Z","caller":"traceutil/trace.go:171","msg":"trace[1642960090] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"121.487785ms","start":"2026-10-14T17:39:02.359129Z","end":"2026-10-14T17:39:02.480617Z","steps":["trace[1642960090] 'process raft request' (duration: 120.837656ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:39:02.482192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.185461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-14T17:39:02.482273Z","caller":"traceutil/trace.go:171","msg":"trace[1730700110] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4900; }","duration":"122.253137ms","start":"2026-10-14T17:39:02.359974Z","end":"2026-10-14T17:39:02.482227Z","steps":["trace[1730700110] 'agreement among raft nodes before linearized reading' (duration: 120.845908ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:39:02.484549Z","caller":"traceutil/trace.go:171","msg":"trace[200459553] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"124.391036ms","start":"2026-10-14T17:39:02.36012Z","end":"2026-10-14T17:39:02.484511Z","steps":["trace[200459553] 'process raft request' (duration: 124.045341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:39:03.031726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.30049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T17:39:03.031891Z","caller":"traceutil/trace.go:171","msg":"trace[1960463139] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:4903; }","duration":"146.517364ms","start":"2026-10-14T17:39:02.885355Z","end":"2026-10-14T17:39:03.031872Z","steps":["trace[1960463139] 'count revisions from in-memory index tree' (duration: 146.220459ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:39:15.995582Z","caller":"traceutil/trace.go:171","msg":"trace[1489194998] linearizableReadLoop","detail":"{readStateIndex:5252; appliedIndex:5251; }","duration":"225.095342ms","start":"2026-10-14T17:39:15.770435Z","end":"2026-10-14T17:39:15.99553Z","steps":["trace[1489194998] 'read index received' (duration: 224.986581ms)","trace[1489194998] 'applied index is now lower than readState.Index' (duration: 107.421µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:39:15.995625Z","caller":"traceutil/trace.go:171","msg":"trace[1297744405] transaction","detail":"{read_only:false; response_revision:5066; number_of_response:1; }","duration":"318.782565ms","start":"2026-10-14T17:39:15.67681Z","end":"2026-10-14T17:39:15.995593Z","steps":["trace[1297744405] 'process raft request' (duration: 318.526672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:39:15.995758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.302525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T17:39:15.995787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:39:15.676785Z","time spent":"318.921897ms","remote":"127.0.0.1:36570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:39:15.995839Z","caller":"traceutil/trace.go:171","msg":"trace[2063124775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5066; }","duration":"225.442617ms","start":"2026-10-14T17:39:15.770379Z","end":"2026-10-14T17:39:15.995822Z","steps":["trace[2063124775] 'agreement among raft nodes before linearized reading' (duration: 225.296185ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:39:16.007739Z","caller":"traceutil/trace.go:171","msg":"trace[1480355701] transaction","detail":"{read_only:false; response_revision:5067; number_of_response:1; }","duration":"212.122118ms","start":"2026-10-14T17:39:15.795591Z","end":"2026-10-14T17:39:16.007713Z","steps":["trace[1480355701] 'process raft request' (duration: 211.841304ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:39:16.008087Z","caller":"traceutil/trace.go:171","msg":"trace[1164315391] transaction","detail":"{read_only:false; response_revision:5068; number_of_response:1; }","duration":"206.978539ms","start":"2026-10-14T17:39:15.80108Z","end":"2026-10-14T17:39:16.008059Z","steps":["trace[1164315391] 'process raft request' (duration: 206.532143ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:39:16.36844Z","caller":"traceutil/trace.go:171","msg":"trace[545733143] transaction","detail":"{read_only:false; response_revision:5074; number_of_response:1; }","duration":"111.799554ms","start":"2026-10-14T17:39:16.256615Z","end":"2026-10-14T17:39:16.368415Z","steps":["trace[545733143] 'process raft request' (duration: 101.765721ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:39:16.368684Z","caller":"traceutil/trace.go:171","msg":"trace[2082763243] transaction","detail":"{read_only:false; response_revision:5075; number_of_response:1; }","duration":"111.371589ms","start":"2026-10-14T17:39:16.257301Z","end":"2026-10-14T17:39:16.368673Z","steps":["trace[2082763243] 'process raft request' (duration: 111.007505ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:39:16.368989Z","caller":"traceutil/trace.go:171","msg":"trace[338298997] transaction","detail":"{read_only:false; response_revision:5076; number_of_response:1; }","duration":"110.181242ms","start":"2026-10-14T17:39:16.258759Z","end":"2026-10-14T17:39:16.36894Z","steps":["trace[338298997] 'process raft request' (duration: 109.624644ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:40:03.309009Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2881} {"level":"info","ts":"2026-10-14T17:40:03.470262Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2881,"took":"159.610906ms","hash":448002065,"current-db-size-bytes":52350976,"current-db-size":"52 MB","current-db-size-in-use-bytes":28712960,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-14T17:40:03.470363Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":448002065,"revision":2881,"compact-revision":-1} {"level":"info","ts":"2026-10-14T17:45:03.317204Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5493} {"level":"info","ts":"2026-10-14T17:45:03.391453Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5493,"took":"73.187255ms","hash":834005471,"current-db-size-bytes":52350976,"current-db-size":"52 MB","current-db-size-in-use-bytes":18419712,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-10-14T17:45:03.39192Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":834005471,"revision":5493,"compact-revision":2881}