{"level":"warn","ts":"2026-10-13T12:47:54.541832Z","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-13T12:47:54.541918Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.217: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.217:2380","--initial-cluster=instance=https://162.253.55.217:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.217:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.217: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-13T12:47:54.541986Z","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-13T12:47:54.541997Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-10-13T12:47:54.542017Z","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-13T12:47:54.542817Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"]} {"level":"info","ts":"2026-10-13T12:47:54.54291Z","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.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.217: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-13T12:47:54.548018Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.882279ms"} {"level":"info","ts":"2026-10-13T12:47:54.559657Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e368e6818bd6f7","cluster-id":"289e52865b7eaeb1"} {"level":"info","ts":"2026-10-13T12:47:54.559731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=()"} {"level":"info","ts":"2026-10-13T12:47:54.559754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 0"} {"level":"info","ts":"2026-10-13T12:47:54.559774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e368e6818bd6f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-13T12:47:54.559789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 1"} {"level":"info","ts":"2026-10-13T12:47:54.559838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"warn","ts":"2026-10-13T12:47:54.569353Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-13T12:47:54.57312Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-13T12:47:54.575062Z","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-13T12:47:54.577282Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e368e6818bd6f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-13T12:47:54.578172Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e368e6818bd6f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-13T12:47:54.579442Z","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-13T12:47:54.579634Z","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-13T12:47:54.579653Z","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-13T12:47:54.579991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"info","ts":"2026-10-13T12:47:54.580076Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","added-peer-id":"f1e368e6818bd6f7","added-peer-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-10-13T12:47:54.581357Z","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-13T12:47:54.581426Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-10-13T12:47:54.581488Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-10-13T12:47:54.581681Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e368e6818bd6f7","initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-13T12:47:54.581703Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-13T12:47:54.660675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 is starting a new election at term 1"} {"level":"info","ts":"2026-10-13T12:47:54.660717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-13T12:47:54.660736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgPreVoteResp from f1e368e6818bd6f7 at term 1"} {"level":"info","ts":"2026-10-13T12:47:54.660748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became candidate at term 2"} {"level":"info","ts":"2026-10-13T12:47:54.660852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgVoteResp from f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-10-13T12:47:54.660863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became leader at term 2"} {"level":"info","ts":"2026-10-13T12:47:54.660872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e368e6818bd6f7 elected leader f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-10-13T12:47:54.661783Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e368e6818bd6f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.217:2379]}","request-path":"/0/members/f1e368e6818bd6f7/attributes","cluster-id":"289e52865b7eaeb1","publish-timeout":"7s"} {"level":"info","ts":"2026-10-13T12:47:54.661818Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T12:47:54.661975Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T12:47:54.662094Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-13T12:47:54.662119Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-13T12:47:54.66485Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-13T12:47:54.665482Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T12:47:54.668492Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.217:2379"} {"level":"info","ts":"2026-10-13T12:47:54.67586Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T12:47:54.675951Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T12:47:54.675987Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T12:48:42.009522Z","caller":"traceutil/trace.go:171","msg":"trace[1351489464] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"142.444075ms","start":"2026-10-13T12:48:41.867048Z","end":"2026-10-13T12:48:42.009492Z","steps":["trace[1351489464] 'process raft request' (duration: 80.820721ms)","trace[1351489464] 'compare' (duration: 61.479022ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:48:42.911369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.083786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T12:48:42.911516Z","caller":"traceutil/trace.go:171","msg":"trace[347496237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"740.282729ms","start":"2026-10-13T12:48:42.171211Z","end":"2026-10-13T12:48:42.911493Z","steps":["trace[347496237] 'range keys from in-memory index tree' (duration: 739.995894ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:48:42.911555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:48:42.17117Z","time spent":"740.376509ms","remote":"127.0.0.1:38258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-13T12:48:42.912859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.896957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T12:48:42.912943Z","caller":"traceutil/trace.go:171","msg":"trace[864847648] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"312.926324ms","start":"2026-10-13T12:48:42.600006Z","end":"2026-10-13T12:48:42.912932Z","steps":["trace[864847648] 'read index received' (duration: 64.902µs)","trace[864847648] 'applied index is now lower than readState.Index' (duration: 312.860452ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:48:42.913022Z","caller":"traceutil/trace.go:171","msg":"trace[1831066880] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"507.977259ms","start":"2026-10-13T12:48:42.405035Z","end":"2026-10-13T12:48:42.913012Z","steps":["trace[1831066880] 'process raft request' (duration: 48.365074ms)","trace[1831066880] 'compare' (duration: 457.880981ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:48:42.913077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:48:42.405004Z","time spent":"508.04538ms","remote":"127.0.0.1:38350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T12:48:42.913399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.530498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-13T12:48:42.913702Z","caller":"traceutil/trace.go:171","msg":"trace[1789682597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"141.856702ms","start":"2026-10-13T12:48:42.771815Z","end":"2026-10-13T12:48:42.913672Z","steps":["trace[1789682597] 'agreement among raft nodes before linearized reading' (duration: 141.382526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:48:42.913758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.646775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-13T12:48:42.91382Z","caller":"traceutil/trace.go:171","msg":"trace[39979345] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"163.745076ms","start":"2026-10-13T12:48:42.750054Z","end":"2026-10-13T12:48:42.913799Z","steps":["trace[39979345] 'agreement among raft nodes before linearized reading' (duration: 163.038335ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:48:42.913349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.312549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j6krk\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-13T12:48:42.913972Z","caller":"traceutil/trace.go:171","msg":"trace[2001616295] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j6krk; range_end:; response_count:1; response_revision:604; }","duration":"314.00155ms","start":"2026-10-13T12:48:42.599957Z","end":"2026-10-13T12:48:42.913959Z","steps":["trace[2001616295] 'agreement among raft nodes before linearized reading' (duration: 313.190068ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:48:42.91402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:48:42.599935Z","time spent":"314.071531ms","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-j6krk\" "} {"level":"info","ts":"2026-10-13T12:48:43.029739Z","caller":"traceutil/trace.go:171","msg":"trace[2132247872] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"111.465146ms","start":"2026-10-13T12:48:42.918242Z","end":"2026-10-13T12:48:43.029707Z","steps":["trace[2132247872] 'process raft request' (duration: 97.311734ms)","trace[2132247872] 'compare' (duration: 13.942709ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:48:43.030173Z","caller":"traceutil/trace.go:171","msg":"trace[1469260067] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"109.158934ms","start":"2026-10-13T12:48:42.921Z","end":"2026-10-13T12:48:43.030159Z","steps":["trace[1469260067] 'process raft request' (duration: 108.663787ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:48:58.876298Z","caller":"traceutil/trace.go:171","msg":"trace[695487884] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"171.337566ms","start":"2026-10-13T12:48:58.704938Z","end":"2026-10-13T12:48:58.876276Z","steps":["trace[695487884] 'process raft request' (duration: 171.139413ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:50:19.272131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.414081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T12:50:19.272453Z","caller":"traceutil/trace.go:171","msg":"trace[1892506179] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"390.640811ms","start":"2026-10-13T12:50:18.881779Z","end":"2026-10-13T12:50:19.27242Z","steps":["trace[1892506179] 'process raft request' (duration: 99.845956ms)","trace[1892506179] 'compare' (duration: 290.265566ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:50:19.272545Z","caller":"traceutil/trace.go:171","msg":"trace[1201501213] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1925; }","duration":"179.035105ms","start":"2026-10-13T12:50:19.093496Z","end":"2026-10-13T12:50:19.272531Z","steps":["trace[1201501213] 'read index received' (duration: 57.012µs)","trace[1201501213] 'applied index is now lower than readState.Index' (duration: 178.977003ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:50:19.272624Z","caller":"traceutil/trace.go:171","msg":"trace[1789062317] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"174.919055ms","start":"2026-10-13T12:50:19.097696Z","end":"2026-10-13T12:50:19.272615Z","steps":["trace[1789062317] 'process raft request' (duration: 174.549922ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:50:19.272734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:50:18.881751Z","time spent":"390.810236ms","remote":"127.0.0.1:38538","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-13T12:50:19.27278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.155583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T12:50:19.272866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.358446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1070d74b-1885-4389-baff-c7235cf87437\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-13T12:50:19.27288Z","caller":"traceutil/trace.go:171","msg":"trace[1265101736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1853; }","duration":"100.284607ms","start":"2026-10-13T12:50:19.172575Z","end":"2026-10-13T12:50:19.272859Z","steps":["trace[1265101736] 'agreement among raft nodes before linearized reading' (duration: 100.172194ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:50:19.27297Z","caller":"traceutil/trace.go:171","msg":"trace[1865594911] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1070d74b-1885-4389-baff-c7235cf87437; range_end:; response_count:1; response_revision:1853; }","duration":"179.50764ms","start":"2026-10-13T12:50:19.093444Z","end":"2026-10-13T12:50:19.272951Z","steps":["trace[1865594911] 'agreement among raft nodes before linearized reading' (duration: 179.262892ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:50:19.629332Z","caller":"traceutil/trace.go:171","msg":"trace[1767016083] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1929; }","duration":"113.134092ms","start":"2026-10-13T12:50:19.516171Z","end":"2026-10-13T12:50:19.629305Z","steps":["trace[1767016083] 'read index received' (duration: 97.152791ms)","trace[1767016083] 'applied index is now lower than readState.Index' (duration: 15.979681ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:50:19.629379Z","caller":"traceutil/trace.go:171","msg":"trace[471474847] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"144.275757ms","start":"2026-10-13T12:50:19.485077Z","end":"2026-10-13T12:50:19.629353Z","steps":["trace[471474847] 'process raft request' (duration: 128.307447ms)","trace[471474847] 'compare' (duration: 15.596198ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:50:19.629836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.278617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-13T12:50:19.629957Z","caller":"traceutil/trace.go:171","msg":"trace[211539516] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1854; }","duration":"220.067054ms","start":"2026-10-13T12:50:19.40987Z","end":"2026-10-13T12:50:19.629937Z","steps":["trace[211539516] 'count revisions from in-memory index tree' (duration: 219.186094ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:50:19.630497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.274931ms","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-13T12:50:19.630735Z","caller":"traceutil/trace.go:171","msg":"trace[1259862454] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1855; }","duration":"114.490598ms","start":"2026-10-13T12:50:19.516086Z","end":"2026-10-13T12:50:19.630576Z","steps":["trace[1259862454] 'agreement among raft nodes before linearized reading' (duration: 113.346ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:50:32.845301Z","caller":"traceutil/trace.go:171","msg":"trace[1057130233] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"123.429732ms","start":"2026-10-13T12:50:32.721841Z","end":"2026-10-13T12:50:32.84527Z","steps":["trace[1057130233] 'process raft request' (duration: 115.267911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:50:32.845854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.348044ms","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-13T12:50:32.845911Z","caller":"traceutil/trace.go:171","msg":"trace[1540971744] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1968; }","duration":"125.444647ms","start":"2026-10-13T12:50:32.720452Z","end":"2026-10-13T12:50:32.845896Z","steps":["trace[1540971744] 'range keys from in-memory index tree' (duration: 124.62783ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:50:49.580908Z","caller":"traceutil/trace.go:171","msg":"trace[1274853259] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"100.975992ms","start":"2026-10-13T12:50:49.479902Z","end":"2026-10-13T12:50:49.580878Z","steps":["trace[1274853259] 'process raft request' (duration: 100.777837ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:50:49.979616Z","caller":"traceutil/trace.go:171","msg":"trace[1201591712] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"120.694665ms","start":"2026-10-13T12:50:49.858891Z","end":"2026-10-13T12:50:49.979586Z","steps":["trace[1201591712] 'process raft request' (duration: 120.487889ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:50:50.020482Z","caller":"traceutil/trace.go:171","msg":"trace[269578342] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"161.16484ms","start":"2026-10-13T12:50:49.859294Z","end":"2026-10-13T12:50:50.020459Z","steps":["trace[269578342] 'process raft request' (duration: 161.002145ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:50:50.141579Z","caller":"traceutil/trace.go:171","msg":"trace[401071856] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"116.495736ms","start":"2026-10-13T12:50:50.025059Z","end":"2026-10-13T12:50:50.141554Z","steps":["trace[401071856] 'process raft request' (duration: 112.532528ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:50:50.365374Z","caller":"traceutil/trace.go:171","msg":"trace[1715834039] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"122.870906ms","start":"2026-10-13T12:50:50.242473Z","end":"2026-10-13T12:50:50.365344Z","steps":["trace[1715834039] 'process raft request' (duration: 80.853599ms)","trace[1715834039] 'compare' (duration: 41.483742ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:50:50.804358Z","caller":"traceutil/trace.go:171","msg":"trace[1704458636] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"112.039383ms","start":"2026-10-13T12:50:50.692294Z","end":"2026-10-13T12:50:50.804333Z","steps":["trace[1704458636] 'process raft request' (duration: 103.020292ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:50:51.330073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.500185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T12:50:51.330147Z","caller":"traceutil/trace.go:171","msg":"trace[1354244142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2087; }","duration":"158.628058ms","start":"2026-10-13T12:50:51.171501Z","end":"2026-10-13T12:50:51.330129Z","steps":["trace[1354244142] 'range keys from in-memory index tree' (duration: 158.171625ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:50:51.753596Z","caller":"traceutil/trace.go:171","msg":"trace[458193380] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"167.567626ms","start":"2026-10-13T12:50:51.586011Z","end":"2026-10-13T12:50:51.753578Z","steps":["trace[458193380] 'process raft request' (duration: 167.393161ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:50:51.753548Z","caller":"traceutil/trace.go:171","msg":"trace[250101526] linearizableReadLoop","detail":"{readStateIndex:2170; appliedIndex:2169; }","duration":"157.278139ms","start":"2026-10-13T12:50:51.596246Z","end":"2026-10-13T12:50:51.753524Z","steps":["trace[250101526] 'read index received' (duration: 157.166495ms)","trace[250101526] 'applied index is now lower than readState.Index' (duration: 110.764µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:50:51.753898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.602598ms","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-13T12:50:51.75397Z","caller":"traceutil/trace.go:171","msg":"trace[570566746] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2088; }","duration":"157.757683ms","start":"2026-10-13T12:50:51.596171Z","end":"2026-10-13T12:50:51.753928Z","steps":["trace[570566746] 'agreement among raft nodes before linearized reading' (duration: 157.449284ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:52:06.143831Z","caller":"traceutil/trace.go:171","msg":"trace[2004945672] linearizableReadLoop","detail":"{readStateIndex:2688; appliedIndex:2687; }","duration":"142.985327ms","start":"2026-10-13T12:52:06.000822Z","end":"2026-10-13T12:52:06.143807Z","steps":["trace[2004945672] 'read index received' (duration: 142.746732ms)","trace[2004945672] 'applied index is now lower than readState.Index' (duration: 237.635µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:52:06.14389Z","caller":"traceutil/trace.go:171","msg":"trace[1554039526] transaction","detail":"{read_only:false; response_revision:2589; number_of_response:1; }","duration":"144.476322ms","start":"2026-10-13T12:52:05.999385Z","end":"2026-10-13T12:52:06.143861Z","steps":["trace[1554039526] 'process raft request' (duration: 144.276398ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:52:06.144021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.183051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-13T12:52:06.144097Z","caller":"traceutil/trace.go:171","msg":"trace[1245337607] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2589; }","duration":"143.345056ms","start":"2026-10-13T12:52:06.00074Z","end":"2026-10-13T12:52:06.144085Z","steps":["trace[1245337607] 'agreement among raft nodes before linearized reading' (duration: 143.210672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:52:38.374963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.647441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T12:52:38.375036Z","caller":"traceutil/trace.go:171","msg":"trace[1117090534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2766; }","duration":"202.767834ms","start":"2026-10-13T12:52:38.172252Z","end":"2026-10-13T12:52:38.37502Z","steps":["trace[1117090534] 'range keys from in-memory index tree' (duration: 202.560429ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:52:38.41278Z","caller":"traceutil/trace.go:171","msg":"trace[246150941] linearizableReadLoop","detail":"{readStateIndex:2874; appliedIndex:2873; }","duration":"102.318243ms","start":"2026-10-13T12:52:38.310439Z","end":"2026-10-13T12:52:38.412757Z","steps":["trace[246150941] 'read index received' (duration: 102.143539ms)","trace[246150941] 'applied index is now lower than readState.Index' (duration: 173.764µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:52:38.413085Z","caller":"traceutil/trace.go:171","msg":"trace[1139229349] transaction","detail":"{read_only:false; response_revision:2767; number_of_response:1; }","duration":"158.275699ms","start":"2026-10-13T12:52:38.254788Z","end":"2026-10-13T12:52:38.413064Z","steps":["trace[1139229349] 'process raft request' (duration: 157.848919ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:52:38.413195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.735892ms","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-13T12:52:38.413271Z","caller":"traceutil/trace.go:171","msg":"trace[1597249827] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2767; }","duration":"102.832484ms","start":"2026-10-13T12:52:38.310422Z","end":"2026-10-13T12:52:38.413254Z","steps":["trace[1597249827] 'agreement among raft nodes before linearized reading' (duration: 102.562228ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:57:03.724785Z","caller":"traceutil/trace.go:171","msg":"trace[398067725] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"135.142148ms","start":"2026-10-13T12:57:03.589609Z","end":"2026-10-13T12:57:03.724751Z","steps":["trace[398067725] 'process raft request' (duration: 134.867014ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:57:08.650061Z","caller":"traceutil/trace.go:171","msg":"trace[950744672] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"143.774755ms","start":"2026-10-13T12:57:08.506261Z","end":"2026-10-13T12:57:08.650035Z","steps":["trace[950744672] 'process raft request' (duration: 143.602232ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:57:09.18116Z","caller":"traceutil/trace.go:171","msg":"trace[456204256] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"107.619585ms","start":"2026-10-13T12:57:09.07351Z","end":"2026-10-13T12:57:09.181129Z","steps":["trace[456204256] 'process raft request' (duration: 107.206808ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:57:23.88094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.80924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T12:57:23.8811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.41307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-658cdf7f4f-rvh5p\" ","response":"range_response_count:1 size:11848"} {"level":"info","ts":"2026-10-13T12:57:23.88113Z","caller":"traceutil/trace.go:171","msg":"trace[364808269] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:5149; }","duration":"161.021913ms","start":"2026-10-13T12:57:23.720059Z","end":"2026-10-13T12:57:23.881081Z","steps":["trace[364808269] 'count revisions from in-memory index tree' (duration: 160.698968ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:57:23.881762Z","caller":"traceutil/trace.go:171","msg":"trace[1332645025] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-658cdf7f4f-rvh5p; range_end:; response_count:1; response_revision:5149; }","duration":"275.585542ms","start":"2026-10-13T12:57:23.605614Z","end":"2026-10-13T12:57:23.8812Z","steps":["trace[1332645025] 'range keys from in-memory index tree' (duration: 275.205856ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:57:24.023395Z","caller":"traceutil/trace.go:171","msg":"trace[262172745] linearizableReadLoop","detail":"{readStateIndex:5348; appliedIndex:5347; }","duration":"124.200655ms","start":"2026-10-13T12:57:23.899163Z","end":"2026-10-13T12:57:24.023364Z","steps":["trace[262172745] 'read index received' (duration: 123.992601ms)","trace[262172745] 'applied index is now lower than readState.Index' (duration: 206.594µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:57:24.023768Z","caller":"traceutil/trace.go:171","msg":"trace[933303094] transaction","detail":"{read_only:false; response_revision:5150; number_of_response:1; }","duration":"125.387435ms","start":"2026-10-13T12:57:23.898361Z","end":"2026-10-13T12:57:24.023748Z","steps":["trace[933303094] 'process raft request' (duration: 124.717734ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:57:24.023972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.773575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-13T12:57:24.024874Z","caller":"traceutil/trace.go:171","msg":"trace[1458330691] transaction","detail":"{read_only:false; response_revision:5151; number_of_response:1; }","duration":"124.847536ms","start":"2026-10-13T12:57:23.900005Z","end":"2026-10-13T12:57:24.024852Z","steps":["trace[1458330691] 'process raft request' (duration: 124.665043ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:57:24.024926Z","caller":"traceutil/trace.go:171","msg":"trace[1198375805] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5150; }","duration":"125.772931ms","start":"2026-10-13T12:57:23.899134Z","end":"2026-10-13T12:57:24.024907Z","steps":["trace[1198375805] 'agreement among raft nodes before linearized reading' (duration: 124.604232ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:57:24.024949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.986121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-13T12:57:24.025041Z","caller":"traceutil/trace.go:171","msg":"trace[1171964300] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:5152; }","duration":"106.072622ms","start":"2026-10-13T12:57:23.918931Z","end":"2026-10-13T12:57:24.025004Z","steps":["trace[1171964300] 'agreement among raft nodes before linearized reading' (duration: 105.907059ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:57:24.177782Z","caller":"traceutil/trace.go:171","msg":"trace[1768343276] transaction","detail":"{read_only:false; response_revision:5155; number_of_response:1; }","duration":"145.054694ms","start":"2026-10-13T12:57:24.032702Z","end":"2026-10-13T12:57:24.177757Z","steps":["trace[1768343276] 'process raft request' (duration: 144.985543ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:57:24.177946Z","caller":"traceutil/trace.go:171","msg":"trace[1361776813] transaction","detail":"{read_only:false; response_revision:5154; number_of_response:1; }","duration":"145.278226ms","start":"2026-10-13T12:57:24.032525Z","end":"2026-10-13T12:57:24.177804Z","steps":["trace[1361776813] 'process raft request' (duration: 89.302298ms)","trace[1361776813] 'compare' (duration: 55.730975ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:57:24.206123Z","caller":"traceutil/trace.go:171","msg":"trace[1084053810] transaction","detail":"{read_only:false; response_revision:5156; number_of_response:1; }","duration":"165.649017ms","start":"2026-10-13T12:57:24.040437Z","end":"2026-10-13T12:57:24.206086Z","steps":["trace[1084053810] 'process raft request' (duration: 165.527755ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:57:54.853924Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2857} {"level":"info","ts":"2026-10-13T12:57:54.928864Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2857,"took":"74.059882ms","hash":1250917958,"current-db-size-bytes":53481472,"current-db-size":"54 MB","current-db-size-in-use-bytes":27811840,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-13T12:57:54.928922Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1250917958,"revision":2857,"compact-revision":-1} {"level":"info","ts":"2026-10-13T13:02:05.614933Z","caller":"traceutil/trace.go:171","msg":"trace[1001878210] transaction","detail":"{read_only:false; response_revision:7118; number_of_response:1; }","duration":"112.612929ms","start":"2026-10-13T13:02:05.502297Z","end":"2026-10-13T13:02:05.61491Z","steps":["trace[1001878210] 'process raft request' (duration: 112.146179ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:02:54.865805Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5347} {"level":"info","ts":"2026-10-13T13:02:54.965445Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5347,"took":"98.609582ms","hash":508497780,"current-db-size-bytes":53481472,"current-db-size":"54 MB","current-db-size-in-use-bytes":18604032,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-13T13:02:54.965505Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":508497780,"revision":5347,"compact-revision":2857}