{"level":"warn","ts":"2026-04-18T22:14:11.017287Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-18T22:14:11.017432Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49: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://199.204.45.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49: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-04-18T22:14:11.017522Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-18T22:14:11.017536Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-04-18T22:14:11.01756Z","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-04-18T22:14:11.018264Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-04-18T22:14:11.018342Z","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":16,"max-cpu-available":16,"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://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49: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-04-18T22:14:11.023533Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.933571ms"} {"level":"info","ts":"2026-04-18T22:14:11.031539Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-04-18T22:14:11.031681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-04-18T22:14:11.031715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-04-18T22:14:11.031734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-18T22:14:11.031747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-04-18T22:14:11.031802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-04-18T22:14:11.037008Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-18T22:14:11.03883Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-18T22:14:11.040416Z","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-04-18T22:14:11.04293Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-18T22:14:11.043285Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-18T22:14:11.043319Z","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-04-18T22:14:11.04342Z","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-04-18T22:14:11.043435Z","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-04-18T22:14:11.04877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-04-18T22:14:11.049028Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-04-18T22:14:11.058067Z","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-04-18T22:14:11.058386Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-18T22:14:11.058434Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-18T22:14:11.058607Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-04-18T22:14:11.058627Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-04-18T22:14:11.932938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-04-18T22:14:11.933042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-18T22:14:11.933092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-04-18T22:14:11.933123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-04-18T22:14:11.933141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-04-18T22:14:11.933159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-04-18T22:14:11.93318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-04-18T22:14:11.935356Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:14:11.935516Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T22:14:11.935505Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-04-18T22:14:11.935714Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T22:14:11.935889Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-18T22:14:11.935926Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-18T22:14:11.936655Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:14:11.936793Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:14:11.936835Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:14:11.93933Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-04-18T22:14:11.939768Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-18T22:14:39.956508Z","caller":"traceutil/trace.go:171","msg":"trace[857564687] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"272.957312ms","start":"2026-04-18T22:14:39.683509Z","end":"2026-04-18T22:14:39.956466Z","steps":["trace[857564687] 'process raft request' (duration: 242.64307ms)","trace[857564687] 'compare' (duration: 30.070357ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:14:39.956523Z","caller":"traceutil/trace.go:171","msg":"trace[1838966866] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"270.162164ms","start":"2026-04-18T22:14:39.686333Z","end":"2026-04-18T22:14:39.956496Z","steps":["trace[1838966866] 'process raft request' (duration: 270.099693ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:39.956556Z","caller":"traceutil/trace.go:171","msg":"trace[755576635] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"272.975462ms","start":"2026-04-18T22:14:39.683558Z","end":"2026-04-18T22:14:39.956533Z","steps":["trace[755576635] 'process raft request' (duration: 272.826069ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:42.774673Z","caller":"traceutil/trace.go:171","msg":"trace[1344850636] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"133.216094ms","start":"2026-04-18T22:14:42.641409Z","end":"2026-04-18T22:14:42.774625Z","steps":["trace[1344850636] 'process raft request' (duration: 132.860017ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:47.759641Z","caller":"traceutil/trace.go:171","msg":"trace[1236759449] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"194.076988ms","start":"2026-04-18T22:14:47.565526Z","end":"2026-04-18T22:14:47.759603Z","steps":["trace[1236759449] 'process raft request' (duration: 193.317003ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:47.880961Z","caller":"traceutil/trace.go:171","msg":"trace[27355537] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:589; }","duration":"283.520894ms","start":"2026-04-18T22:14:47.597409Z","end":"2026-04-18T22:14:47.88093Z","steps":["trace[27355537] 'read index received' (duration: 161.047816ms)","trace[27355537] 'applied index is now lower than readState.Index' (duration: 122.471018ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:14:47.881132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.684197ms","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-04-18T22:14:47.8813Z","caller":"traceutil/trace.go:171","msg":"trace[446765594] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:572; }","duration":"283.911462ms","start":"2026-04-18T22:14:47.597372Z","end":"2026-04-18T22:14:47.881284Z","steps":["trace[446765594] 'agreement among raft nodes before linearized reading' (duration: 283.636937ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:47.881821Z","caller":"traceutil/trace.go:171","msg":"trace[942707667] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"285.945853ms","start":"2026-04-18T22:14:47.595014Z","end":"2026-04-18T22:14:47.88096Z","steps":["trace[942707667] 'process raft request' (duration: 273.745988ms)","trace[942707667] 'compare' (duration: 11.933139ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:14:48.345408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.669267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ldddv\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-18T22:14:48.345535Z","caller":"traceutil/trace.go:171","msg":"trace[391635413] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ldddv; range_end:; response_count:1; response_revision:574; }","duration":"296.855911ms","start":"2026-04-18T22:14:48.048647Z","end":"2026-04-18T22:14:48.345503Z","steps":["trace[391635413] 'range keys from in-memory index tree' (duration: 296.445033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:14:48.345501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.699631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-18T22:14:48.345564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.190279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-18T22:14:48.345673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.524234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:14:48.34604Z","caller":"traceutil/trace.go:171","msg":"trace[754809023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"301.331931ms","start":"2026-04-18T22:14:48.044692Z","end":"2026-04-18T22:14:48.346024Z","steps":["trace[754809023] 'range keys from in-memory index tree' (duration: 300.439043ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:48.345673Z","caller":"traceutil/trace.go:171","msg":"trace[1465713503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:574; }","duration":"199.355253ms","start":"2026-04-18T22:14:48.146295Z","end":"2026-04-18T22:14:48.345651Z","steps":["trace[1465713503] 'range keys from in-memory index tree' (duration: 198.926514ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:48.345607Z","caller":"traceutil/trace.go:171","msg":"trace[1862266737] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"327.862714ms","start":"2026-04-18T22:14:48.017718Z","end":"2026-04-18T22:14:48.345581Z","steps":["trace[1862266737] 'range keys from in-memory index tree' (duration: 327.563058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:14:48.346136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:14:48.044681Z","time spent":"301.441563ms","remote":"127.0.0.1:49656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:14:48.346253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:14:48.017686Z","time spent":"328.513026ms","remote":"127.0.0.1:49904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-18T22:15:04.174504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.52395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:15:04.174704Z","caller":"traceutil/trace.go:171","msg":"trace[151236342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:738; }","duration":"128.704703ms","start":"2026-04-18T22:15:04.045941Z","end":"2026-04-18T22:15:04.174645Z","steps":["trace[151236342] 'range keys from in-memory index tree' (duration: 128.395117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:04.174685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.214217ms","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-04-18T22:15:04.174843Z","caller":"traceutil/trace.go:171","msg":"trace[917544123] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:738; }","duration":"172.423711ms","start":"2026-04-18T22:15:04.002385Z","end":"2026-04-18T22:15:04.174808Z","steps":["trace[917544123] 'range keys from in-memory index tree' (duration: 172.058065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:18.159028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.049152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:16:18.159242Z","caller":"traceutil/trace.go:171","msg":"trace[675931602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1804; }","duration":"113.341588ms","start":"2026-04-18T22:16:18.045875Z","end":"2026-04-18T22:16:18.159217Z","steps":["trace[675931602] 'range keys from in-memory index tree' (duration: 112.925419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:18.159389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.669214ms","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-04-18T22:16:18.159472Z","caller":"traceutil/trace.go:171","msg":"trace[1149796034] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1804; }","duration":"199.811067ms","start":"2026-04-18T22:16:17.959644Z","end":"2026-04-18T22:16:18.159455Z","steps":["trace[1149796034] 'range keys from in-memory index tree' (duration: 199.45408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:18.159673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.99468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-18T22:16:18.159815Z","caller":"traceutil/trace.go:171","msg":"trace[442981593] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1804; }","duration":"120.201385ms","start":"2026-04-18T22:16:18.039587Z","end":"2026-04-18T22:16:18.159789Z","steps":["trace[442981593] 'range keys from in-memory index tree' (duration: 119.654444ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:16:18.705447Z","caller":"traceutil/trace.go:171","msg":"trace[1542532773] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"139.010641ms","start":"2026-04-18T22:16:18.566415Z","end":"2026-04-18T22:16:18.705426Z","steps":["trace[1542532773] 'process raft request' (duration: 138.909969ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:16:18.710852Z","caller":"traceutil/trace.go:171","msg":"trace[918208389] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"139.199014ms","start":"2026-04-18T22:16:18.571625Z","end":"2026-04-18T22:16:18.710824Z","steps":["trace[918208389] 'process raft request' (duration: 139.023211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:03.501385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.49399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:17:03.501528Z","caller":"traceutil/trace.go:171","msg":"trace[1124794532] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2102; }","duration":"140.720744ms","start":"2026-04-18T22:17:03.360779Z","end":"2026-04-18T22:17:03.501499Z","steps":["trace[1124794532] 'count revisions from in-memory index tree' (duration: 140.311425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:09.461519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.017369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:17:09.461655Z","caller":"traceutil/trace.go:171","msg":"trace[548055788] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"192.044579ms","start":"2026-04-18T22:17:09.269589Z","end":"2026-04-18T22:17:09.461634Z","steps":["trace[548055788] 'process raft request' (duration: 73.084851ms)","trace[548055788] 'compare' (duration: 117.907977ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:17:09.917194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.272124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-18T22:17:09.917328Z","caller":"traceutil/trace.go:171","msg":"trace[825131466] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2134; }","duration":"303.469448ms","start":"2026-04-18T22:17:09.613834Z","end":"2026-04-18T22:17:09.917304Z","steps":["trace[825131466] 'range keys from in-memory index tree' (duration: 302.942616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:09.917393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:09.613817Z","time spent":"303.56266ms","remote":"127.0.0.1:49904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-18T22:17:09.917652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.657111ms","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-04-18T22:17:09.91785Z","caller":"traceutil/trace.go:171","msg":"trace[457536413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2134; }","duration":"335.883116ms","start":"2026-04-18T22:17:09.581893Z","end":"2026-04-18T22:17:09.917776Z","steps":["trace[457536413] 'range keys from in-memory index tree' (duration: 335.191222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:09.917951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:09.581871Z","time spent":"336.062979ms","remote":"127.0.0.1:49904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-18T22:18:04.8521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.050908ms","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-04-18T22:18:04.852195Z","caller":"traceutil/trace.go:171","msg":"trace[1426893377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2602; }","duration":"100.20596ms","start":"2026-04-18T22:18:04.751973Z","end":"2026-04-18T22:18:04.852179Z","steps":["trace[1426893377] 'range keys from in-memory index tree' (duration: 99.783112ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:37.939477Z","caller":"traceutil/trace.go:171","msg":"trace[1974517099] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"158.546615ms","start":"2026-04-18T22:18:37.780893Z","end":"2026-04-18T22:18:37.93944Z","steps":["trace[1974517099] 'process raft request' (duration: 158.27639ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:08.562272Z","caller":"traceutil/trace.go:171","msg":"trace[2132459194] transaction","detail":"{read_only:false; response_revision:3797; number_of_response:1; }","duration":"206.860416ms","start":"2026-04-18T22:21:08.355384Z","end":"2026-04-18T22:21:08.562244Z","steps":["trace[2132459194] 'process raft request' (duration: 206.676422ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:08.562252Z","caller":"traceutil/trace.go:171","msg":"trace[128466183] linearizableReadLoop","detail":"{readStateIndex:3935; appliedIndex:3934; }","duration":"205.841735ms","start":"2026-04-18T22:21:08.356384Z","end":"2026-04-18T22:21:08.562226Z","steps":["trace[128466183] 'read index received' (duration: 205.704423ms)","trace[128466183] 'applied index is now lower than readState.Index' (duration: 135.552µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:21:08.562583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.173892ms","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-04-18T22:21:08.562674Z","caller":"traceutil/trace.go:171","msg":"trace[1705424804] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3797; }","duration":"206.324545ms","start":"2026-04-18T22:21:08.35633Z","end":"2026-04-18T22:21:08.562654Z","steps":["trace[1705424804] 'agreement among raft nodes before linearized reading' (duration: 206.007569ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:08.564794Z","caller":"traceutil/trace.go:171","msg":"trace[1932732927] transaction","detail":"{read_only:false; response_revision:3798; number_of_response:1; }","duration":"207.905718ms","start":"2026-04-18T22:21:08.356864Z","end":"2026-04-18T22:21:08.56477Z","steps":["trace[1932732927] 'process raft request' (duration: 207.745563ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:22:36.881021Z","caller":"traceutil/trace.go:171","msg":"trace[710153423] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"250.348634ms","start":"2026-04-18T22:22:36.630649Z","end":"2026-04-18T22:22:36.880998Z","steps":["trace[710153423] 'process raft request' (duration: 250.208741ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:22:36.892887Z","caller":"traceutil/trace.go:171","msg":"trace[1038467605] linearizableReadLoop","detail":"{readStateIndex:5186; appliedIndex:5184; }","duration":"167.865739ms","start":"2026-04-18T22:22:36.724981Z","end":"2026-04-18T22:22:36.892847Z","steps":["trace[1038467605] 'read index received' (duration: 155.814787ms)","trace[1038467605] 'applied index is now lower than readState.Index' (duration: 12.049641ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:22:36.893256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.259586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-08ba4233-76ff-4b7d-9cb4-2d29b70b6777\" ","response":"range_response_count:1 size:3720"} {"level":"info","ts":"2026-04-18T22:22:36.893295Z","caller":"traceutil/trace.go:171","msg":"trace[17385775] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-08ba4233-76ff-4b7d-9cb4-2d29b70b6777; range_end:; response_count:1; response_revision:5003; }","duration":"168.352558ms","start":"2026-04-18T22:22:36.724931Z","end":"2026-04-18T22:22:36.893284Z","steps":["trace[17385775] 'agreement among raft nodes before linearized reading' (duration: 168.254586ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:22:36.892909Z","caller":"traceutil/trace.go:171","msg":"trace[126036841] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"223.172459ms","start":"2026-04-18T22:22:36.669692Z","end":"2026-04-18T22:22:36.892865Z","steps":["trace[126036841] 'process raft request' (duration: 222.880133ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:22:37.330585Z","caller":"traceutil/trace.go:171","msg":"trace[73977757] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"252.66186ms","start":"2026-04-18T22:22:37.077898Z","end":"2026-04-18T22:22:37.330559Z","steps":["trace[73977757] 'process raft request' (duration: 208.34718ms)","trace[73977757] 'compare' (duration: 44.061915ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:22:37.346567Z","caller":"traceutil/trace.go:171","msg":"trace[432157845] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"166.495272ms","start":"2026-04-18T22:22:37.180045Z","end":"2026-04-18T22:22:37.34654Z","steps":["trace[432157845] 'process raft request' (duration: 166.440601ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:22:37.346605Z","caller":"traceutil/trace.go:171","msg":"trace[125300309] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"268.337845ms","start":"2026-04-18T22:22:37.078223Z","end":"2026-04-18T22:22:37.34656Z","steps":["trace[125300309] 'process raft request' (duration: 267.955858ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:22:37.346708Z","caller":"traceutil/trace.go:171","msg":"trace[1044969682] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"267.192822ms","start":"2026-04-18T22:22:37.07947Z","end":"2026-04-18T22:22:37.346663Z","steps":["trace[1044969682] 'process raft request' (duration: 266.943307ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:22:37.346692Z","caller":"traceutil/trace.go:171","msg":"trace[1436308595] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"268.299434ms","start":"2026-04-18T22:22:37.07833Z","end":"2026-04-18T22:22:37.346629Z","steps":["trace[1436308595] 'process raft request' (duration: 267.998888ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:22:48.781138Z","caller":"traceutil/trace.go:171","msg":"trace[483169476] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"175.87804ms","start":"2026-04-18T22:22:48.605224Z","end":"2026-04-18T22:22:48.781102Z","steps":["trace[483169476] 'process raft request' (duration: 153.383778ms)","trace[483169476] 'compare' (duration: 22.295588ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:22:48.797672Z","caller":"traceutil/trace.go:171","msg":"trace[479166312] transaction","detail":"{read_only:false; response_revision:5244; number_of_response:1; }","duration":"144.170314ms","start":"2026-04-18T22:22:48.653469Z","end":"2026-04-18T22:22:48.79764Z","steps":["trace[479166312] 'process raft request' (duration: 144.02463ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:22:48.797769Z","caller":"traceutil/trace.go:171","msg":"trace[1555368099] transaction","detail":"{read_only:false; response_revision:5243; number_of_response:1; }","duration":"144.303487ms","start":"2026-04-18T22:22:48.653431Z","end":"2026-04-18T22:22:48.797734Z","steps":["trace[1555368099] 'process raft request' (duration: 143.883007ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:22:57.05504Z","caller":"traceutil/trace.go:171","msg":"trace[372515452] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"139.611541ms","start":"2026-04-18T22:22:56.915399Z","end":"2026-04-18T22:22:57.055011Z","steps":["trace[372515452] 'process raft request' (duration: 139.388477ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:22:57.335126Z","caller":"traceutil/trace.go:171","msg":"trace[270726007] transaction","detail":"{read_only:false; response_revision:5413; number_of_response:1; }","duration":"366.224839ms","start":"2026-04-18T22:22:56.968846Z","end":"2026-04-18T22:22:57.335071Z","steps":["trace[270726007] 'process raft request' (duration: 366.049455ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:22:57.33514Z","caller":"traceutil/trace.go:171","msg":"trace[462338647] transaction","detail":"{read_only:false; response_revision:5412; number_of_response:1; }","duration":"392.347063ms","start":"2026-04-18T22:22:56.942719Z","end":"2026-04-18T22:22:57.335066Z","steps":["trace[462338647] 'process raft request' (duration: 299.811506ms)","trace[462338647] 'compare' (duration: 92.005046ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:22:57.335268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.899182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:22:57.335289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:22:56.968819Z","time spent":"366.386483ms","remote":"127.0.0.1:49904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:22:57.335318Z","caller":"traceutil/trace.go:171","msg":"trace[1834198317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5414; }","duration":"354.997274ms","start":"2026-04-18T22:22:56.98031Z","end":"2026-04-18T22:22:57.335307Z","steps":["trace[1834198317] 'agreement among raft nodes before linearized reading' (duration: 354.872251ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:22:57.33512Z","caller":"traceutil/trace.go:171","msg":"trace[1682485512] linearizableReadLoop","detail":"{readStateIndex:5608; appliedIndex:5604; }","duration":"354.707418ms","start":"2026-04-18T22:22:56.980358Z","end":"2026-04-18T22:22:57.335066Z","steps":["trace[1682485512] 'read index received' (duration: 74.578567ms)","trace[1682485512] 'applied index is now lower than readState.Index' (duration: 280.127331ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:22:57.335373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:22:56.980293Z","time spent":"355.071315ms","remote":"127.0.0.1:49646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:22:57.335376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:22:56.942698Z","time spent":"392.542776ms","remote":"127.0.0.1:49846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:22:57.335497Z","caller":"traceutil/trace.go:171","msg":"trace[446845878] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"366.010483ms","start":"2026-04-18T22:22:56.96943Z","end":"2026-04-18T22:22:57.33544Z","steps":["trace[446845878] 'process raft request' (duration: 365.576865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:22:57.335789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:22:56.969389Z","time spent":"366.276789ms","remote":"127.0.0.1:49904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:22:57.375955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.638805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:22:57.376025Z","caller":"traceutil/trace.go:171","msg":"trace[943887943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5415; }","duration":"332.775428ms","start":"2026-04-18T22:22:57.043233Z","end":"2026-04-18T22:22:57.376009Z","steps":["trace[943887943] 'agreement among raft nodes before linearized reading' (duration: 332.647486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:22:57.37606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:22:57.043199Z","time spent":"332.854259ms","remote":"127.0.0.1:49656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-18T22:24:11.962569Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2993} {"level":"info","ts":"2026-04-18T22:24:12.045984Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2993,"took":"81.153158ms","hash":3269925608,"current-db-size-bytes":58519552,"current-db-size":"58 MB","current-db-size-in-use-bytes":33718272,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-18T22:24:12.046336Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3269925608,"revision":2993,"compact-revision":-1} {"level":"info","ts":"2026-04-18T22:29:11.968313Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6096} {"level":"info","ts":"2026-04-18T22:29:12.065394Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6096,"took":"95.18376ms","hash":1782443140,"current-db-size-bytes":58519552,"current-db-size":"58 MB","current-db-size-in-use-bytes":23797760,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-18T22:29:12.065446Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1782443140,"revision":6096,"compact-revision":2993} {"level":"info","ts":"2026-04-18T22:29:13.638295Z","caller":"traceutil/trace.go:171","msg":"trace[2088856090] linearizableReadLoop","detail":"{readStateIndex:8584; appliedIndex:8583; }","duration":"346.419822ms","start":"2026-04-18T22:29:13.291856Z","end":"2026-04-18T22:29:13.638276Z","steps":["trace[2088856090] 'read index received' (duration: 346.266689ms)","trace[2088856090] 'applied index is now lower than readState.Index' (duration: 152.283µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:29:13.638336Z","caller":"traceutil/trace.go:171","msg":"trace[1209885147] transaction","detail":"{read_only:false; response_revision:8298; number_of_response:1; }","duration":"405.179371ms","start":"2026-04-18T22:29:13.233139Z","end":"2026-04-18T22:29:13.638318Z","steps":["trace[1209885147] 'process raft request' (duration: 405.017918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:29:13.638498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.458004ms","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-04-18T22:29:13.638529Z","caller":"traceutil/trace.go:171","msg":"trace[1559410872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8298; }","duration":"229.536376ms","start":"2026-04-18T22:29:13.408984Z","end":"2026-04-18T22:29:13.638521Z","steps":["trace[1559410872] 'agreement among raft nodes before linearized reading' (duration: 229.434454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:29:13.638546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.328232ms","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-04-18T22:29:13.638575Z","caller":"traceutil/trace.go:171","msg":"trace[1458672846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8298; }","duration":"228.392734ms","start":"2026-04-18T22:29:13.410175Z","end":"2026-04-18T22:29:13.638568Z","steps":["trace[1458672846] 'agreement among raft nodes before linearized reading' (duration: 228.229421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:29:13.638517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:29:13.233111Z","time spent":"405.342695ms","remote":"127.0.0.1:49904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:29:13.638657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.808224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-18T22:29:13.638681Z","caller":"traceutil/trace.go:171","msg":"trace[1616132387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8298; }","duration":"102.876135ms","start":"2026-04-18T22:29:13.5358Z","end":"2026-04-18T22:29:13.638676Z","steps":["trace[1616132387] 'agreement among raft nodes before linearized reading' (duration: 102.60223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:29:13.638657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.823875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-18T22:29:13.6385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.642906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-18T22:29:13.638736Z","caller":"traceutil/trace.go:171","msg":"trace[1810282403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8298; }","duration":"123.955837ms","start":"2026-04-18T22:29:13.514761Z","end":"2026-04-18T22:29:13.638717Z","steps":["trace[1810282403] 'agreement among raft nodes before linearized reading' (duration: 123.675751ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:29:13.63875Z","caller":"traceutil/trace.go:171","msg":"trace[2121320449] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:8298; }","duration":"346.929932ms","start":"2026-04-18T22:29:13.291811Z","end":"2026-04-18T22:29:13.638741Z","steps":["trace[2121320449] 'agreement among raft nodes before linearized reading' (duration: 346.532895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:29:13.638781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:29:13.291793Z","time spent":"346.980053ms","remote":"127.0.0.1:49746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-04-18T22:29:13.638921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.838991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:126889"} {"level":"info","ts":"2026-04-18T22:29:13.638977Z","caller":"traceutil/trace.go:171","msg":"trace[1736468236] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:8298; }","duration":"340.933763ms","start":"2026-04-18T22:29:13.298027Z","end":"2026-04-18T22:29:13.638961Z","steps":["trace[1736468236] 'agreement among raft nodes before linearized reading' (duration: 340.344921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:29:13.639029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:29:13.298004Z","time spent":"341.011884ms","remote":"127.0.0.1:49732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":126913,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-04-18T22:29:25.326813Z","caller":"traceutil/trace.go:171","msg":"trace[1677273152] linearizableReadLoop","detail":"{readStateIndex:8648; appliedIndex:8647; }","duration":"241.047117ms","start":"2026-04-18T22:29:25.085735Z","end":"2026-04-18T22:29:25.326782Z","steps":["trace[1677273152] 'read index received' (duration: 240.701341ms)","trace[1677273152] 'applied index is now lower than readState.Index' (duration: 344.756µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:29:25.326937Z","caller":"traceutil/trace.go:171","msg":"trace[718521005] transaction","detail":"{read_only:false; response_revision:8360; number_of_response:1; }","duration":"257.233382ms","start":"2026-04-18T22:29:25.069674Z","end":"2026-04-18T22:29:25.326907Z","steps":["trace[718521005] 'process raft request' (duration: 256.857775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:29:25.327046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.299332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-18T22:29:25.327122Z","caller":"traceutil/trace.go:171","msg":"trace[1295177969] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:8360; }","duration":"241.448715ms","start":"2026-04-18T22:29:25.085661Z","end":"2026-04-18T22:29:25.32711Z","steps":["trace[1295177969] 'agreement among raft nodes before linearized reading' (duration: 241.230901ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:29:55.239574Z","caller":"traceutil/trace.go:171","msg":"trace[1275199161] transaction","detail":"{read_only:false; response_revision:8572; number_of_response:1; }","duration":"143.117152ms","start":"2026-04-18T22:29:55.09642Z","end":"2026-04-18T22:29:55.239537Z","steps":["trace[1275199161] 'process raft request' (duration: 142.777576ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:30:09.175469Z","caller":"traceutil/trace.go:171","msg":"trace[158401943] linearizableReadLoop","detail":"{readStateIndex:8994; appliedIndex:8993; }","duration":"165.717846ms","start":"2026-04-18T22:30:09.009715Z","end":"2026-04-18T22:30:09.175433Z","steps":["trace[158401943] 'read index received' (duration: 165.462451ms)","trace[158401943] 'applied index is now lower than readState.Index' (duration: 253.795µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:30:09.17553Z","caller":"traceutil/trace.go:171","msg":"trace[743887208] transaction","detail":"{read_only:false; response_revision:8692; number_of_response:1; }","duration":"200.726948ms","start":"2026-04-18T22:30:08.974757Z","end":"2026-04-18T22:30:09.175484Z","steps":["trace[743887208] 'process raft request' (duration: 200.453343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:30:09.17573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.579951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:30:09.175786Z","caller":"traceutil/trace.go:171","msg":"trace[1354040461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8692; }","duration":"129.682813ms","start":"2026-04-18T22:30:09.046087Z","end":"2026-04-18T22:30:09.175769Z","steps":["trace[1354040461] 'agreement among raft nodes before linearized reading' (duration: 129.572861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:30:09.17669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.94074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210982"} {"level":"info","ts":"2026-04-18T22:30:09.176803Z","caller":"traceutil/trace.go:171","msg":"trace[318988705] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8692; }","duration":"167.099374ms","start":"2026-04-18T22:30:09.009679Z","end":"2026-04-18T22:30:09.176778Z","steps":["trace[318988705] 'agreement among raft nodes before linearized reading' (duration: 165.89799ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:30:09.393642Z","caller":"traceutil/trace.go:171","msg":"trace[447468547] transaction","detail":"{read_only:false; response_revision:8694; number_of_response:1; }","duration":"100.022228ms","start":"2026-04-18T22:30:09.293588Z","end":"2026-04-18T22:30:09.39361Z","steps":["trace[447468547] 'process raft request' (duration: 74.27666ms)","trace[447468547] 'compare' (duration: 25.40866ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:30:26.159598Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023cc.wal"} {"level":"info","ts":"2026-04-18T22:31:58.68699Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d0ba3c4de8c6ef7a","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-18T22:31:58.692455Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-04-18T22:31:58.692558Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-04-18T22:34:11.97724Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8292} {"level":"info","ts":"2026-04-18T22:34:12.063001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8292,"took":"82.861195ms","hash":3559217944,"current-db-size-bytes":58519552,"current-db-size":"58 MB","current-db-size-in-use-bytes":25079808,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-18T22:34:12.063122Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3559217944,"revision":8292,"compact-revision":6096} {"level":"info","ts":"2026-04-18T22:39:11.983679Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10515} {"level":"info","ts":"2026-04-18T22:39:12.066727Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10515,"took":"80.046392ms","hash":3350792241,"current-db-size-bytes":58519552,"current-db-size":"58 MB","current-db-size-in-use-bytes":21463040,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-18T22:39:12.066815Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3350792241,"revision":10515,"compact-revision":8292}