{"level":"warn","ts":"2026-05-04T01:39:18.328783Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-04T01:39:18.328878Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246: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.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246: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-05-04T01:39:18.328945Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-04T01:39:18.328953Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-05-04T01:39:18.328972Z","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-05-04T01:39:18.329612Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-05-04T01:39:18.329684Z","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://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246: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-05-04T01:39:18.334193Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.303822ms"} {"level":"info","ts":"2026-05-04T01:39:18.385809Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-05-04T01:39:18.385911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-05-04T01:39:18.385931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-05-04T01:39:18.385942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-04T01:39:18.385949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-05-04T01:39:18.385982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-05-04T01:39:18.401834Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-04T01:39:18.403463Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-04T01:39:18.405011Z","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-05-04T01:39:18.407241Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-04T01:39:18.407484Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-04T01:39:18.407569Z","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-05-04T01:39:18.409623Z","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-05-04T01:39:18.409644Z","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-05-04T01:39:18.4105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-05-04T01:39:18.410735Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-05-04T01:39:18.41241Z","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-05-04T01:39:18.412497Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-05-04T01:39:18.412551Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-05-04T01:39:18.412917Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-04T01:39:18.413006Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-04T01:39:19.08827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-05-04T01:39:19.088342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-04T01:39:19.088385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-05-04T01:39:19.088402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-05-04T01:39:19.088412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-05-04T01:39:19.088423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-05-04T01:39:19.088439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-05-04T01:39:19.089315Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T01:39:19.090124Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-04T01:39:19.090125Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-04T01:39:19.090152Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-04T01:39:19.090614Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-04T01:39:19.09072Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-04T01:39:19.09321Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-05-04T01:39:19.093344Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-04T01:39:19.098599Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T01:39:19.09903Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T01:39:19.099088Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T01:39:26.356729Z","caller":"traceutil/trace.go:171","msg":"trace[2010537468] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"100.943779ms","start":"2026-05-04T01:39:26.255726Z","end":"2026-05-04T01:39:26.35667Z","steps":["trace[2010537468] 'process raft request' (duration: 98.509332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:52.645846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.980382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:39:52.646041Z","caller":"traceutil/trace.go:171","msg":"trace[1776977290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"466.252926ms","start":"2026-05-04T01:39:52.179756Z","end":"2026-05-04T01:39:52.646009Z","steps":["trace[1776977290] 'range keys from in-memory index tree' (duration: 465.88027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:52.646025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.895615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-04T01:39:52.646121Z","caller":"traceutil/trace.go:171","msg":"trace[1732352223] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:557; }","duration":"329.086758ms","start":"2026-05-04T01:39:52.317009Z","end":"2026-05-04T01:39:52.646095Z","steps":["trace[1732352223] 'range keys from in-memory index tree' (duration: 328.557438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:52.64611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:39:52.179736Z","time spent":"466.361979ms","remote":"127.0.0.1:51330","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-05-04T01:39:52.646266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:39:52.316975Z","time spent":"329.279523ms","remote":"127.0.0.1:51372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.246\" "} {"level":"warn","ts":"2026-05-04T01:39:56.407049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.015048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t9s4d\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-04T01:39:56.407129Z","caller":"traceutil/trace.go:171","msg":"trace[1269714043] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t9s4d; range_end:; response_count:1; response_revision:586; }","duration":"145.13667ms","start":"2026-05-04T01:39:56.261973Z","end":"2026-05-04T01:39:56.40711Z","steps":["trace[1269714043] 'range keys from in-memory index tree' (duration: 144.852705ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:39:56.624835Z","caller":"traceutil/trace.go:171","msg":"trace[891136849] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"202.226405ms","start":"2026-05-04T01:39:56.422583Z","end":"2026-05-04T01:39:56.624809Z","steps":["trace[891136849] 'process raft request' (duration: 201.93811ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:39:56.675442Z","caller":"traceutil/trace.go:171","msg":"trace[1266259082] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"101.115883ms","start":"2026-05-04T01:39:56.574277Z","end":"2026-05-04T01:39:56.675393Z","steps":["trace[1266259082] 'process raft request' (duration: 96.297472ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:39:57.346044Z","caller":"traceutil/trace.go:171","msg":"trace[766072581] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"363.248007ms","start":"2026-05-04T01:39:56.982768Z","end":"2026-05-04T01:39:57.346016Z","steps":["trace[766072581] 'read index received' (duration: 358.033959ms)","trace[766072581] 'applied index is now lower than readState.Index' (duration: 5.212618ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:39:57.346068Z","caller":"traceutil/trace.go:171","msg":"trace[617129253] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"608.776347ms","start":"2026-05-04T01:39:56.737261Z","end":"2026-05-04T01:39:57.346037Z","steps":["trace[617129253] 'process raft request' (duration: 603.463556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:57.34631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.526913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-04T01:39:57.346373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.27828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-04T01:39:57.346421Z","caller":"traceutil/trace.go:171","msg":"trace[12083945] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"259.381143ms","start":"2026-05-04T01:39:57.087026Z","end":"2026-05-04T01:39:57.346407Z","steps":["trace[12083945] 'agreement among raft nodes before linearized reading' (duration: 259.143658ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:39:57.346409Z","caller":"traceutil/trace.go:171","msg":"trace[1340519756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"363.704346ms","start":"2026-05-04T01:39:56.982686Z","end":"2026-05-04T01:39:57.346391Z","steps":["trace[1340519756] 'agreement among raft nodes before linearized reading' (duration: 363.421661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:57.346384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.663852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T01:39:57.346477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:39:56.982656Z","time spent":"363.808618ms","remote":"127.0.0.1:51600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-04T01:39:57.346515Z","caller":"traceutil/trace.go:171","msg":"trace[370418724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"163.861557ms","start":"2026-05-04T01:39:57.182621Z","end":"2026-05-04T01:39:57.346483Z","steps":["trace[370418724] 'agreement among raft nodes before linearized reading' (duration: 163.606261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:57.346547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:39:56.737235Z","time spent":"608.900819ms","remote":"127.0.0.1:51568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-04T01:39:57.346708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.118196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-04T01:39:57.346763Z","caller":"traceutil/trace.go:171","msg":"trace[1089526708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"250.197038ms","start":"2026-05-04T01:39:57.096552Z","end":"2026-05-04T01:39:57.346749Z","steps":["trace[1089526708] 'agreement among raft nodes before linearized reading' (duration: 250.020895ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:40:14.509916Z","caller":"traceutil/trace.go:171","msg":"trace[2079523458] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"129.097604ms","start":"2026-05-04T01:40:14.380793Z","end":"2026-05-04T01:40:14.509891Z","steps":["trace[2079523458] 'read index received' (duration: 128.965272ms)","trace[2079523458] 'applied index is now lower than readState.Index' (duration: 131.262µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:40:14.51Z","caller":"traceutil/trace.go:171","msg":"trace[610800020] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"309.061038ms","start":"2026-05-04T01:40:14.200902Z","end":"2026-05-04T01:40:14.509963Z","steps":["trace[610800020] 'process raft request' (duration: 308.839964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:40:14.510115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.305619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hfmmc\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-04T01:40:14.510165Z","caller":"traceutil/trace.go:171","msg":"trace[1285234873] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hfmmc; range_end:; response_count:1; response_revision:753; }","duration":"129.40921ms","start":"2026-05-04T01:40:14.380743Z","end":"2026-05-04T01:40:14.510152Z","steps":["trace[1285234873] 'agreement among raft nodes before linearized reading' (duration: 129.249587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:40:14.510466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:40:14.200886Z","time spent":"309.256281ms","remote":"127.0.0.1:51600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T01:40:14.673477Z","caller":"traceutil/trace.go:171","msg":"trace[430967337] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"156.109638ms","start":"2026-05-04T01:40:14.517341Z","end":"2026-05-04T01:40:14.673451Z","steps":["trace[430967337] 'process raft request' (duration: 155.973106ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:40:48.869657Z","caller":"traceutil/trace.go:171","msg":"trace[43045636] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"184.459456ms","start":"2026-05-04T01:40:48.685174Z","end":"2026-05-04T01:40:48.869633Z","steps":["trace[43045636] 'process raft request' (duration: 184.316414ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:40:49.271534Z","caller":"traceutil/trace.go:171","msg":"trace[118487943] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"170.21879ms","start":"2026-05-04T01:40:49.101288Z","end":"2026-05-04T01:40:49.271507Z","steps":["trace[118487943] 'process raft request' (duration: 170.079197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:40:49.80586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.537217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-04T01:40:49.805894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.968067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-04T01:40:49.805946Z","caller":"traceutil/trace.go:171","msg":"trace[405715829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:975; }","duration":"112.656919ms","start":"2026-05-04T01:40:49.693267Z","end":"2026-05-04T01:40:49.805924Z","steps":["trace[405715829] 'range keys from in-memory index tree' (duration: 112.392764ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:40:49.805976Z","caller":"traceutil/trace.go:171","msg":"trace[308515992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:975; }","duration":"113.102969ms","start":"2026-05-04T01:40:49.692852Z","end":"2026-05-04T01:40:49.805955Z","steps":["trace[308515992] 'range keys from in-memory index tree' (duration: 112.804153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:40:49.806088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.227264ms","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-05-04T01:40:49.806165Z","caller":"traceutil/trace.go:171","msg":"trace[585418214] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:975; }","duration":"116.358877ms","start":"2026-05-04T01:40:49.689788Z","end":"2026-05-04T01:40:49.806147Z","steps":["trace[585418214] 'range keys from in-memory index tree' (duration: 116.087761ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:40:53.972885Z","caller":"traceutil/trace.go:171","msg":"trace[391969165] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"140.188144ms","start":"2026-05-04T01:40:53.832673Z","end":"2026-05-04T01:40:53.972861Z","steps":["trace[391969165] 'process raft request' (duration: 140.050921ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:40:53.973614Z","caller":"traceutil/trace.go:171","msg":"trace[445443533] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"140.584233ms","start":"2026-05-04T01:40:53.833006Z","end":"2026-05-04T01:40:53.97359Z","steps":["trace[445443533] 'process raft request' (duration: 140.432199ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:40:53.973882Z","caller":"traceutil/trace.go:171","msg":"trace[1931128221] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"139.413518ms","start":"2026-05-04T01:40:53.834298Z","end":"2026-05-04T01:40:53.973712Z","steps":["trace[1931128221] 'process raft request' (duration: 139.250275ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:13.205527Z","caller":"traceutil/trace.go:171","msg":"trace[713235806] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"226.215467ms","start":"2026-05-04T01:41:12.979281Z","end":"2026-05-04T01:41:13.205497Z","steps":["trace[713235806] 'process raft request' (duration: 225.932421ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:14.20195Z","caller":"traceutil/trace.go:171","msg":"trace[1569266811] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"103.811745ms","start":"2026-05-04T01:41:14.098106Z","end":"2026-05-04T01:41:14.201917Z","steps":["trace[1569266811] 'process raft request' (duration: 103.556199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:41:14.329622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.646537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T01:41:14.329806Z","caller":"traceutil/trace.go:171","msg":"trace[97054212] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1094; }","duration":"228.697239ms","start":"2026-05-04T01:41:14.101092Z","end":"2026-05-04T01:41:14.329789Z","steps":["trace[97054212] 'read index received' (duration: 100.607158ms)","trace[97054212] 'applied index is now lower than readState.Index' (duration: 128.088611ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:41:14.329949Z","caller":"traceutil/trace.go:171","msg":"trace[1843398904] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"229.132998ms","start":"2026-05-04T01:41:14.100773Z","end":"2026-05-04T01:41:14.329906Z","steps":["trace[1843398904] 'process raft request' (duration: 118.92837ms)","trace[1843398904] 'compare' (duration: 109.486894ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:41:14.330077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.986575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-04T01:41:14.33015Z","caller":"traceutil/trace.go:171","msg":"trace[56761970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1057; }","duration":"229.079788ms","start":"2026-05-04T01:41:14.101059Z","end":"2026-05-04T01:41:14.330138Z","steps":["trace[56761970] 'agreement among raft nodes before linearized reading' (duration: 228.840772ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:15.344773Z","caller":"traceutil/trace.go:171","msg":"trace[578733899] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"131.172705ms","start":"2026-05-04T01:41:15.213577Z","end":"2026-05-04T01:41:15.34475Z","steps":["trace[578733899] 'process raft request' (duration: 131.041082ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:15.539397Z","caller":"traceutil/trace.go:171","msg":"trace[1443054471] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"130.41503ms","start":"2026-05-04T01:41:15.408929Z","end":"2026-05-04T01:41:15.539344Z","steps":["trace[1443054471] 'process raft request' (duration: 130.255966ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:15.860307Z","caller":"traceutil/trace.go:171","msg":"trace[247557476] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"156.199247ms","start":"2026-05-04T01:41:15.704084Z","end":"2026-05-04T01:41:15.860284Z","steps":["trace[247557476] 'process raft request' (duration: 155.993303ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:28.65563Z","caller":"traceutil/trace.go:171","msg":"trace[2086451974] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1196; }","duration":"263.153209ms","start":"2026-05-04T01:41:28.392456Z","end":"2026-05-04T01:41:28.655609Z","steps":["trace[2086451974] 'read index received' (duration: 263.000777ms)","trace[2086451974] 'applied index is now lower than readState.Index' (duration: 151.342µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:41:28.655705Z","caller":"traceutil/trace.go:171","msg":"trace[1299958702] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"381.931054ms","start":"2026-05-04T01:41:28.273736Z","end":"2026-05-04T01:41:28.655667Z","steps":["trace[1299958702] 'process raft request' (duration: 381.735021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:41:28.655837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.367083ms","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-05-04T01:41:28.655873Z","caller":"traceutil/trace.go:171","msg":"trace[199444863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1153; }","duration":"263.453505ms","start":"2026-05-04T01:41:28.392403Z","end":"2026-05-04T01:41:28.655856Z","steps":["trace[199444863] 'agreement among raft nodes before linearized reading' (duration: 263.318072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:41:28.655896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:41:28.273719Z","time spent":"382.082767ms","remote":"127.0.0.1:51600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T01:41:28.656046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.662894ms","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-05-04T01:41:28.656151Z","caller":"traceutil/trace.go:171","msg":"trace[453344303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1153; }","duration":"245.799337ms","start":"2026-05-04T01:41:28.410324Z","end":"2026-05-04T01:41:28.656123Z","steps":["trace[453344303] 'agreement among raft nodes before linearized reading' (duration: 245.505292ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:28.884739Z","caller":"traceutil/trace.go:171","msg":"trace[237878732] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"223.652049ms","start":"2026-05-04T01:41:28.661063Z","end":"2026-05-04T01:41:28.884715Z","steps":["trace[237878732] 'process raft request' (duration: 223.528437ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:44.856423Z","caller":"traceutil/trace.go:171","msg":"trace[610473564] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"128.912927ms","start":"2026-05-04T01:41:44.727478Z","end":"2026-05-04T01:41:44.856391Z","steps":["trace[610473564] 'process raft request' (duration: 77.130316ms)","trace[610473564] 'compare' (duration: 51.649128ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:41:45.634607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.393374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-04T01:41:45.634718Z","caller":"traceutil/trace.go:171","msg":"trace[1274685094] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1277; }","duration":"205.452483ms","start":"2026-05-04T01:41:45.429244Z","end":"2026-05-04T01:41:45.634696Z","steps":["trace[1274685094] 'read index received' (duration: 84.987228ms)","trace[1274685094] 'applied index is now lower than readState.Index' (duration: 120.463655ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:41:45.634739Z","caller":"traceutil/trace.go:171","msg":"trace[1695237333] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"273.924913ms","start":"2026-05-04T01:41:45.360785Z","end":"2026-05-04T01:41:45.63471Z","steps":["trace[1695237333] 'process raft request' (duration: 153.346606ms)","trace[1695237333] 'compare' (duration: 120.17234ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:41:45.634846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.598825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:41:45.63488Z","caller":"traceutil/trace.go:171","msg":"trace[1604345337] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1231; }","duration":"205.675877ms","start":"2026-05-04T01:41:45.429191Z","end":"2026-05-04T01:41:45.634867Z","steps":["trace[1604345337] 'agreement among raft nodes before linearized reading' (duration: 205.581145ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:45.717394Z","caller":"traceutil/trace.go:171","msg":"trace[891387748] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"275.41678ms","start":"2026-05-04T01:41:45.441952Z","end":"2026-05-04T01:41:45.717369Z","steps":["trace[891387748] 'process raft request' (duration: 275.267718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:41:45.717805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.325811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-04T01:41:45.717848Z","caller":"traceutil/trace.go:171","msg":"trace[1119507912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1232; }","duration":"234.373241ms","start":"2026-05-04T01:41:45.483455Z","end":"2026-05-04T01:41:45.717828Z","steps":["trace[1119507912] 'agreement among raft nodes before linearized reading' (duration: 234.215279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:41:45.79772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.222103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-04T01:41:45.797813Z","caller":"traceutil/trace.go:171","msg":"trace[364310178] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1233; }","duration":"158.366935ms","start":"2026-05-04T01:41:45.639427Z","end":"2026-05-04T01:41:45.797794Z","steps":["trace[364310178] 'agreement among raft nodes before linearized reading' (duration: 158.09492ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:45.798122Z","caller":"traceutil/trace.go:171","msg":"trace[1770690834] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"157.151955ms","start":"2026-05-04T01:41:45.640963Z","end":"2026-05-04T01:41:45.798115Z","steps":["trace[1770690834] 'process raft request' (duration: 151.417911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:41:45.798275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.122373ms","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-05-04T01:41:45.798294Z","caller":"traceutil/trace.go:171","msg":"trace[1531187260] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1233; }","duration":"118.185045ms","start":"2026-05-04T01:41:45.680102Z","end":"2026-05-04T01:41:45.798287Z","steps":["trace[1531187260] 'agreement among raft nodes before linearized reading' (duration: 118.117844ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:58.994544Z","caller":"traceutil/trace.go:171","msg":"trace[159650032] linearizableReadLoop","detail":"{readStateIndex:1372; appliedIndex:1371; }","duration":"151.442011ms","start":"2026-05-04T01:41:58.843075Z","end":"2026-05-04T01:41:58.994517Z","steps":["trace[159650032] 'read index received' (duration: 151.152475ms)","trace[159650032] 'applied index is now lower than readState.Index' (duration: 285.316µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:41:58.994701Z","caller":"traceutil/trace.go:171","msg":"trace[1974775820] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"158.510168ms","start":"2026-05-04T01:41:58.836175Z","end":"2026-05-04T01:41:58.994685Z","steps":["trace[1974775820] 'process raft request' (duration: 158.07814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:41:58.994963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.859778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-04T01:41:58.995212Z","caller":"traceutil/trace.go:171","msg":"trace[952996640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1322; }","duration":"152.143735ms","start":"2026-05-04T01:41:58.843042Z","end":"2026-05-04T01:41:58.995186Z","steps":["trace[952996640] 'agreement among raft nodes before linearized reading' (duration: 151.741828ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:45:10.915234Z","caller":"traceutil/trace.go:171","msg":"trace[1938140879] linearizableReadLoop","detail":"{readStateIndex:2321; appliedIndex:2320; }","duration":"134.527802ms","start":"2026-05-04T01:45:10.780671Z","end":"2026-05-04T01:45:10.915198Z","steps":["trace[1938140879] 'read index received' (duration: 134.270157ms)","trace[1938140879] 'applied index is now lower than readState.Index' (duration: 256.095µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:45:10.915668Z","caller":"traceutil/trace.go:171","msg":"trace[1485630347] transaction","detail":"{read_only:false; response_revision:2232; number_of_response:1; }","duration":"146.317609ms","start":"2026-05-04T01:45:10.769329Z","end":"2026-05-04T01:45:10.915647Z","steps":["trace[1485630347] 'process raft request' (duration: 145.697878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:45:10.91611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.413059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-04T01:45:10.916163Z","caller":"traceutil/trace.go:171","msg":"trace[532985035] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2232; }","duration":"135.509891ms","start":"2026-05-04T01:45:10.78064Z","end":"2026-05-04T01:45:10.91615Z","steps":["trace[532985035] 'agreement among raft nodes before linearized reading' (duration: 134.708205ms)"],"step_count":1}