{"level":"warn","ts":"2026-04-04T10:13:46.732716Z","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-04T10:13:46.732795Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240: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.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-04T10:13:46.73286Z","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-04T10:13:46.732872Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-04-04T10:13:46.732887Z","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-04T10:13:46.733553Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-04-04T10:13:46.733625Z","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.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-04T10:13:46.737636Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.837801ms"} {"level":"info","ts":"2026-04-04T10:13:46.744129Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-04-04T10:13:46.744275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-04-04T10:13:46.744317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-04-04T10:13:46.744336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-04T10:13:46.744351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-04-04T10:13:46.744405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-04-04T10:13:46.751802Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-04T10:13:46.756815Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-04T10:13:46.760497Z","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-04T10:13:46.764276Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-04T10:13:46.767372Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-04T10:13:46.767481Z","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-04T10:13:46.767512Z","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-04T10:13:46.767518Z","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-04T10:13:46.768306Z","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-04T10:13:46.76845Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-04T10:13:46.768474Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-04T10:13:46.768604Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-04-04T10:13:46.768616Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-04-04T10:13:46.769487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-04-04T10:13:46.769554Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-04-04T10:13:47.044738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-04-04T10:13:47.044795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-04T10:13:47.044838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-04-04T10:13:47.044863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-04-04T10:13:47.044879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-04-04T10:13:47.044899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-04-04T10:13:47.044916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-04-04T10:13:47.050438Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:13:47.056988Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-04-04T10:13:47.057112Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-04T10:13:47.057265Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-04T10:13:47.057468Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-04T10:13:47.057556Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-04T10:13:47.059842Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:13:47.059934Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:13:47.059965Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:13:47.060908Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-04T10:13:47.061186Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-04-04T10:13:56.407259Z","caller":"traceutil/trace.go:171","msg":"trace[1410027908] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"110.867374ms","start":"2026-04-04T10:13:56.296356Z","end":"2026-04-04T10:13:56.407223Z","steps":["trace[1410027908] 'process raft request' (duration: 73.309799ms)","trace[1410027908] 'compare' (duration: 37.243526ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:13:56.621482Z","caller":"traceutil/trace.go:171","msg":"trace[1239595518] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"205.766642ms","start":"2026-04-04T10:13:56.415683Z","end":"2026-04-04T10:13:56.62145Z","steps":["trace[1239595518] 'process raft request' (duration: 205.632888ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:13:56.621522Z","caller":"traceutil/trace.go:171","msg":"trace[2037772914] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"205.6822ms","start":"2026-04-04T10:13:56.41582Z","end":"2026-04-04T10:13:56.621502Z","steps":["trace[2037772914] 'process raft request' (duration: 205.546536ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:13:56.622191Z","caller":"traceutil/trace.go:171","msg":"trace[552971781] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"205.37045ms","start":"2026-04-04T10:13:56.416796Z","end":"2026-04-04T10:13:56.622167Z","steps":["trace[552971781] 'process raft request' (duration: 204.606538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:13:56.62239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.473632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-04T10:13:56.622473Z","caller":"traceutil/trace.go:171","msg":"trace[1314586725] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:359; }","duration":"184.633846ms","start":"2026-04-04T10:13:56.437822Z","end":"2026-04-04T10:13:56.622456Z","steps":["trace[1314586725] 'agreement among raft nodes before linearized reading' (duration: 184.456921ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:13:56.622495Z","caller":"traceutil/trace.go:171","msg":"trace[1579143537] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:363; }","duration":"183.593307ms","start":"2026-04-04T10:13:56.437873Z","end":"2026-04-04T10:13:56.621466Z","steps":["trace[1579143537] 'read index received' (duration: 5.865338ms)","trace[1579143537] 'applied index is now lower than readState.Index' (duration: 177.727149ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:13:56.62263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.359054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-04-04T10:13:56.622677Z","caller":"traceutil/trace.go:171","msg":"trace[1799623016] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:359; }","duration":"172.447947ms","start":"2026-04-04T10:13:56.450217Z","end":"2026-04-04T10:13:56.622665Z","steps":["trace[1799623016] 'agreement among raft nodes before linearized reading' (duration: 172.360824ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:13:56.622242Z","caller":"traceutil/trace.go:171","msg":"trace[783039092] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"207.415299ms","start":"2026-04-04T10:13:56.413979Z","end":"2026-04-04T10:13:56.621395Z","steps":["trace[783039092] 'process raft request' (duration: 149.224923ms)","trace[783039092] 'compare' (duration: 57.951849ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:13:56.627442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.866006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-04T10:13:56.6275Z","caller":"traceutil/trace.go:171","msg":"trace[450907127] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:359; }","duration":"138.953329ms","start":"2026-04-04T10:13:56.488518Z","end":"2026-04-04T10:13:56.627472Z","steps":["trace[450907127] 'agreement among raft nodes before linearized reading' (duration: 137.389144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:14:15.313085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.221437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-04T10:14:15.313197Z","caller":"traceutil/trace.go:171","msg":"trace[1355554242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:508; }","duration":"130.381161ms","start":"2026-04-04T10:14:15.182788Z","end":"2026-04-04T10:14:15.313169Z","steps":["trace[1355554242] 'range keys from in-memory index tree' (duration: 130.062892ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:14:17.096529Z","caller":"traceutil/trace.go:171","msg":"trace[1315515797] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"245.613875ms","start":"2026-04-04T10:14:16.850886Z","end":"2026-04-04T10:14:17.0965Z","steps":["trace[1315515797] 'process raft request' (duration: 242.065693ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:14:17.102158Z","caller":"traceutil/trace.go:171","msg":"trace[1965989646] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:598; }","duration":"153.751036ms","start":"2026-04-04T10:14:16.948391Z","end":"2026-04-04T10:14:17.102142Z","steps":["trace[1965989646] 'read index received' (duration: 144.523363ms)","trace[1965989646] 'applied index is now lower than readState.Index' (duration: 9.227093ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:14:17.102247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.842628ms","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-04T10:14:17.102268Z","caller":"traceutil/trace.go:171","msg":"trace[1583771353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"153.90608ms","start":"2026-04-04T10:14:16.948354Z","end":"2026-04-04T10:14:17.10226Z","steps":["trace[1583771353] 'agreement among raft nodes before linearized reading' (duration: 153.835088ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:14:17.102273Z","caller":"traceutil/trace.go:171","msg":"trace[578983408] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"205.451414ms","start":"2026-04-04T10:14:16.89679Z","end":"2026-04-04T10:14:17.102242Z","steps":["trace[578983408] 'process raft request' (duration: 205.251139ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:14:22.244378Z","caller":"traceutil/trace.go:171","msg":"trace[1900802552] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"143.67126ms","start":"2026-04-04T10:14:22.100687Z","end":"2026-04-04T10:14:22.244358Z","steps":["trace[1900802552] 'read index received' (duration: 143.081203ms)","trace[1900802552] 'applied index is now lower than readState.Index' (duration: 589.256µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:14:22.244444Z","caller":"traceutil/trace.go:171","msg":"trace[515433402] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"171.274323ms","start":"2026-04-04T10:14:22.073134Z","end":"2026-04-04T10:14:22.244408Z","steps":["trace[515433402] 'process raft request' (duration: 170.770919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:14:22.244738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.090181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9bdx8\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-04T10:14:22.2449Z","caller":"traceutil/trace.go:171","msg":"trace[664682696] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9bdx8; range_end:; response_count:1; response_revision:617; }","duration":"144.319659ms","start":"2026-04-04T10:14:22.100554Z","end":"2026-04-04T10:14:22.244873Z","steps":["trace[664682696] 'agreement among raft nodes before linearized reading' (duration: 143.948348ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:14:22.755863Z","caller":"traceutil/trace.go:171","msg":"trace[103750037] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"470.854271ms","start":"2026-04-04T10:14:22.284979Z","end":"2026-04-04T10:14:22.755833Z","steps":["trace[103750037] 'read index received' (duration: 470.505091ms)","trace[103750037] 'applied index is now lower than readState.Index' (duration: 348.06µs)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:14:22.756032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.036156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:14:22.755984Z","caller":"traceutil/trace.go:171","msg":"trace[331437895] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"492.977799ms","start":"2026-04-04T10:14:22.262971Z","end":"2026-04-04T10:14:22.755949Z","steps":["trace[331437895] 'process raft request' (duration: 492.608859ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:14:22.756097Z","caller":"traceutil/trace.go:171","msg":"trace[444667711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"471.148969ms","start":"2026-04-04T10:14:22.284929Z","end":"2026-04-04T10:14:22.756078Z","steps":["trace[444667711] 'agreement among raft nodes before linearized reading' (duration: 471.036506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:14:22.756147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:14:22.284909Z","time spent":"471.227272ms","remote":"127.0.0.1:53150","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-04-04T10:14:22.756796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:14:22.262952Z","time spent":"493.146373ms","remote":"127.0.0.1:53342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T10:14:22.795026Z","caller":"traceutil/trace.go:171","msg":"trace[1477231818] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"501.291525ms","start":"2026-04-04T10:14:22.293702Z","end":"2026-04-04T10:14:22.794994Z","steps":["trace[1477231818] 'process raft request' (duration: 489.682756ms)","trace[1477231818] 'compare' (duration: 11.363882ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:14:22.795175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:14:22.293689Z","time spent":"501.418558ms","remote":"127.0.0.1:53646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-04T10:14:24.347493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.158963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-04T10:14:24.347612Z","caller":"traceutil/trace.go:171","msg":"trace[1016588559] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"140.705406ms","start":"2026-04-04T10:14:24.206879Z","end":"2026-04-04T10:14:24.347585Z","steps":["trace[1016588559] 'process raft request' (duration: 33.230814ms)","trace[1016588559] 'compare' (duration: 107.030789ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:15:12.458339Z","caller":"traceutil/trace.go:171","msg":"trace[944148827] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"132.133478ms","start":"2026-04-04T10:15:12.32618Z","end":"2026-04-04T10:15:12.458314Z","steps":["trace[944148827] 'process raft request' (duration: 131.993164ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:15:33.286758Z","caller":"traceutil/trace.go:171","msg":"trace[108403326] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"130.385639ms","start":"2026-04-04T10:15:33.15634Z","end":"2026-04-04T10:15:33.286725Z","steps":["trace[108403326] 'process raft request' (duration: 130.218414ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:15:34.466252Z","caller":"traceutil/trace.go:171","msg":"trace[574329862] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1101; }","duration":"138.114659ms","start":"2026-04-04T10:15:34.328115Z","end":"2026-04-04T10:15:34.466229Z","steps":["trace[574329862] 'read index received' (duration: 138.012566ms)","trace[574329862] 'applied index is now lower than readState.Index' (duration: 101.173µs)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:15:34.466406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.266333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-04T10:15:34.466447Z","caller":"traceutil/trace.go:171","msg":"trace[1959973745] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1066; }","duration":"138.344106ms","start":"2026-04-04T10:15:34.328083Z","end":"2026-04-04T10:15:34.466435Z","steps":["trace[1959973745] 'agreement among raft nodes before linearized reading' (duration: 138.231562ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:15:34.466631Z","caller":"traceutil/trace.go:171","msg":"trace[535201324] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"163.04623ms","start":"2026-04-04T10:15:34.303556Z","end":"2026-04-04T10:15:34.466602Z","steps":["trace[535201324] 'process raft request' (duration: 162.546206ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:15:35.990743Z","caller":"traceutil/trace.go:171","msg":"trace[1175438580] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"263.338461ms","start":"2026-04-04T10:15:35.727305Z","end":"2026-04-04T10:15:35.990643Z","steps":["trace[1175438580] 'process raft request' (duration: 263.084293ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:15:49.993088Z","caller":"traceutil/trace.go:171","msg":"trace[511627783] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"175.750512ms","start":"2026-04-04T10:15:49.817315Z","end":"2026-04-04T10:15:49.993065Z","steps":["trace[511627783] 'process raft request' (duration: 175.581378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:15:50.189299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.144321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:15:50.189363Z","caller":"traceutil/trace.go:171","msg":"trace[6240778] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1170; }","duration":"124.251265ms","start":"2026-04-04T10:15:50.065098Z","end":"2026-04-04T10:15:50.189349Z","steps":["trace[6240778] 'count revisions from in-memory index tree' (duration: 124.07986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:15:50.189466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.747493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-04T10:15:50.189569Z","caller":"traceutil/trace.go:171","msg":"trace[1852438239] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1170; }","duration":"141.898017ms","start":"2026-04-04T10:15:50.047643Z","end":"2026-04-04T10:15:50.189541Z","steps":["trace[1852438239] 'range keys from in-memory index tree' (duration: 141.525436ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:16:04.781967Z","caller":"traceutil/trace.go:171","msg":"trace[1620928650] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"119.041866ms","start":"2026-04-04T10:16:04.662883Z","end":"2026-04-04T10:16:04.781925Z","steps":["trace[1620928650] 'process raft request' (duration: 118.726207ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:16:04.9702Z","caller":"traceutil/trace.go:171","msg":"trace[1953339361] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"182.852924ms","start":"2026-04-04T10:16:04.787323Z","end":"2026-04-04T10:16:04.970176Z","steps":["trace[1953339361] 'process raft request' (duration: 182.34489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:16:05.562854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.203815ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-04T10:16:06.324065Z","caller":"traceutil/trace.go:171","msg":"trace[1225516878] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"218.574994ms","start":"2026-04-04T10:16:06.105473Z","end":"2026-04-04T10:16:06.324048Z","steps":["trace[1225516878] 'process raft request' (duration: 218.42457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:16:06.324155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.431157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-04T10:16:06.324047Z","caller":"traceutil/trace.go:171","msg":"trace[701476442] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1269; }","duration":"159.305913ms","start":"2026-04-04T10:16:06.164714Z","end":"2026-04-04T10:16:06.32402Z","steps":["trace[701476442] 'read index received' (duration: 159.131579ms)","trace[701476442] 'applied index is now lower than readState.Index' (duration: 173.524µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:16:06.324196Z","caller":"traceutil/trace.go:171","msg":"trace[1232107913] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1225; }","duration":"159.52398ms","start":"2026-04-04T10:16:06.16466Z","end":"2026-04-04T10:16:06.324184Z","steps":["trace[1232107913] 'agreement among raft nodes before linearized reading' (duration: 159.433878ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:16:07.136996Z","caller":"traceutil/trace.go:171","msg":"trace[557229517] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"142.670569ms","start":"2026-04-04T10:16:06.994288Z","end":"2026-04-04T10:16:07.136959Z","steps":["trace[557229517] 'process raft request' (duration: 142.487604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:16:19.479307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.198327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:16:19.479383Z","caller":"traceutil/trace.go:171","msg":"trace[64867651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1330; }","duration":"192.32471ms","start":"2026-04-04T10:16:19.287038Z","end":"2026-04-04T10:16:19.479362Z","steps":["trace[64867651] 'range keys from in-memory index tree' (duration: 192.106854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:16:19.479491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.620798ms","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-04T10:16:19.479699Z","caller":"traceutil/trace.go:171","msg":"trace[1999038604] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1330; }","duration":"283.872145ms","start":"2026-04-04T10:16:19.195802Z","end":"2026-04-04T10:16:19.479674Z","steps":["trace[1999038604] 'range keys from in-memory index tree' (duration: 283.459573ms)"],"step_count":1}