{"level":"warn","ts":"2026-10-03T22:02:19.069407Z","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-03T22:02:19.069491Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.95: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.95:2380","--initial-cluster=instance=https://162.253.55.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.95: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-03T22:02:19.069549Z","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-03T22:02:19.069556Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.95:2380"]} {"level":"info","ts":"2026-10-03T22:02:19.069573Z","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-03T22:02:19.07188Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.95:2379"]} {"level":"info","ts":"2026-10-03T22:02:19.072233Z","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.95:2380"],"listen-peer-urls":["https://162.253.55.95:2380"],"advertise-client-urls":["https://162.253.55.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.95: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-03T22:02:19.078183Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.655194ms"} {"level":"info","ts":"2026-10-03T22:02:19.091182Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"282167b6747c9cda","cluster-id":"dc65cfc6e389e361"} {"level":"info","ts":"2026-10-03T22:02:19.091378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda switched to configuration voters=()"} {"level":"info","ts":"2026-10-03T22:02:19.091408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda became follower at term 0"} {"level":"info","ts":"2026-10-03T22:02:19.09142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 282167b6747c9cda [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-03T22:02:19.091438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda became follower at term 1"} {"level":"info","ts":"2026-10-03T22:02:19.091483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda switched to configuration voters=(2891706469084601562)"} {"level":"warn","ts":"2026-10-03T22:02:19.105411Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-03T22:02:19.109406Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-03T22:02:19.113877Z","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-03T22:02:19.116011Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"282167b6747c9cda","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-03T22:02:19.11667Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"282167b6747c9cda","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-03T22:02:19.116831Z","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-03T22:02:19.116919Z","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-03T22:02:19.11693Z","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-03T22:02:19.11772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda switched to configuration voters=(2891706469084601562)"} {"level":"info","ts":"2026-10-03T22:02:19.117869Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dc65cfc6e389e361","local-member-id":"282167b6747c9cda","added-peer-id":"282167b6747c9cda","added-peer-peer-urls":["https://162.253.55.95:2380"]} {"level":"info","ts":"2026-10-03T22:02:19.118381Z","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-03T22:02:19.118572Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"282167b6747c9cda","initial-advertise-peer-urls":["https://162.253.55.95:2380"],"listen-peer-urls":["https://162.253.55.95:2380"],"advertise-client-urls":["https://162.253.55.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-03T22:02:19.118606Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-03T22:02:19.118731Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.95:2380"} {"level":"info","ts":"2026-10-03T22:02:19.118742Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.95:2380"} {"level":"info","ts":"2026-10-03T22:02:20.092748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda is starting a new election at term 1"} {"level":"info","ts":"2026-10-03T22:02:20.092895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda became pre-candidate at term 1"} {"level":"info","ts":"2026-10-03T22:02:20.092954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda received MsgPreVoteResp from 282167b6747c9cda at term 1"} {"level":"info","ts":"2026-10-03T22:02:20.092997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda became candidate at term 2"} {"level":"info","ts":"2026-10-03T22:02:20.093017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda received MsgVoteResp from 282167b6747c9cda at term 2"} {"level":"info","ts":"2026-10-03T22:02:20.093041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda became leader at term 2"} {"level":"info","ts":"2026-10-03T22:02:20.093083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 282167b6747c9cda elected leader 282167b6747c9cda at term 2"} {"level":"info","ts":"2026-10-03T22:02:20.094149Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T22:02:20.094977Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"282167b6747c9cda","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.95:2379]}","request-path":"/0/members/282167b6747c9cda/attributes","cluster-id":"dc65cfc6e389e361","publish-timeout":"7s"} {"level":"info","ts":"2026-10-03T22:02:20.09508Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-03T22:02:20.095028Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-03T22:02:20.095295Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-03T22:02:20.095347Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-03T22:02:20.095596Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dc65cfc6e389e361","local-member-id":"282167b6747c9cda","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T22:02:20.095816Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T22:02:20.095871Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T22:02:20.097098Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-03T22:02:20.098917Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.95:2379"} {"level":"info","ts":"2026-10-03T22:02:22.955858Z","caller":"traceutil/trace.go:171","msg":"trace[53033530] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"244.939955ms","start":"2026-10-03T22:02:22.710887Z","end":"2026-10-03T22:02:22.955827Z","steps":["trace[53033530] 'read index received' (duration: 233.403946ms)","trace[53033530] 'applied index is now lower than readState.Index' (duration: 11.534829ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:02:22.956076Z","caller":"traceutil/trace.go:171","msg":"trace[1189167758] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"246.181279ms","start":"2026-10-03T22:02:22.709828Z","end":"2026-10-03T22:02:22.956009Z","steps":["trace[1189167758] 'process raft request' (duration: 234.465907ms)","trace[1189167758] 'compare' (duration: 11.351875ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:02:22.956239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.296343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-10-03T22:02:22.956349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.779162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-03T22:02:22.956383Z","caller":"traceutil/trace.go:171","msg":"trace[969646174] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:72; }","duration":"245.501476ms","start":"2026-10-03T22:02:22.710859Z","end":"2026-10-03T22:02:22.956361Z","steps":["trace[969646174] 'agreement among raft nodes before linearized reading' (duration: 245.20597ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:02:22.956423Z","caller":"traceutil/trace.go:171","msg":"trace[670371384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"152.010067ms","start":"2026-10-03T22:02:22.804382Z","end":"2026-10-03T22:02:22.956392Z","steps":["trace[670371384] 'agreement among raft nodes before linearized reading' (duration: 151.784222ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:02:23.143939Z","caller":"traceutil/trace.go:171","msg":"trace[1743903562] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"108.26712ms","start":"2026-10-03T22:02:23.035618Z","end":"2026-10-03T22:02:23.143885Z","steps":["trace[1743903562] 'process raft request' (duration: 42.510457ms)","trace[1743903562] 'compare' (duration: 65.62821ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:02:24.133085Z","caller":"traceutil/trace.go:171","msg":"trace[2047400090] transaction","detail":"{read_only:false; response_revision:159; number_of_response:1; }","duration":"111.937349ms","start":"2026-10-03T22:02:24.02111Z","end":"2026-10-03T22:02:24.133047Z","steps":["trace[2047400090] 'process raft request' (duration: 66.755765ms)","trace[2047400090] 'compare' (duration: 44.876398ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:02:24.133014Z","caller":"traceutil/trace.go:171","msg":"trace[639629606] transaction","detail":"{read_only:false; response_revision:160; number_of_response:1; }","duration":"111.347287ms","start":"2026-10-03T22:02:24.021639Z","end":"2026-10-03T22:02:24.132986Z","steps":["trace[639629606] 'process raft request' (duration: 111.291576ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:02:24.571723Z","caller":"traceutil/trace.go:171","msg":"trace[1981567100] transaction","detail":"{read_only:false; response_revision:178; number_of_response:1; }","duration":"102.358302ms","start":"2026-10-03T22:02:24.469336Z","end":"2026-10-03T22:02:24.571694Z","steps":["trace[1981567100] 'process raft request' (duration: 56.135428ms)","trace[1981567100] 'compare' (duration: 46.131582ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:03:01.520989Z","caller":"traceutil/trace.go:171","msg":"trace[1090012012] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"131.451421ms","start":"2026-10-03T22:03:01.389442Z","end":"2026-10-03T22:03:01.520893Z","steps":["trace[1090012012] 'process raft request' (duration: 130.199071ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:03:01.653704Z","caller":"traceutil/trace.go:171","msg":"trace[713978665] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"211.079661ms","start":"2026-10-03T22:03:01.442584Z","end":"2026-10-03T22:03:01.653664Z","steps":["trace[713978665] 'process raft request' (duration: 207.121756ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:03:01.653788Z","caller":"traceutil/trace.go:171","msg":"trace[1991938967] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"200.778491ms","start":"2026-10-03T22:03:01.452977Z","end":"2026-10-03T22:03:01.653756Z","steps":["trace[1991938967] 'process raft request' (duration: 200.613329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:03:04.146999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.151396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-10-03T22:03:04.147148Z","caller":"traceutil/trace.go:171","msg":"trace[1461382149] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:660; }","duration":"106.36922ms","start":"2026-10-03T22:03:04.040733Z","end":"2026-10-03T22:03:04.147102Z","steps":["trace[1461382149] 'range keys from in-memory index tree' (duration: 105.831141ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:03:08.069917Z","caller":"traceutil/trace.go:171","msg":"trace[1440894666] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"234.025501ms","start":"2026-10-03T22:03:07.83586Z","end":"2026-10-03T22:03:08.069886Z","steps":["trace[1440894666] 'process raft request' (duration: 170.163757ms)","trace[1440894666] 'compare' (duration: 63.60782ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:03:08.127141Z","caller":"traceutil/trace.go:171","msg":"trace[1359535430] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"291.042635ms","start":"2026-10-03T22:03:07.836046Z","end":"2026-10-03T22:03:08.127089Z","steps":["trace[1359535430] 'process raft request' (duration: 290.667159ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:03:08.127252Z","caller":"traceutil/trace.go:171","msg":"trace[707085222] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"287.098611ms","start":"2026-10-03T22:03:07.840131Z","end":"2026-10-03T22:03:08.12723Z","steps":["trace[707085222] 'process raft request' (duration: 286.891478ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:03:08.127152Z","caller":"traceutil/trace.go:171","msg":"trace[158607806] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:721; }","duration":"275.548566ms","start":"2026-10-03T22:03:07.851551Z","end":"2026-10-03T22:03:08.127099Z","steps":["trace[158607806] 'read index received' (duration: 154.396004ms)","trace[158607806] 'applied index is now lower than readState.Index' (duration: 121.150952ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:03:08.127478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.894242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-f6xg7\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-03T22:03:08.127459Z","caller":"traceutil/trace.go:171","msg":"trace[926072343] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"289.022212ms","start":"2026-10-03T22:03:07.838389Z","end":"2026-10-03T22:03:08.127411Z","steps":["trace[926072343] 'process raft request' (duration: 288.503864ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:03:08.127557Z","caller":"traceutil/trace.go:171","msg":"trace[1894886375] range","detail":"{range_begin:/registry/pods/kube-system/cilium-f6xg7; range_end:; response_count:1; response_revision:701; }","duration":"275.997333ms","start":"2026-10-03T22:03:07.851543Z","end":"2026-10-03T22:03:08.12754Z","steps":["trace[1894886375] 'agreement among raft nodes before linearized reading' (duration: 275.679238ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:03:08.250308Z","caller":"traceutil/trace.go:171","msg":"trace[1259086580] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"108.758314ms","start":"2026-10-03T22:03:08.141511Z","end":"2026-10-03T22:03:08.25027Z","steps":["trace[1259086580] 'process raft request' (duration: 106.128352ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:03:08.421643Z","caller":"traceutil/trace.go:171","msg":"trace[1222917065] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"112.582275ms","start":"2026-10-03T22:03:08.309002Z","end":"2026-10-03T22:03:08.421584Z","steps":["trace[1222917065] 'process raft request' (duration: 99.754259ms)","trace[1222917065] 'compare' (duration: 12.515311ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:03:08.983064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.423754ms","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-10-03T22:03:08.983171Z","caller":"traceutil/trace.go:171","msg":"trace[761294835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:703; }","duration":"298.672448ms","start":"2026-10-03T22:03:08.684475Z","end":"2026-10-03T22:03:08.983147Z","steps":["trace[761294835] 'range keys from in-memory index tree' (duration: 298.1782ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:03:08.983148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.143402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-03T22:03:08.983244Z","caller":"traceutil/trace.go:171","msg":"trace[1931917293] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:703; }","duration":"441.307924ms","start":"2026-10-03T22:03:08.541909Z","end":"2026-10-03T22:03:08.983217Z","steps":["trace[1931917293] 'range keys from in-memory index tree' (duration: 440.936379ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:03:08.98329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:03:08.541896Z","time spent":"441.384065ms","remote":"127.0.0.1:49714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-03T22:03:25.08Z","caller":"traceutil/trace.go:171","msg":"trace[910711871] linearizableReadLoop","detail":"{readStateIndex:842; appliedIndex:841; }","duration":"117.806603ms","start":"2026-10-03T22:03:24.962166Z","end":"2026-10-03T22:03:25.079972Z","steps":["trace[910711871] 'read index received' (duration: 117.61548ms)","trace[910711871] 'applied index is now lower than readState.Index' (duration: 189.633µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:03:25.080379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.187188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-l4n2v\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-10-03T22:03:25.080473Z","caller":"traceutil/trace.go:171","msg":"trace[1578808800] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-l4n2v; range_end:; response_count:1; response_revision:812; }","duration":"118.31126ms","start":"2026-10-03T22:03:24.962141Z","end":"2026-10-03T22:03:25.080452Z","steps":["trace[1578808800] 'agreement among raft nodes before linearized reading' (duration: 118.087537ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:04:46.139857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.485618ms","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-03T22:04:46.139977Z","caller":"traceutil/trace.go:171","msg":"trace[343962807] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1897; }","duration":"158.673897ms","start":"2026-10-03T22:04:45.981275Z","end":"2026-10-03T22:04:46.139949Z","steps":["trace[343962807] 'range keys from in-memory index tree' (duration: 158.193386ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:04:46.585802Z","caller":"traceutil/trace.go:171","msg":"trace[1404621608] linearizableReadLoop","detail":"{readStateIndex:1972; appliedIndex:1971; }","duration":"172.255234ms","start":"2026-10-03T22:04:46.413522Z","end":"2026-10-03T22:04:46.585777Z","steps":["trace[1404621608] 'read index received' (duration: 172.098787ms)","trace[1404621608] 'applied index is now lower than readState.Index' (duration: 152.696µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:04:46.585895Z","caller":"traceutil/trace.go:171","msg":"trace[757215714] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"193.037821ms","start":"2026-10-03T22:04:46.392816Z","end":"2026-10-03T22:04:46.585854Z","steps":["trace[757215714] 'process raft request' (duration: 192.7886ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:04:46.586065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.563027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-03T22:04:46.586122Z","caller":"traceutil/trace.go:171","msg":"trace[1201020414] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1899; }","duration":"150.674422ms","start":"2026-10-03T22:04:46.435434Z","end":"2026-10-03T22:04:46.586108Z","steps":["trace[1201020414] 'agreement among raft nodes before linearized reading' (duration: 150.501084ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:04:46.586124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.574347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-256a034c-a491-4a66-8300-00a26f7b9bcb\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-03T22:04:46.586225Z","caller":"traceutil/trace.go:171","msg":"trace[1934020971] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-256a034c-a491-4a66-8300-00a26f7b9bcb; range_end:; response_count:1; response_revision:1899; }","duration":"172.734184ms","start":"2026-10-03T22:04:46.413464Z","end":"2026-10-03T22:04:46.586198Z","steps":["trace[1934020971] 'agreement among raft nodes before linearized reading' (duration: 172.439131ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:06:27.39281Z","caller":"traceutil/trace.go:171","msg":"trace[933714555] linearizableReadLoop","detail":"{readStateIndex:2701; appliedIndex:2700; }","duration":"142.481611ms","start":"2026-10-03T22:06:27.250309Z","end":"2026-10-03T22:06:27.39279Z","steps":["trace[933714555] 'read index received' (duration: 142.271025ms)","trace[933714555] 'applied index is now lower than readState.Index' (duration: 209.886µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:06:27.393078Z","caller":"traceutil/trace.go:171","msg":"trace[1324656619] transaction","detail":"{read_only:false; response_revision:2605; number_of_response:1; }","duration":"190.72069ms","start":"2026-10-03T22:06:27.202345Z","end":"2026-10-03T22:06:27.393066Z","steps":["trace[1324656619] 'process raft request' (duration: 190.290207ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:06:27.393334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.006946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-03T22:06:27.393378Z","caller":"traceutil/trace.go:171","msg":"trace[2077560246] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2605; }","duration":"143.083538ms","start":"2026-10-03T22:06:27.250285Z","end":"2026-10-03T22:06:27.393368Z","steps":["trace[2077560246] 'agreement among raft nodes before linearized reading' (duration: 142.939694ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:07:04.67214Z","caller":"traceutil/trace.go:171","msg":"trace[228742301] transaction","detail":"{read_only:false; response_revision:2810; number_of_response:1; }","duration":"148.216578ms","start":"2026-10-03T22:07:04.5239Z","end":"2026-10-03T22:07:04.672117Z","steps":["trace[228742301] 'process raft request' (duration: 148.088975ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:07:04.7167Z","caller":"traceutil/trace.go:171","msg":"trace[1692163838] transaction","detail":"{read_only:false; response_revision:2811; number_of_response:1; }","duration":"188.745818ms","start":"2026-10-03T22:07:04.527928Z","end":"2026-10-03T22:07:04.716674Z","steps":["trace[1692163838] 'process raft request' (duration: 188.537913ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:11:49.831641Z","caller":"traceutil/trace.go:171","msg":"trace[93234310] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"106.306334ms","start":"2026-10-03T22:11:49.725304Z","end":"2026-10-03T22:11:49.83161Z","steps":["trace[93234310] 'process raft request' (duration: 88.794065ms)","trace[93234310] 'compare' (duration: 17.260095ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:12:10.835464Z","caller":"traceutil/trace.go:171","msg":"trace[577868273] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"357.288543ms","start":"2026-10-03T22:12:10.478135Z","end":"2026-10-03T22:12:10.835424Z","steps":["trace[577868273] 'process raft request' (duration: 356.842686ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:12:10.835627Z","caller":"traceutil/trace.go:171","msg":"trace[1192259805] linearizableReadLoop","detail":"{readStateIndex:5474; appliedIndex:5473; }","duration":"356.695314ms","start":"2026-10-03T22:12:10.478879Z","end":"2026-10-03T22:12:10.835575Z","steps":["trace[1192259805] 'read index received' (duration: 356.012922ms)","trace[1192259805] 'applied index is now lower than readState.Index' (duration: 674.932µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:12:10.836197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:12:10.478116Z","time spent":"357.473076ms","remote":"127.0.0.1:49714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:12:10.852169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.409038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T22:12:10.852298Z","caller":"traceutil/trace.go:171","msg":"trace[1517594533] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:5276; }","duration":"178.593871ms","start":"2026-10-03T22:12:10.673682Z","end":"2026-10-03T22:12:10.852276Z","steps":["trace[1517594533] 'agreement among raft nodes before linearized reading' (duration: 162.55038ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:12:10.852345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.962222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-03T22:12:10.852448Z","caller":"traceutil/trace.go:171","msg":"trace[1823483048] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5276; }","duration":"265.142285ms","start":"2026-10-03T22:12:10.587277Z","end":"2026-10-03T22:12:10.85242Z","steps":["trace[1823483048] 'agreement among raft nodes before linearized reading' (duration: 248.92489ms)","trace[1823483048] 'range keys from in-memory index tree' (duration: 15.878519ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:12:10.852789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.901075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-03T22:12:10.852855Z","caller":"traceutil/trace.go:171","msg":"trace[1333461986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5276; }","duration":"374.014236ms","start":"2026-10-03T22:12:10.478823Z","end":"2026-10-03T22:12:10.852837Z","steps":["trace[1333461986] 'agreement among raft nodes before linearized reading' (duration: 357.341324ms)","trace[1333461986] 'range keys from in-memory index tree' (duration: 16.501109ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:12:10.852887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:12:10.478795Z","time spent":"374.082678ms","remote":"127.0.0.1:49714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-03T22:12:11.202868Z","caller":"traceutil/trace.go:171","msg":"trace[1289269301] transaction","detail":"{read_only:false; response_revision:5283; number_of_response:1; }","duration":"139.229835ms","start":"2026-10-03T22:12:11.063598Z","end":"2026-10-03T22:12:11.202828Z","steps":["trace[1289269301] 'process raft request' (duration: 80.368449ms)","trace[1289269301] 'compare' (duration: 58.55252ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:12:11.203332Z","caller":"traceutil/trace.go:171","msg":"trace[350623546] transaction","detail":"{read_only:false; response_revision:5284; number_of_response:1; }","duration":"139.574291ms","start":"2026-10-03T22:12:11.063729Z","end":"2026-10-03T22:12:11.203303Z","steps":["trace[350623546] 'process raft request' (duration: 139.030131ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:12:11.20382Z","caller":"traceutil/trace.go:171","msg":"trace[1057165340] linearizableReadLoop","detail":"{readStateIndex:5483; appliedIndex:5480; }","duration":"138.913649ms","start":"2026-10-03T22:12:11.064882Z","end":"2026-10-03T22:12:11.203795Z","steps":["trace[1057165340] 'read index received' (duration: 19.561271ms)","trace[1057165340] 'applied index is now lower than readState.Index' (duration: 119.350698ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:12:11.204119Z","caller":"traceutil/trace.go:171","msg":"trace[1443144498] transaction","detail":"{read_only:false; response_revision:5285; number_of_response:1; }","duration":"140.13743ms","start":"2026-10-03T22:12:11.063961Z","end":"2026-10-03T22:12:11.204098Z","steps":["trace[1443144498] 'process raft request' (duration: 139.096932ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:12:11.204433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.522399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b09dc9e6-80f7-4f2a-ac69-bdd962bbe77a\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-10-03T22:12:11.205957Z","caller":"traceutil/trace.go:171","msg":"trace[369722206] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b09dc9e6-80f7-4f2a-ac69-bdd962bbe77a; range_end:; response_count:1; response_revision:5286; }","duration":"141.070146ms","start":"2026-10-03T22:12:11.064853Z","end":"2026-10-03T22:12:11.205923Z","steps":["trace[369722206] 'agreement among raft nodes before linearized reading' (duration: 139.353507ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:12:20.128971Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2891} {"level":"info","ts":"2026-10-03T22:12:20.212259Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2891,"took":"81.30766ms","hash":2297358884,"current-db-size-bytes":52461568,"current-db-size":"52 MB","current-db-size-in-use-bytes":27406336,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-03T22:12:20.212337Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2297358884,"revision":2891,"compact-revision":-1} {"level":"info","ts":"2026-10-03T22:17:20.135364Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5351} {"level":"info","ts":"2026-10-03T22:17:20.215946Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5351,"took":"78.738978ms","hash":975299391,"current-db-size-bytes":52461568,"current-db-size":"52 MB","current-db-size-in-use-bytes":18747392,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-03T22:17:20.216038Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":975299391,"revision":5351,"compact-revision":2891}