{"level":"warn","ts":"2026-05-04T01:33:13.29421Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-04T01:33:13.294335Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115: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.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-04T01:33:13.294422Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-04T01:33:13.294437Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-05-04T01:33:13.294474Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-04T01:33:13.295248Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-05-04T01:33:13.295333Z","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.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-04T01:33:13.29912Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.534321ms"} {"level":"info","ts":"2026-05-04T01:33:13.30819Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-05-04T01:33:13.3083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-05-04T01:33:13.308322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-05-04T01:33:13.308333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-04T01:33:13.308344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-05-04T01:33:13.308385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-05-04T01:33:13.319774Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-04T01:33:13.322564Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-04T01:33:13.324018Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-04T01:33:13.326317Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-04T01:33:13.326504Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-04T01:33:13.326517Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-04T01:33:13.328367Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-04T01:33:13.32838Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-04T01:33:13.328743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-05-04T01:33:13.328856Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-05-04T01:33:13.335858Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-04T01:33:13.335962Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-05-04T01:33:13.336039Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-05-04T01:33:13.336447Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-04T01:33:13.336543Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-04T01:33:13.710103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-05-04T01:33:13.710177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-04T01:33:13.710258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-05-04T01:33:13.710286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-05-04T01:33:13.710295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-05-04T01:33:13.710309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-05-04T01:33:13.710332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-05-04T01:33:13.711993Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-05-04T01:33:13.712056Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-04T01:33:13.712218Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T01:33:13.712573Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-04T01:33:13.712604Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-04T01:33:13.713009Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T01:33:13.713095Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T01:33:13.713141Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T01:33:13.716204Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-04T01:33:13.716299Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-04T01:33:13.719657Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"warn","ts":"2026-05-04T01:33:16.556189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.276964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-04T01:33:16.556329Z","caller":"traceutil/trace.go:171","msg":"trace[1056356731] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"387.271615ms","start":"2026-05-04T01:33:16.169038Z","end":"2026-05-04T01:33:16.55631Z","steps":["trace[1056356731] 'read index received' (duration: 264.225324ms)","trace[1056356731] 'applied index is now lower than readState.Index' (duration: 123.044921ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:33:16.556437Z","caller":"traceutil/trace.go:171","msg":"trace[1776589828] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"388.238907ms","start":"2026-05-04T01:33:16.168184Z","end":"2026-05-04T01:33:16.556423Z","steps":["trace[1776589828] 'process raft request' (duration: 265.211263ms)","trace[1776589828] 'compare' (duration: 122.107321ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:33:16.55651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:33:16.168167Z","time spent":"388.310432ms","remote":"127.0.0.1:44968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-04T01:33:16.556637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.471784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-04T01:33:16.556732Z","caller":"traceutil/trace.go:171","msg":"trace[1582611225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"377.590808ms","start":"2026-05-04T01:33:16.179115Z","end":"2026-05-04T01:33:16.556706Z","steps":["trace[1582611225] 'agreement among raft nodes before linearized reading' (duration: 377.362319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:33:16.556782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:33:16.179107Z","time spent":"377.666341ms","remote":"127.0.0.1:44584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-04T01:33:16.556784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.713187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-04T01:33:16.556844Z","caller":"traceutil/trace.go:171","msg":"trace[1015223473] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:73; }","duration":"387.812285ms","start":"2026-05-04T01:33:16.169015Z","end":"2026-05-04T01:33:16.556827Z","steps":["trace[1015223473] 'agreement among raft nodes before linearized reading' (duration: 387.680574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:33:16.556892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:33:16.169003Z","time spent":"387.880899ms","remote":"127.0.0.1:44950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-05-04T01:33:16.557006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.218817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-04T01:33:16.557149Z","caller":"traceutil/trace.go:171","msg":"trace[314471422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"328.419491ms","start":"2026-05-04T01:33:16.228682Z","end":"2026-05-04T01:33:16.557102Z","steps":["trace[314471422] 'agreement among raft nodes before linearized reading' (duration: 328.122358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:33:16.557226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:33:16.22865Z","time spent":"328.542903ms","remote":"127.0.0.1:44596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-04T01:33:16.732392Z","caller":"traceutil/trace.go:171","msg":"trace[923734333] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"162.645556ms","start":"2026-05-04T01:33:16.569685Z","end":"2026-05-04T01:33:16.732331Z","steps":["trace[923734333] 'process raft request' (duration: 150.010841ms)","trace[923734333] 'compare' (duration: 12.399308ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:33:44.316762Z","caller":"traceutil/trace.go:171","msg":"trace[190308173] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"342.991184ms","start":"2026-05-04T01:33:43.973737Z","end":"2026-05-04T01:33:44.316728Z","steps":["trace[190308173] 'process raft request' (duration: 295.665464ms)","trace[190308173] 'compare' (duration: 46.486247ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:33:44.317269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:33:43.973716Z","time spent":"343.253223ms","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T01:33:44.317392Z","caller":"traceutil/trace.go:171","msg":"trace[1646852476] transaction","detail":"{read_only:false; number_of_response:1; response_revision:564; }","duration":"343.464209ms","start":"2026-05-04T01:33:43.973919Z","end":"2026-05-04T01:33:44.317384Z","steps":["trace[1646852476] 'process raft request' (duration: 342.789167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:33:44.317455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:33:43.973901Z","time spent":"343.530621ms","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97,"response count":0,"response size":150531,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T01:33:44.317619Z","caller":"traceutil/trace.go:171","msg":"trace[420855982] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:582; }","duration":"340.772246ms","start":"2026-05-04T01:33:43.976773Z","end":"2026-05-04T01:33:44.317545Z","steps":["trace[420855982] 'read index received' (duration: 292.659428ms)","trace[420855982] 'applied index is now lower than readState.Index' (duration: 48.104619ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:33:44.317687Z","caller":"traceutil/trace.go:171","msg":"trace[2097517109] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"338.593583ms","start":"2026-05-04T01:33:43.979053Z","end":"2026-05-04T01:33:44.317646Z","steps":["trace[2097517109] 'process raft request' (duration: 338.159604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:33:44.317909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.075332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T01:33:44.317944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:33:43.979043Z","time spent":"338.806759ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T01:33:44.317944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.938564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:33:44.318024Z","caller":"traceutil/trace.go:171","msg":"trace[1648147659] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:566; }","duration":"305.058461ms","start":"2026-05-04T01:33:44.012953Z","end":"2026-05-04T01:33:44.318011Z","steps":["trace[1648147659] 'agreement among raft nodes before linearized reading' (duration: 304.956953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:33:44.318034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.65877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T01:33:44.318032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.200463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T01:33:44.318058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.719247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:33:44.318134Z","caller":"traceutil/trace.go:171","msg":"trace[220855966] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:566; }","duration":"335.763517ms","start":"2026-05-04T01:33:43.982331Z","end":"2026-05-04T01:33:44.318095Z","steps":["trace[220855966] 'agreement among raft nodes before linearized reading' (duration: 335.597987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:33:44.318149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.474802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T01:33:44.318167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.137235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:33:44.318182Z","caller":"traceutil/trace.go:171","msg":"trace[544829998] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:566; }","duration":"340.513627ms","start":"2026-05-04T01:33:43.977659Z","end":"2026-05-04T01:33:44.318173Z","steps":["trace[544829998] 'agreement among raft nodes before linearized reading' (duration: 340.475872ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:33:44.318167Z","caller":"traceutil/trace.go:171","msg":"trace[182283761] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:566; }","duration":"335.362314ms","start":"2026-05-04T01:33:43.982773Z","end":"2026-05-04T01:33:44.318135Z","steps":["trace[182283761] 'agreement among raft nodes before linearized reading' (duration: 335.13148ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:33:44.318209Z","caller":"traceutil/trace.go:171","msg":"trace[2117749954] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:566; }","duration":"332.184279ms","start":"2026-05-04T01:33:43.986008Z","end":"2026-05-04T01:33:44.318192Z","steps":["trace[2117749954] 'agreement among raft nodes before linearized reading' (duration: 332.130245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:33:44.318236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:33:43.982767Z","time spent":"335.457633ms","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-05-04T01:33:44.318213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:33:43.982326Z","time spent":"335.850288ms","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-05-04T01:33:44.318264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.666659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T01:33:44.318272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:33:43.986Z","time spent":"332.26054ms","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-05-04T01:33:44.318299Z","caller":"traceutil/trace.go:171","msg":"trace[1374292019] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:566; }","duration":"335.709633ms","start":"2026-05-04T01:33:43.982576Z","end":"2026-05-04T01:33:44.318285Z","steps":["trace[1374292019] 'agreement among raft nodes before linearized reading' (duration: 335.660019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:33:44.318264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.461526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T01:33:44.318331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:33:43.98257Z","time spent":"335.753358ms","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-05-04T01:33:44.318115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.531042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:33:44.318381Z","caller":"traceutil/trace.go:171","msg":"trace[1426481575] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:566; }","duration":"341.592642ms","start":"2026-05-04T01:33:43.976758Z","end":"2026-05-04T01:33:44.318351Z","steps":["trace[1426481575] 'agreement among raft nodes before linearized reading' (duration: 341.359409ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:33:44.318421Z","caller":"traceutil/trace.go:171","msg":"trace[1041783466] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:566; }","duration":"338.837576ms","start":"2026-05-04T01:33:43.979565Z","end":"2026-05-04T01:33:44.318403Z","steps":["trace[1041783466] 'agreement among raft nodes before linearized reading' (duration: 338.523862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:33:44.318441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:33:43.976753Z","time spent":"341.680611ms","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-05-04T01:33:44.318477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:33:43.97956Z","time spent":"338.907758ms","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-05-04T01:33:44.318062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:33:44.012936Z","time spent":"305.117814ms","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-04T01:33:44.317774Z","caller":"traceutil/trace.go:171","msg":"trace[504110306] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"296.071787ms","start":"2026-05-04T01:33:44.021689Z","end":"2026-05-04T01:33:44.317761Z","steps":["trace[504110306] 'process raft request' (duration: 295.693281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:33:44.318075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.489912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:33:44.318202Z","caller":"traceutil/trace.go:171","msg":"trace[1826540617] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:566; }","duration":"141.918414ms","start":"2026-05-04T01:33:44.176247Z","end":"2026-05-04T01:33:44.318165Z","steps":["trace[1826540617] 'agreement among raft nodes before linearized reading' (duration: 141.661094ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:33:44.317996Z","caller":"traceutil/trace.go:171","msg":"trace[1265306500] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:566; }","duration":"341.17921ms","start":"2026-05-04T01:33:43.976793Z","end":"2026-05-04T01:33:44.317973Z","steps":["trace[1265306500] 'agreement among raft nodes before linearized reading' (duration: 341.033546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:33:44.318675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:33:43.976789Z","time spent":"341.877089ms","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"info","ts":"2026-05-04T01:33:44.318678Z","caller":"traceutil/trace.go:171","msg":"trace[1585188820] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:566; }","duration":"333.179864ms","start":"2026-05-04T01:33:43.985481Z","end":"2026-05-04T01:33:44.318661Z","steps":["trace[1585188820] 'agreement among raft nodes before linearized reading' (duration: 332.364827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:33:44.318215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:33:43.977653Z","time spent":"340.556123ms","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-05-04T01:33:44.31878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:33:43.985475Z","time spent":"333.271703ms","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-04T01:33:45.224008Z","caller":"traceutil/trace.go:171","msg":"trace[725103169] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"146.108449ms","start":"2026-05-04T01:33:45.077865Z","end":"2026-05-04T01:33:45.223974Z","steps":["trace[725103169] 'process raft request' (duration: 140.350109ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:33:51.752128Z","caller":"traceutil/trace.go:171","msg":"trace[2020608961] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"113.622471ms","start":"2026-05-04T01:33:51.638472Z","end":"2026-05-04T01:33:51.752094Z","steps":["trace[2020608961] 'read index received' (duration: 104.666579ms)","trace[2020608961] 'applied index is now lower than readState.Index' (duration: 8.954253ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:33:51.752281Z","caller":"traceutil/trace.go:171","msg":"trace[146172614] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"290.040039ms","start":"2026-05-04T01:33:51.462193Z","end":"2026-05-04T01:33:51.752233Z","steps":["trace[146172614] 'process raft request' (duration: 280.987337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:33:51.752327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.831761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:33:51.752426Z","caller":"traceutil/trace.go:171","msg":"trace[1472915380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:678; }","duration":"114.011724ms","start":"2026-05-04T01:33:51.638387Z","end":"2026-05-04T01:33:51.752399Z","steps":["trace[1472915380] 'agreement among raft nodes before linearized reading' (duration: 113.827111ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:33:51.896195Z","caller":"traceutil/trace.go:171","msg":"trace[1096779751] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"139.727035ms","start":"2026-05-04T01:33:51.756433Z","end":"2026-05-04T01:33:51.89616Z","steps":["trace[1096779751] 'process raft request' (duration: 97.451453ms)","trace[1096779751] 'compare' (duration: 42.092061ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:33:51.896198Z","caller":"traceutil/trace.go:171","msg":"trace[112605385] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:699; }","duration":"117.213262ms","start":"2026-05-04T01:33:51.778899Z","end":"2026-05-04T01:33:51.896112Z","steps":["trace[112605385] 'read index received' (duration: 74.991114ms)","trace[112605385] 'applied index is now lower than readState.Index' (duration: 42.220528ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:33:51.896466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.548608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-04T01:33:51.896513Z","caller":"traceutil/trace.go:171","msg":"trace[1502520204] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:679; }","duration":"117.618931ms","start":"2026-05-04T01:33:51.778881Z","end":"2026-05-04T01:33:51.8965Z","steps":["trace[1502520204] 'agreement among raft nodes before linearized reading' (duration: 117.405473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:35:24.82111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.789684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:35:24.822005Z","caller":"traceutil/trace.go:171","msg":"trace[1859616921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1873; }","duration":"181.780363ms","start":"2026-05-04T01:35:24.640195Z","end":"2026-05-04T01:35:24.821975Z","steps":["trace[1859616921] 'range keys from in-memory index tree' (duration: 180.66117ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:35:52.781377Z","caller":"traceutil/trace.go:171","msg":"trace[192532708] linearizableReadLoop","detail":"{readStateIndex:2153; appliedIndex:2152; }","duration":"120.49715ms","start":"2026-05-04T01:35:52.660854Z","end":"2026-05-04T01:35:52.781351Z","steps":["trace[192532708] 'read index received' (duration: 116.729169ms)","trace[192532708] 'applied index is now lower than readState.Index' (duration: 3.765271ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:35:52.781535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.636777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:35:52.781578Z","caller":"traceutil/trace.go:171","msg":"trace[719689520] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2077; }","duration":"120.729758ms","start":"2026-05-04T01:35:52.660835Z","end":"2026-05-04T01:35:52.781565Z","steps":["trace[719689520] 'agreement among raft nodes before linearized reading' (duration: 120.621445ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:35:52.781562Z","caller":"traceutil/trace.go:171","msg":"trace[726277673] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"120.91405ms","start":"2026-05-04T01:35:52.660609Z","end":"2026-05-04T01:35:52.781523Z","steps":["trace[726277673] 'process raft request' (duration: 117.045547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:35:59.628712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.842684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T01:35:59.628969Z","caller":"traceutil/trace.go:171","msg":"trace[968430849] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"370.896331ms","start":"2026-05-04T01:35:59.258052Z","end":"2026-05-04T01:35:59.628949Z","steps":["trace[968430849] 'process raft request' (duration: 370.8003ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:35:59.628987Z","caller":"traceutil/trace.go:171","msg":"trace[524292593] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2189; }","duration":"382.495294ms","start":"2026-05-04T01:35:59.246454Z","end":"2026-05-04T01:35:59.628949Z","steps":["trace[524292593] 'read index received' (duration: 63.313454ms)","trace[524292593] 'applied index is now lower than readState.Index' (duration: 319.18012ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:35:59.629157Z","caller":"traceutil/trace.go:171","msg":"trace[2046517388] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"428.113396ms","start":"2026-05-04T01:35:59.201026Z","end":"2026-05-04T01:35:59.62914Z","steps":["trace[2046517388] 'process raft request' (duration: 108.723214ms)","trace[2046517388] 'compare' (duration: 318.554783ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:35:59.629339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:35:59.200994Z","time spent":"428.212447ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T01:35:59.629052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:35:59.258021Z","time spent":"370.993101ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T01:35:59.629607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.131361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-04T01:35:59.629915Z","caller":"traceutil/trace.go:171","msg":"trace[304957147] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2114; }","duration":"383.522883ms","start":"2026-05-04T01:35:59.246371Z","end":"2026-05-04T01:35:59.629894Z","steps":["trace[304957147] 'agreement among raft nodes before linearized reading' (duration: 382.986346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:35:59.630039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:35:59.24635Z","time spent":"383.640286ms","remote":"127.0.0.1:44968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-05-04T01:35:59.640876Z","caller":"traceutil/trace.go:171","msg":"trace[87968587] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"115.016613ms","start":"2026-05-04T01:35:59.525835Z","end":"2026-05-04T01:35:59.640852Z","steps":["trace[87968587] 'process raft request' (duration: 114.877289ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:35:59.654096Z","caller":"traceutil/trace.go:171","msg":"trace[1304714703] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"123.249339ms","start":"2026-05-04T01:35:59.530797Z","end":"2026-05-04T01:35:59.654046Z","steps":["trace[1304714703] 'process raft request' (duration: 122.97792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:35:59.654076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.246416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T01:35:59.654155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.62515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-04T01:35:59.654245Z","caller":"traceutil/trace.go:171","msg":"trace[131913623] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2116; }","duration":"245.467779ms","start":"2026-05-04T01:35:59.408733Z","end":"2026-05-04T01:35:59.654201Z","steps":["trace[131913623] 'agreement among raft nodes before linearized reading' (duration: 245.225754ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:35:59.654382Z","caller":"traceutil/trace.go:171","msg":"trace[147511100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2116; }","duration":"329.805449ms","start":"2026-05-04T01:35:59.32447Z","end":"2026-05-04T01:35:59.654276Z","steps":["trace[147511100] 'agreement among raft nodes before linearized reading' (duration: 329.475884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:35:59.65452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:35:59.324455Z","time spent":"329.989519ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-04T01:35:59.953047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.676847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T01:35:59.953279Z","caller":"traceutil/trace.go:171","msg":"trace[1238944755] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"293.055701ms","start":"2026-05-04T01:35:59.6602Z","end":"2026-05-04T01:35:59.953255Z","steps":["trace[1238944755] 'process raft request' (duration: 186.053669ms)","trace[1238944755] 'compare' (duration: 106.554334ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:41:48.113302Z","caller":"traceutil/trace.go:171","msg":"trace[1968978498] transaction","detail":"{read_only:false; response_revision:4777; number_of_response:1; }","duration":"229.241691ms","start":"2026-05-04T01:41:47.884034Z","end":"2026-05-04T01:41:48.113276Z","steps":["trace[1968978498] 'process raft request' (duration: 229.067913ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:48.114113Z","caller":"traceutil/trace.go:171","msg":"trace[1111327641] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"167.97724ms","start":"2026-05-04T01:41:47.946114Z","end":"2026-05-04T01:41:48.114091Z","steps":["trace[1111327641] 'process raft request' (duration: 167.552025ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:48.490285Z","caller":"traceutil/trace.go:171","msg":"trace[524627368] linearizableReadLoop","detail":"{readStateIndex:4980; appliedIndex:4978; }","duration":"126.485061ms","start":"2026-05-04T01:41:48.363779Z","end":"2026-05-04T01:41:48.490264Z","steps":["trace[524627368] 'read index received' (duration: 28.768156ms)","trace[524627368] 'applied index is now lower than readState.Index' (duration: 97.715805ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:41:48.490564Z","caller":"traceutil/trace.go:171","msg":"trace[94868579] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"127.755918ms","start":"2026-05-04T01:41:48.3628Z","end":"2026-05-04T01:41:48.490556Z","steps":["trace[94868579] 'process raft request' (duration: 112.708137ms)","trace[94868579] 'compare' (duration: 14.632706ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:41:48.49087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.037135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-05-04T01:41:48.490989Z","caller":"traceutil/trace.go:171","msg":"trace[504421165] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4799; }","duration":"127.223804ms","start":"2026-05-04T01:41:48.363743Z","end":"2026-05-04T01:41:48.490967Z","steps":["trace[504421165] 'agreement among raft nodes before linearized reading' (duration: 126.978406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:41:48.49142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.771071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-05-04T01:41:48.491531Z","caller":"traceutil/trace.go:171","msg":"trace[1652619992] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4799; }","duration":"121.897919ms","start":"2026-05-04T01:41:48.369608Z","end":"2026-05-04T01:41:48.491506Z","steps":["trace[1652619992] 'agreement among raft nodes before linearized reading' (duration: 120.8712ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:48.639885Z","caller":"traceutil/trace.go:171","msg":"trace[1887872178] linearizableReadLoop","detail":"{readStateIndex:4984; appliedIndex:4982; }","duration":"100.339178ms","start":"2026-05-04T01:41:48.539524Z","end":"2026-05-04T01:41:48.639853Z","steps":["trace[1887872178] 'read index received' (duration: 8.731078ms)","trace[1887872178] 'applied index is now lower than readState.Index' (duration: 91.596131ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:41:48.640016Z","caller":"traceutil/trace.go:171","msg":"trace[161851260] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"102.152128ms","start":"2026-05-04T01:41:48.537849Z","end":"2026-05-04T01:41:48.640001Z","steps":["trace[161851260] 'process raft request' (duration: 65.641914ms)","trace[161851260] 'compare' (duration: 36.044069ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:41:48.640405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.871612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-05-04T01:41:48.640451Z","caller":"traceutil/trace.go:171","msg":"trace[1900386463] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4805; }","duration":"100.961051ms","start":"2026-05-04T01:41:48.539478Z","end":"2026-05-04T01:41:48.640439Z","steps":["trace[1900386463] 'agreement among raft nodes before linearized reading' (duration: 100.800313ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:43:13.845761Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2936} {"level":"info","ts":"2026-05-04T01:43:13.944761Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2936,"took":"96.735213ms","hash":83542009,"current-db-size-bytes":52453376,"current-db-size":"52 MB","current-db-size-in-use-bytes":29069312,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-04T01:43:13.944817Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":83542009,"revision":2936,"compact-revision":-1} {"level":"info","ts":"2026-05-04T01:48:13.851987Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5605} {"level":"info","ts":"2026-05-04T01:48:13.945613Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5605,"took":"92.046531ms","hash":4066245183,"current-db-size-bytes":52453376,"current-db-size":"52 MB","current-db-size-in-use-bytes":18268160,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-04T01:48:13.945687Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4066245183,"revision":5605,"compact-revision":2936}