{"level":"warn","ts":"2026-03-02T02:04:53.773872Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T02:04:53.773963Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.67: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.67:2380","--initial-cluster=instance=https://199.204.45.67:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.67:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.67: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-03-02T02:04:53.774036Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T02:04:53.774048Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.67:2380"]} {"level":"info","ts":"2026-03-02T02:04:53.774067Z","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-03-02T02:04:53.775107Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.67:2379"]} {"level":"info","ts":"2026-03-02T02:04:53.775535Z","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.67:2380"],"listen-peer-urls":["https://199.204.45.67:2380"],"advertise-client-urls":["https://199.204.45.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.67: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-03-02T02:04:53.780046Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.25836ms"} {"level":"info","ts":"2026-03-02T02:04:53.785404Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cddd08233fdda28a","cluster-id":"72e3bf4964adc703"} {"level":"info","ts":"2026-03-02T02:04:53.785486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a switched to configuration voters=()"} {"level":"info","ts":"2026-03-02T02:04:53.785509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became follower at term 0"} {"level":"info","ts":"2026-03-02T02:04:53.785523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cddd08233fdda28a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-02T02:04:53.785534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became follower at term 1"} {"level":"info","ts":"2026-03-02T02:04:53.785571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a switched to configuration voters=(14834021695116649098)"} {"level":"warn","ts":"2026-03-02T02:04:53.799883Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-02T02:04:53.80124Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-02T02:04:53.802395Z","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-03-02T02:04:53.805129Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cddd08233fdda28a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-02T02:04:53.806126Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cddd08233fdda28a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-02T02:04:53.80621Z","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-03-02T02:04:53.806325Z","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-03-02T02:04:53.806339Z","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-03-02T02:04:53.807752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a switched to configuration voters=(14834021695116649098)"} {"level":"info","ts":"2026-03-02T02:04:53.807905Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"72e3bf4964adc703","local-member-id":"cddd08233fdda28a","added-peer-id":"cddd08233fdda28a","added-peer-peer-urls":["https://199.204.45.67:2380"]} {"level":"info","ts":"2026-03-02T02:04:53.809056Z","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-03-02T02:04:53.809375Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cddd08233fdda28a","initial-advertise-peer-urls":["https://199.204.45.67:2380"],"listen-peer-urls":["https://199.204.45.67:2380"],"advertise-client-urls":["https://199.204.45.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-02T02:04:53.809151Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.67:2380"} {"level":"info","ts":"2026-03-02T02:04:53.809495Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.67:2380"} {"level":"info","ts":"2026-03-02T02:04:53.809462Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-02T02:04:53.986764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a is starting a new election at term 1"} {"level":"info","ts":"2026-03-02T02:04:53.986852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became pre-candidate at term 1"} {"level":"info","ts":"2026-03-02T02:04:53.986891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a received MsgPreVoteResp from cddd08233fdda28a at term 1"} {"level":"info","ts":"2026-03-02T02:04:53.986912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became candidate at term 2"} {"level":"info","ts":"2026-03-02T02:04:53.986923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a received MsgVoteResp from cddd08233fdda28a at term 2"} {"level":"info","ts":"2026-03-02T02:04:53.986946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became leader at term 2"} {"level":"info","ts":"2026-03-02T02:04:53.986959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cddd08233fdda28a elected leader cddd08233fdda28a at term 2"} {"level":"info","ts":"2026-03-02T02:04:53.987933Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cddd08233fdda28a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.67:2379]}","request-path":"/0/members/cddd08233fdda28a/attributes","cluster-id":"72e3bf4964adc703","publish-timeout":"7s"} {"level":"info","ts":"2026-03-02T02:04:53.987948Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T02:04:53.987985Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:04:53.98825Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-02T02:04:53.98828Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-02T02:04:53.988232Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T02:04:53.989183Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"72e3bf4964adc703","local-member-id":"cddd08233fdda28a","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:04:53.989395Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:04:53.989439Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:04:53.991065Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.67:2379"} {"level":"info","ts":"2026-03-02T02:04:53.993848Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-02T02:05:11.83768Z","caller":"traceutil/trace.go:171","msg":"trace[1764950505] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"129.711744ms","start":"2026-03-02T02:05:11.707645Z","end":"2026-03-02T02:05:11.837357Z","steps":["trace[1764950505] 'process raft request' (duration: 108.08805ms)","trace[1764950505] 'compare' (duration: 21.493783ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:05:18.852127Z","caller":"traceutil/trace.go:171","msg":"trace[492353113] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"127.174129ms","start":"2026-03-02T02:05:18.724936Z","end":"2026-03-02T02:05:18.85211Z","steps":["trace[492353113] 'process raft request' (duration: 127.058289ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:05:23.993937Z","caller":"traceutil/trace.go:171","msg":"trace[1884105820] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"230.496242ms","start":"2026-03-02T02:05:23.763418Z","end":"2026-03-02T02:05:23.993914Z","steps":["trace[1884105820] 'read index received' (duration: 230.365371ms)","trace[1884105820] 'applied index is now lower than readState.Index' (duration: 129.881µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:05:23.994156Z","caller":"traceutil/trace.go:171","msg":"trace[1988299430] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"570.148775ms","start":"2026-03-02T02:05:23.423995Z","end":"2026-03-02T02:05:23.994144Z","steps":["trace[1988299430] 'process raft request' (duration: 569.801283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:05:23.99429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.832363ms","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-03-02T02:05:23.994407Z","caller":"traceutil/trace.go:171","msg":"trace[1188303368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:470; }","duration":"231.003324ms","start":"2026-03-02T02:05:23.763378Z","end":"2026-03-02T02:05:23.994381Z","steps":["trace[1188303368] 'agreement among raft nodes before linearized reading' (duration: 230.709973ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:05:23.994503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:05:23.423978Z","time spent":"570.203156ms","remote":"127.0.0.1:49112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:05:23.994792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.88243ms","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-03-02T02:05:23.994889Z","caller":"traceutil/trace.go:171","msg":"trace[1459769295] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:470; }","duration":"111.037881ms","start":"2026-03-02T02:05:23.883831Z","end":"2026-03-02T02:05:23.994868Z","steps":["trace[1459769295] 'agreement among raft nodes before linearized reading' (duration: 110.294908ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:05:24.206169Z","caller":"traceutil/trace.go:171","msg":"trace[267271982] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:488; }","duration":"190.094028ms","start":"2026-03-02T02:05:24.016055Z","end":"2026-03-02T02:05:24.206149Z","steps":["trace[267271982] 'read index received' (duration: 188.220469ms)","trace[267271982] 'applied index is now lower than readState.Index' (duration: 1.872619ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:05:24.20622Z","caller":"traceutil/trace.go:171","msg":"trace[1664281617] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"206.884838ms","start":"2026-03-02T02:05:23.999305Z","end":"2026-03-02T02:05:24.20619Z","steps":["trace[1664281617] 'process raft request' (duration: 204.955799ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:05:24.206294Z","caller":"traceutil/trace.go:171","msg":"trace[1661415512] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"205.849794ms","start":"2026-03-02T02:05:24.000435Z","end":"2026-03-02T02:05:24.206285Z","steps":["trace[1661415512] 'process raft request' (duration: 205.671233ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:05:24.206448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.38106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-03-02T02:05:24.206511Z","caller":"traceutil/trace.go:171","msg":"trace[1668652912] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:472; }","duration":"190.49406ms","start":"2026-03-02T02:05:24.016004Z","end":"2026-03-02T02:05:24.206498Z","steps":["trace[1668652912] 'agreement among raft nodes before linearized reading' (duration: 190.367809ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:05:27.535615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.378624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"warn","ts":"2026-03-02T02:05:27.53566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.477321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.67\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-02T02:05:27.535704Z","caller":"traceutil/trace.go:171","msg":"trace[1798028713] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:501; }","duration":"386.527804ms","start":"2026-03-02T02:05:27.149156Z","end":"2026-03-02T02:05:27.535684Z","steps":["trace[1798028713] 'range keys from in-memory index tree' (duration: 386.141403ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:05:27.535764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:05:27.149127Z","time spent":"386.624355ms","remote":"127.0.0.1:48932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12778,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"info","ts":"2026-03-02T02:05:27.535768Z","caller":"traceutil/trace.go:171","msg":"trace[286607783] range","detail":"{range_begin:/registry/masterleases/199.204.45.67; range_end:; response_count:1; response_revision:501; }","duration":"358.638982ms","start":"2026-03-02T02:05:27.177101Z","end":"2026-03-02T02:05:27.53574Z","steps":["trace[286607783] 'range keys from in-memory index tree' (duration: 358.31807ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:05:27.535826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:05:27.17708Z","time spent":"358.731542ms","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.67\" "} {"level":"warn","ts":"2026-03-02T02:05:31.922853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.922042ms","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-03-02T02:05:31.923009Z","caller":"traceutil/trace.go:171","msg":"trace[1765498079] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:514; }","duration":"561.094983ms","start":"2026-03-02T02:05:31.361891Z","end":"2026-03-02T02:05:31.922985Z","steps":["trace[1765498079] 'range keys from in-memory index tree' (duration: 560.902322ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:05:31.924385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.355762ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:05:31.924484Z","caller":"traceutil/trace.go:171","msg":"trace[1170873392] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"713.63112ms","start":"2026-03-02T02:05:31.210837Z","end":"2026-03-02T02:05:31.924468Z","steps":["trace[1170873392] 'read index received' (duration: 11.436524ms)","trace[1170873392] 'applied index is now lower than readState.Index' (duration: 702.192956ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:05:31.924583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.746491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:05:31.924632Z","caller":"traceutil/trace.go:171","msg":"trace[37567325] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"713.831091ms","start":"2026-03-02T02:05:31.210787Z","end":"2026-03-02T02:05:31.924618Z","steps":["trace[37567325] 'agreement among raft nodes before linearized reading' (duration: 713.736881ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:05:31.924675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:05:31.210766Z","time spent":"713.896752ms","remote":"127.0.0.1:48824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-02T02:05:31.924654Z","caller":"traceutil/trace.go:171","msg":"trace[964050136] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"970.572446ms","start":"2026-03-02T02:05:30.953997Z","end":"2026-03-02T02:05:31.924569Z","steps":["trace[964050136] 'process raft request' (duration: 268.281699ms)","trace[964050136] 'compare' (duration: 700.994601ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:05:31.925012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:05:30.953975Z","time spent":"970.877427ms","remote":"127.0.0.1:49030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:05:34.344138Z","caller":"traceutil/trace.go:171","msg":"trace[953781710] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:563; }","duration":"249.772959ms","start":"2026-03-02T02:05:34.094341Z","end":"2026-03-02T02:05:34.344114Z","steps":["trace[953781710] 'read index received' (duration: 249.715818ms)","trace[953781710] 'applied index is now lower than readState.Index' (duration: 56.131µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:05:34.344267Z","caller":"traceutil/trace.go:171","msg":"trace[157836926] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"250.329661ms","start":"2026-03-02T02:05:34.093912Z","end":"2026-03-02T02:05:34.344242Z","steps":["trace[157836926] 'process raft request' (duration: 250.08746ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:05:34.344333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.96769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:05:34.344417Z","caller":"traceutil/trace.go:171","msg":"trace[793128620] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:544; }","duration":"250.03202ms","start":"2026-03-02T02:05:34.094336Z","end":"2026-03-02T02:05:34.344368Z","steps":["trace[793128620] 'agreement among raft nodes before linearized reading' (duration: 249.938709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:05:34.350304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.970297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:05:34.350373Z","caller":"traceutil/trace.go:171","msg":"trace[117235450] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:546; }","duration":"243.054747ms","start":"2026-03-02T02:05:34.107301Z","end":"2026-03-02T02:05:34.350355Z","steps":["trace[117235450] 'agreement among raft nodes before linearized reading' (duration: 242.944687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:05:34.350676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.494056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-02T02:05:34.350645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.367947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-02T02:05:34.350758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.759258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:05:34.350774Z","caller":"traceutil/trace.go:171","msg":"trace[126361499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:546; }","duration":"104.542837ms","start":"2026-03-02T02:05:34.246204Z","end":"2026-03-02T02:05:34.350747Z","steps":["trace[126361499] 'agreement among raft nodes before linearized reading' (duration: 104.119575ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:05:34.350791Z","caller":"traceutil/trace.go:171","msg":"trace[1027667477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"148.840958ms","start":"2026-03-02T02:05:34.201942Z","end":"2026-03-02T02:05:34.350783Z","steps":["trace[1027667477] 'agreement among raft nodes before linearized reading' (duration: 148.795438ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:05:34.350942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.113077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:05:34.350979Z","caller":"traceutil/trace.go:171","msg":"trace[23408052] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:546; }","duration":"243.160367ms","start":"2026-03-02T02:05:34.107809Z","end":"2026-03-02T02:05:34.350969Z","steps":["trace[23408052] 'agreement among raft nodes before linearized reading' (duration: 243.107747ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:05:34.350995Z","caller":"traceutil/trace.go:171","msg":"trace[386043344] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"224.340468ms","start":"2026-03-02T02:05:34.126613Z","end":"2026-03-02T02:05:34.350954Z","steps":["trace[386043344] 'process raft request' (duration: 223.517534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:05:34.351107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.67148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:05:34.351138Z","caller":"traceutil/trace.go:171","msg":"trace[2084202974] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:546; }","duration":"243.71445ms","start":"2026-03-02T02:05:34.107415Z","end":"2026-03-02T02:05:34.351129Z","steps":["trace[2084202974] 'agreement among raft nodes before linearized reading' (duration: 243.66655ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:05:34.350718Z","caller":"traceutil/trace.go:171","msg":"trace[267485973] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:546; }","duration":"112.567546ms","start":"2026-03-02T02:05:34.238139Z","end":"2026-03-02T02:05:34.350707Z","steps":["trace[267485973] 'agreement among raft nodes before linearized reading' (duration: 112.418795ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:05:37.277931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.853737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-03-02T02:05:37.277997Z","caller":"traceutil/trace.go:171","msg":"trace[671904372] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:586; }","duration":"152.975918ms","start":"2026-03-02T02:05:37.125006Z","end":"2026-03-02T02:05:37.277982Z","steps":["trace[671904372] 'range keys from in-memory index tree' (duration: 152.645056ms)"],"step_count":1}