{"level":"warn","ts":"2026-05-29T17:22:04.42282Z","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-29T17:22:04.42292Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.214.21: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.19.214.21:2380","--initial-cluster=instance=https://199.19.214.21:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.214.21:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.214.21: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-29T17:22:04.423Z","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-29T17:22:04.423015Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.214.21:2380"]} {"level":"info","ts":"2026-05-29T17:22:04.423033Z","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-29T17:22:04.423696Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.214.21:2379"]} {"level":"info","ts":"2026-05-29T17:22:04.423776Z","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.19.214.21:2380"],"listen-peer-urls":["https://199.19.214.21:2380"],"advertise-client-urls":["https://199.19.214.21:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.214.21:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.214.21: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-29T17:22:04.430806Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.782787ms"} {"level":"info","ts":"2026-05-29T17:22:04.444668Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d1872cc6a259534d","cluster-id":"b979e5170b701aa8"} {"level":"info","ts":"2026-05-29T17:22:04.444795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1872cc6a259534d switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T17:22:04.444831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1872cc6a259534d became follower at term 0"} {"level":"info","ts":"2026-05-29T17:22:04.444845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d1872cc6a259534d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T17:22:04.444863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1872cc6a259534d became follower at term 1"} {"level":"info","ts":"2026-05-29T17:22:04.444922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1872cc6a259534d switched to configuration voters=(15098085507421786957)"} {"level":"warn","ts":"2026-05-29T17:22:04.457848Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T17:22:04.462683Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T17:22:04.465188Z","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-29T17:22:04.467767Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d1872cc6a259534d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T17:22:04.467841Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d1872cc6a259534d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T17:22:04.468016Z","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-29T17:22:04.468069Z","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-29T17:22:04.468093Z","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-29T17:22:04.469923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1872cc6a259534d switched to configuration voters=(15098085507421786957)"} {"level":"info","ts":"2026-05-29T17:22:04.470455Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b979e5170b701aa8","local-member-id":"d1872cc6a259534d","added-peer-id":"d1872cc6a259534d","added-peer-peer-urls":["https://199.19.214.21:2380"]} {"level":"info","ts":"2026-05-29T17:22:04.479132Z","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-29T17:22:04.479336Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.214.21:2380"} {"level":"info","ts":"2026-05-29T17:22:04.479368Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d1872cc6a259534d","initial-advertise-peer-urls":["https://199.19.214.21:2380"],"listen-peer-urls":["https://199.19.214.21:2380"],"advertise-client-urls":["https://199.19.214.21:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.214.21:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T17:22:04.479412Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.214.21:2380"} {"level":"info","ts":"2026-05-29T17:22:04.479415Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T17:22:04.946128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1872cc6a259534d is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T17:22:04.946189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1872cc6a259534d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T17:22:04.946203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1872cc6a259534d received MsgPreVoteResp from d1872cc6a259534d at term 1"} {"level":"info","ts":"2026-05-29T17:22:04.946216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1872cc6a259534d became candidate at term 2"} {"level":"info","ts":"2026-05-29T17:22:04.946222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1872cc6a259534d received MsgVoteResp from d1872cc6a259534d at term 2"} {"level":"info","ts":"2026-05-29T17:22:04.946234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1872cc6a259534d became leader at term 2"} {"level":"info","ts":"2026-05-29T17:22:04.946241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d1872cc6a259534d elected leader d1872cc6a259534d at term 2"} {"level":"info","ts":"2026-05-29T17:22:04.947611Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d1872cc6a259534d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.214.21:2379]}","request-path":"/0/members/d1872cc6a259534d/attributes","cluster-id":"b979e5170b701aa8","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T17:22:04.948922Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T17:22:04.949845Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T17:22:04.950295Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T17:22:04.951779Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T17:22:04.951809Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T17:22:04.951861Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b979e5170b701aa8","local-member-id":"d1872cc6a259534d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T17:22:04.951977Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T17:22:04.952013Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T17:22:04.954571Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.214.21:2379"} {"level":"info","ts":"2026-05-29T17:22:04.957145Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T17:22:43.952808Z","caller":"traceutil/trace.go:171","msg":"trace[1958259715] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"123.802921ms","start":"2026-05-29T17:22:43.828972Z","end":"2026-05-29T17:22:43.952775Z","steps":["trace[1958259715] 'process raft request' (duration: 118.236058ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:22:43.953461Z","caller":"traceutil/trace.go:171","msg":"trace[351656841] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"107.550381ms","start":"2026-05-29T17:22:43.845902Z","end":"2026-05-29T17:22:43.953452Z","steps":["trace[351656841] 'process raft request' (duration: 107.158603ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:22:50.096003Z","caller":"traceutil/trace.go:171","msg":"trace[1029942877] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"102.365746ms","start":"2026-05-29T17:22:49.9936Z","end":"2026-05-29T17:22:50.095966Z","steps":["trace[1029942877] 'read index received' (duration: 99.380606ms)","trace[1029942877] 'applied index is now lower than readState.Index' (duration: 2.98378ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T17:22:50.096232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.600162ms","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-29T17:22:50.096348Z","caller":"traceutil/trace.go:171","msg":"trace[1414411519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"102.780285ms","start":"2026-05-29T17:22:49.993553Z","end":"2026-05-29T17:22:50.096333Z","steps":["trace[1414411519] 'agreement among raft nodes before linearized reading' (duration: 102.574541ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:22:50.096115Z","caller":"traceutil/trace.go:171","msg":"trace[1884069506] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"105.660493ms","start":"2026-05-29T17:22:49.990386Z","end":"2026-05-29T17:22:50.096047Z","steps":["trace[1884069506] 'process raft request' (duration: 103.652062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:22:50.406547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.72357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T17:22:50.406735Z","caller":"traceutil/trace.go:171","msg":"trace[1408822521] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"304.043497ms","start":"2026-05-29T17:22:50.102636Z","end":"2026-05-29T17:22:50.40668Z","steps":["trace[1408822521] 'process raft request' (duration: 177.048671ms)","trace[1408822521] 'compare' (duration: 125.136348ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T17:22:50.406975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.956214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-29T17:22:50.407029Z","caller":"traceutil/trace.go:171","msg":"trace[1438679191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"266.050726ms","start":"2026-05-29T17:22:50.140967Z","end":"2026-05-29T17:22:50.407017Z","steps":["trace[1438679191] 'agreement among raft nodes before linearized reading' (duration: 265.912213ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:22:50.406813Z","caller":"traceutil/trace.go:171","msg":"trace[415989715] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"235.370404ms","start":"2026-05-29T17:22:50.171428Z","end":"2026-05-29T17:22:50.406799Z","steps":["trace[415989715] 'process raft request' (duration: 235.258611ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:22:50.406857Z","caller":"traceutil/trace.go:171","msg":"trace[860509662] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"265.820942ms","start":"2026-05-29T17:22:50.141024Z","end":"2026-05-29T17:22:50.406845Z","steps":["trace[860509662] 'read index received' (duration: 138.759245ms)","trace[860509662] 'applied index is now lower than readState.Index' (duration: 127.060367ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T17:22:50.407262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:22:50.102612Z","time spent":"304.337243ms","remote":"127.0.0.1:45210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T17:22:51.125312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.429016ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T17:22:51.125451Z","caller":"traceutil/trace.go:171","msg":"trace[506967592] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:593; }","duration":"275.607729ms","start":"2026-05-29T17:22:50.849822Z","end":"2026-05-29T17:22:51.12543Z","steps":["trace[506967592] 'range keys from in-memory index tree' (duration: 275.408586ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:22:51.125879Z","caller":"traceutil/trace.go:171","msg":"trace[548684343] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"225.151477ms","start":"2026-05-29T17:22:50.900701Z","end":"2026-05-29T17:22:51.125852Z","steps":["trace[548684343] 'read index received' (duration: 222.307239ms)","trace[548684343] 'applied index is now lower than readState.Index' (duration: 2.842798ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T17:22:51.12597Z","caller":"traceutil/trace.go:171","msg":"trace[559448337] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"282.917548ms","start":"2026-05-29T17:22:50.843039Z","end":"2026-05-29T17:22:51.125956Z","steps":["trace[559448337] 'process raft request' (duration: 280.271984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:22:51.125916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.940573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t8xz9\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-29T17:22:51.12611Z","caller":"traceutil/trace.go:171","msg":"trace[1612157636] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t8xz9; range_end:; response_count:1; response_revision:593; }","duration":"541.302259ms","start":"2026-05-29T17:22:50.584787Z","end":"2026-05-29T17:22:51.126089Z","steps":["trace[1612157636] 'range keys from in-memory index tree' (duration: 540.192236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:22:51.126118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.417653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-29T17:22:51.126172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:22:50.584762Z","time spent":"541.398472ms","remote":"127.0.0.1:45158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-t8xz9\" "} {"level":"info","ts":"2026-05-29T17:22:51.126179Z","caller":"traceutil/trace.go:171","msg":"trace[784267310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"225.528225ms","start":"2026-05-29T17:22:50.900633Z","end":"2026-05-29T17:22:51.126161Z","steps":["trace[784267310] 'agreement among raft nodes before linearized reading' (duration: 225.30165ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:24:01.251998Z","caller":"traceutil/trace.go:171","msg":"trace[1302471507] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1085; }","duration":"367.78071ms","start":"2026-05-29T17:24:00.884184Z","end":"2026-05-29T17:24:01.251965Z","steps":["trace[1302471507] 'read index received' (duration: 367.591786ms)","trace[1302471507] 'applied index is now lower than readState.Index' (duration: 187.374µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T17:24:01.25211Z","caller":"traceutil/trace.go:171","msg":"trace[1235763494] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"484.289816ms","start":"2026-05-29T17:24:00.767776Z","end":"2026-05-29T17:24:01.252066Z","steps":["trace[1235763494] 'process raft request' (duration: 483.98459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:24:01.252326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:24:00.767755Z","time spent":"484.446359ms","remote":"127.0.0.1:45210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T17:24:01.252385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.956804ms","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-29T17:24:01.252383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.179118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-29T17:24:01.252405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.251527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T17:24:01.25244Z","caller":"traceutil/trace.go:171","msg":"trace[146207121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1047; }","duration":"367.079106ms","start":"2026-05-29T17:24:00.885347Z","end":"2026-05-29T17:24:01.252426Z","steps":["trace[146207121] 'agreement among raft nodes before linearized reading' (duration: 366.82202ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:24:01.252491Z","caller":"traceutil/trace.go:171","msg":"trace[758243443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1047; }","duration":"368.349532ms","start":"2026-05-29T17:24:00.884109Z","end":"2026-05-29T17:24:01.252458Z","steps":["trace[758243443] 'agreement among raft nodes before linearized reading' (duration: 368.039315ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:24:01.252528Z","caller":"traceutil/trace.go:171","msg":"trace[856916459] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1047; }","duration":"180.427721ms","start":"2026-05-29T17:24:01.072073Z","end":"2026-05-29T17:24:01.252501Z","steps":["trace[856916459] 'agreement among raft nodes before linearized reading' (duration: 180.156154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:24:01.252542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:24:00.88532Z","time spent":"367.211999ms","remote":"127.0.0.1:45210","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-05-29T17:24:01.252554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:24:00.884081Z","time spent":"368.463864ms","remote":"127.0.0.1:45210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T17:24:06.242314Z","caller":"traceutil/trace.go:171","msg":"trace[509210849] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"174.05303ms","start":"2026-05-29T17:24:06.068207Z","end":"2026-05-29T17:24:06.24226Z","steps":["trace[509210849] 'process raft request' (duration: 173.862696ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:24:06.574547Z","caller":"traceutil/trace.go:171","msg":"trace[1937104825] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"158.327303ms","start":"2026-05-29T17:24:06.416184Z","end":"2026-05-29T17:24:06.574511Z","steps":["trace[1937104825] 'process raft request' (duration: 158.085328ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:24:23.885081Z","caller":"traceutil/trace.go:171","msg":"trace[1118052518] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1216; }","duration":"469.405527ms","start":"2026-05-29T17:24:23.41564Z","end":"2026-05-29T17:24:23.885045Z","steps":["trace[1118052518] 'read index received' (duration: 51.43817ms)","trace[1118052518] 'applied index is now lower than readState.Index' (duration: 417.965707ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T17:24:23.885226Z","caller":"traceutil/trace.go:171","msg":"trace[666501535] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"498.19452ms","start":"2026-05-29T17:24:23.386975Z","end":"2026-05-29T17:24:23.88517Z","steps":["trace[666501535] 'process raft request' (duration: 497.889913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:24:23.885393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.715343ms","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-29T17:24:23.885433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:24:23.386947Z","time spent":"498.380854ms","remote":"127.0.0.1:45210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T17:24:23.885448Z","caller":"traceutil/trace.go:171","msg":"trace[999342556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1173; }","duration":"469.837296ms","start":"2026-05-29T17:24:23.415595Z","end":"2026-05-29T17:24:23.885432Z","steps":["trace[999342556] 'agreement among raft nodes before linearized reading' (duration: 469.622531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:24:23.885514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:24:23.415573Z","time spent":"469.926448ms","remote":"127.0.0.1:45210","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":"info","ts":"2026-05-29T17:24:24.052335Z","caller":"traceutil/trace.go:171","msg":"trace[445023575] linearizableReadLoop","detail":"{readStateIndex:1219; appliedIndex:1218; }","duration":"167.11146ms","start":"2026-05-29T17:24:23.885181Z","end":"2026-05-29T17:24:24.052293Z","steps":["trace[445023575] 'read index received' (duration: 84.52748ms)","trace[445023575] 'applied index is now lower than readState.Index' (duration: 82.58064ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T17:24:24.052382Z","caller":"traceutil/trace.go:171","msg":"trace[987074073] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"163.225202ms","start":"2026-05-29T17:24:23.889135Z","end":"2026-05-29T17:24:24.05236Z","steps":["trace[987074073] 'process raft request' (duration: 163.067429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:24:24.052498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.707054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T17:24:24.052538Z","caller":"traceutil/trace.go:171","msg":"trace[1369939013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1174; }","duration":"606.805686ms","start":"2026-05-29T17:24:23.445718Z","end":"2026-05-29T17:24:24.052524Z","steps":["trace[1369939013] 'agreement among raft nodes before linearized reading' (duration: 606.705734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:24:24.052577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:24:23.445698Z","time spent":"606.870467ms","remote":"127.0.0.1:45024","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-05-29T17:24:24.052604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.083242ms","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-29T17:24:24.052701Z","caller":"traceutil/trace.go:171","msg":"trace[1383916021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1174; }","duration":"307.247827ms","start":"2026-05-29T17:24:23.74543Z","end":"2026-05-29T17:24:24.052678Z","steps":["trace[1383916021] 'agreement among raft nodes before linearized reading' (duration: 306.906459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:24:24.052749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:24:23.745397Z","time spent":"307.345409ms","remote":"127.0.0.1:45210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T17:24:40.262141Z","caller":"traceutil/trace.go:171","msg":"trace[1445433021] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"169.5222ms","start":"2026-05-29T17:24:40.092584Z","end":"2026-05-29T17:24:40.262107Z","steps":["trace[1445433021] 'process raft request' (duration: 130.820267ms)","trace[1445433021] 'compare' (duration: 38.432378ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T17:24:40.262284Z","caller":"traceutil/trace.go:171","msg":"trace[1097019671] linearizableReadLoop","detail":"{readStateIndex:1293; appliedIndex:1291; }","duration":"118.657491ms","start":"2026-05-29T17:24:40.143582Z","end":"2026-05-29T17:24:40.26224Z","steps":["trace[1097019671] 'read index received' (duration: 79.795274ms)","trace[1097019671] 'applied index is now lower than readState.Index' (duration: 38.859217ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T17:24:40.262294Z","caller":"traceutil/trace.go:171","msg":"trace[1585213925] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"167.957428ms","start":"2026-05-29T17:24:40.094323Z","end":"2026-05-29T17:24:40.262281Z","steps":["trace[1585213925] 'process raft request' (duration: 167.701893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:24:40.262608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.537638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-29T17:24:40.262643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.037199ms","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-29T17:24:40.26266Z","caller":"traceutil/trace.go:171","msg":"trace[670369094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1244; }","duration":"118.619629ms","start":"2026-05-29T17:24:40.144028Z","end":"2026-05-29T17:24:40.262648Z","steps":["trace[670369094] 'agreement among raft nodes before linearized reading' (duration: 118.440826ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:24:40.262688Z","caller":"traceutil/trace.go:171","msg":"trace[1142193525] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1244; }","duration":"119.167122ms","start":"2026-05-29T17:24:40.143508Z","end":"2026-05-29T17:24:40.262675Z","steps":["trace[1142193525] 'agreement among raft nodes before linearized reading' (duration: 118.935837ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:24:40.799908Z","caller":"traceutil/trace.go:171","msg":"trace[724722977] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"281.549316ms","start":"2026-05-29T17:24:40.518334Z","end":"2026-05-29T17:24:40.799884Z","steps":["trace[724722977] 'process raft request' (duration: 281.392363ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:24:40.799957Z","caller":"traceutil/trace.go:171","msg":"trace[1471055382] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1296; }","duration":"263.888268ms","start":"2026-05-29T17:24:40.536055Z","end":"2026-05-29T17:24:40.799944Z","steps":["trace[1471055382] 'read index received' (duration: 263.878708ms)","trace[1471055382] 'applied index is now lower than readState.Index' (duration: 7.94µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T17:24:40.800095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.01016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T17:24:40.800133Z","caller":"traceutil/trace.go:171","msg":"trace[186228423] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1247; }","duration":"264.076182ms","start":"2026-05-29T17:24:40.536039Z","end":"2026-05-29T17:24:40.800116Z","steps":["trace[186228423] 'agreement among raft nodes before linearized reading' (duration: 263.97295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:24:40.884626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.247822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5373"} {"level":"info","ts":"2026-05-29T17:24:40.884683Z","caller":"traceutil/trace.go:171","msg":"trace[977457888] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1248; }","duration":"243.358583ms","start":"2026-05-29T17:24:40.641312Z","end":"2026-05-29T17:24:40.884671Z","steps":["trace[977457888] 'agreement among raft nodes before linearized reading' (duration: 243.17594ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:24:40.884707Z","caller":"traceutil/trace.go:171","msg":"trace[244162783] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"159.992797ms","start":"2026-05-29T17:24:40.7247Z","end":"2026-05-29T17:24:40.884693Z","steps":["trace[244162783] 'process raft request' (duration: 142.83004ms)","trace[244162783] 'compare' (duration: 16.703557ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T17:24:41.524482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.232147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T17:24:41.524575Z","caller":"traceutil/trace.go:171","msg":"trace[1484781838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1251; }","duration":"100.396761ms","start":"2026-05-29T17:24:41.424158Z","end":"2026-05-29T17:24:41.524555Z","steps":["trace[1484781838] 'range keys from in-memory index tree' (duration: 100.104595ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:24:41.799813Z","caller":"traceutil/trace.go:171","msg":"trace[1697618910] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"248.051088ms","start":"2026-05-29T17:24:41.551735Z","end":"2026-05-29T17:24:41.799786Z","steps":["trace[1697618910] 'process raft request' (duration: 247.871605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:24:42.037729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.340201ms","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-05-29T17:24:42.037826Z","caller":"traceutil/trace.go:171","msg":"trace[1116466679] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1252; }","duration":"192.504595ms","start":"2026-05-29T17:24:41.845298Z","end":"2026-05-29T17:24:42.037803Z","steps":["trace[1116466679] 'count revisions from in-memory index tree' (duration: 192.24719ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:24:56.154962Z","caller":"traceutil/trace.go:171","msg":"trace[462864170] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"260.111193ms","start":"2026-05-29T17:24:55.894819Z","end":"2026-05-29T17:24:56.15493Z","steps":["trace[462864170] 'process raft request' (duration: 259.919189ms)"],"step_count":1}