{"level":"warn","ts":"2026-04-19T13:31:42.927937Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-19T13:31:42.928036Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153: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.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-19T13:31:42.928112Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-19T13:31:42.928129Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-04-19T13:31:42.928149Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-19T13:31:42.929093Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-04-19T13:31:42.929188Z","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":16,"max-cpu-available":16,"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.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-19T13:31:42.934094Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.657532ms"} {"level":"info","ts":"2026-04-19T13:31:42.941253Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-04-19T13:31:42.941342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-04-19T13:31:42.941368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-04-19T13:31:42.941398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-19T13:31:42.941411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-04-19T13:31:42.941452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-04-19T13:31:42.946954Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-19T13:31:42.948854Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-19T13:31:42.950112Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-19T13:31:42.953416Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-19T13:31:42.953778Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-19T13:31:42.953716Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-19T13:31:42.953881Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-19T13:31:42.954001Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-19T13:31:42.954799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-04-19T13:31:42.957592Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-04-19T13:31:42.959368Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-19T13:31:42.959492Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-04-19T13:31:42.959538Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-04-19T13:31:42.959806Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-19T13:31:42.959879Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-19T13:31:43.542576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-04-19T13:31:43.542681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-19T13:31:43.54274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-04-19T13:31:43.542772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-04-19T13:31:43.542783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-04-19T13:31:43.542799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-04-19T13:31:43.542814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-04-19T13:31:43.544439Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-04-19T13:31:43.544686Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T13:31:43.544752Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T13:31:43.544792Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:31:43.545231Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-19T13:31:43.545317Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-19T13:31:43.545786Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:31:43.545909Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:31:43.545947Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:31:43.547637Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-04-19T13:31:43.548356Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-19T13:31:46.381899Z","caller":"traceutil/trace.go:171","msg":"trace[921802785] transaction","detail":"{read_only:false; response_revision:152; number_of_response:1; }","duration":"133.482582ms","start":"2026-04-19T13:31:46.248385Z","end":"2026-04-19T13:31:46.381867Z","steps":["trace[921802785] 'process raft request' (duration: 126.046754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:58.927582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.038227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:31:58.928066Z","caller":"traceutil/trace.go:171","msg":"trace[1923600644] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"435.111584ms","start":"2026-04-19T13:31:58.492928Z","end":"2026-04-19T13:31:58.92804Z","steps":["trace[1923600644] 'process raft request' (duration: 176.785336ms)","trace[1923600644] 'compare' (duration: 256.882872ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:31:58.928176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:58.492909Z","time spent":"435.218406ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4649,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:31:58.928251Z","caller":"traceutil/trace.go:171","msg":"trace[1398096530] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:378; }","duration":"430.113154ms","start":"2026-04-19T13:31:58.498119Z","end":"2026-04-19T13:31:58.928232Z","steps":["trace[1398096530] 'read index received' (duration: 171.577901ms)","trace[1398096530] 'applied index is now lower than readState.Index' (duration: 258.533693ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:31:58.928392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.234547ms","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-04-19T13:31:58.928447Z","caller":"traceutil/trace.go:171","msg":"trace[1804651998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:367; }","duration":"430.347649ms","start":"2026-04-19T13:31:58.498084Z","end":"2026-04-19T13:31:58.928432Z","steps":["trace[1804651998] 'agreement among raft nodes before linearized reading' (duration: 430.223366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:58.928496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:58.498072Z","time spent":"430.412611ms","remote":"127.0.0.1:55084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-19T13:32:12.848316Z","caller":"traceutil/trace.go:171","msg":"trace[1964380277] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"104.31516ms","start":"2026-04-19T13:32:12.743975Z","end":"2026-04-19T13:32:12.84829Z","steps":["trace[1964380277] 'process raft request' (duration: 98.469369ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:19.440947Z","caller":"traceutil/trace.go:171","msg":"trace[1501062813] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"194.616794ms","start":"2026-04-19T13:32:19.246299Z","end":"2026-04-19T13:32:19.440916Z","steps":["trace[1501062813] 'process raft request' (duration: 143.820061ms)","trace[1501062813] 'compare' (duration: 50.65703ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:32:19.486797Z","caller":"traceutil/trace.go:171","msg":"trace[1355573647] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"215.941208ms","start":"2026-04-19T13:32:19.270846Z","end":"2026-04-19T13:32:19.486787Z","steps":["trace[1355573647] 'process raft request' (duration: 215.707003ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:20.248499Z","caller":"traceutil/trace.go:171","msg":"trace[1270217939] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"284.255433ms","start":"2026-04-19T13:32:19.964219Z","end":"2026-04-19T13:32:20.248474Z","steps":["trace[1270217939] 'read index received' (duration: 252.269373ms)","trace[1270217939] 'applied index is now lower than readState.Index' (duration: 31.98501ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:32:20.248555Z","caller":"traceutil/trace.go:171","msg":"trace[1316904469] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"629.271459ms","start":"2026-04-19T13:32:19.619222Z","end":"2026-04-19T13:32:20.248493Z","steps":["trace[1316904469] 'process raft request' (duration: 597.381531ms)","trace[1316904469] 'compare' (duration: 31.750574ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:32:20.248616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.380206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:32:20.248681Z","caller":"traceutil/trace.go:171","msg":"trace[1792105147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"284.479668ms","start":"2026-04-19T13:32:19.964184Z","end":"2026-04-19T13:32:20.248664Z","steps":["trace[1792105147] 'agreement among raft nodes before linearized reading' (duration: 284.371635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:20.248888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:32:19.619194Z","time spent":"629.582445ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:32:34.917638Z","caller":"traceutil/trace.go:171","msg":"trace[1320749956] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"118.268787ms","start":"2026-04-19T13:32:34.799353Z","end":"2026-04-19T13:32:34.917621Z","steps":["trace[1320749956] 'read index received' (duration: 118.130894ms)","trace[1320749956] 'applied index is now lower than readState.Index' (duration: 137.403µs)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:32:34.917781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.41739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-19T13:32:34.917802Z","caller":"traceutil/trace.go:171","msg":"trace[697952379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:737; }","duration":"118.497792ms","start":"2026-04-19T13:32:34.799299Z","end":"2026-04-19T13:32:34.917797Z","steps":["trace[697952379] 'agreement among raft nodes before linearized reading' (duration: 118.3909ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:34.917886Z","caller":"traceutil/trace.go:171","msg":"trace[774512757] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"254.464096ms","start":"2026-04-19T13:32:34.663245Z","end":"2026-04-19T13:32:34.917709Z","steps":["trace[774512757] 'process raft request' (duration: 254.288062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:48.356336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.588134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-819a2d65-a48d-487e-a4ca-5ec2ff47457f\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-04-19T13:33:48.356578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.497845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T13:33:48.356619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.982435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-04-19T13:33:48.356657Z","caller":"traceutil/trace.go:171","msg":"trace[1064255819] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1791; }","duration":"418.616668ms","start":"2026-04-19T13:33:47.938025Z","end":"2026-04-19T13:33:48.356641Z","steps":["trace[1064255819] 'count revisions from in-memory index tree' (duration: 418.422303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:48.356671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.635941ms","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-04-19T13:33:48.356672Z","caller":"traceutil/trace.go:171","msg":"trace[1703779724] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1791; }","duration":"382.049766ms","start":"2026-04-19T13:33:47.97461Z","end":"2026-04-19T13:33:48.356659Z","steps":["trace[1703779724] 'range keys from in-memory index tree' (duration: 381.887282ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:48.35665Z","caller":"traceutil/trace.go:171","msg":"trace[115731024] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"281.205276ms","start":"2026-04-19T13:33:48.075415Z","end":"2026-04-19T13:33:48.356621Z","steps":["trace[115731024] 'process raft request' (duration: 247.617177ms)","trace[115731024] 'compare' (duration: 33.135428ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:33:48.356686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.848873ms","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-04-19T13:33:48.35671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:33:47.974604Z","time spent":"382.098257ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10691,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"info","ts":"2026-04-19T13:33:48.356744Z","caller":"traceutil/trace.go:171","msg":"trace[900364435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1791; }","duration":"501.942896ms","start":"2026-04-19T13:33:47.854788Z","end":"2026-04-19T13:33:48.35673Z","steps":["trace[900364435] 'range keys from in-memory index tree' (duration: 501.68454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:48.356702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:33:47.938007Z","time spent":"418.685069ms","remote":"127.0.0.1:55108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-04-19T13:33:48.356785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:33:47.85477Z","time spent":"502.007487ms","remote":"127.0.0.1:55084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-19T13:33:48.35658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.126908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:33:48.356919Z","caller":"traceutil/trace.go:171","msg":"trace[157846698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1791; }","duration":"394.480736ms","start":"2026-04-19T13:33:47.962409Z","end":"2026-04-19T13:33:48.356889Z","steps":["trace[157846698] 'range keys from in-memory index tree' (duration: 394.060776ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:48.356708Z","caller":"traceutil/trace.go:171","msg":"trace[635169145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1791; }","duration":"497.687853ms","start":"2026-04-19T13:33:47.859008Z","end":"2026-04-19T13:33:48.356696Z","steps":["trace[635169145] 'range keys from in-memory index tree' (duration: 497.54379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:48.356959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:33:47.962395Z","time spent":"394.554507ms","remote":"127.0.0.1:54804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-19T13:33:48.356964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:33:47.858998Z","time spent":"497.958639ms","remote":"127.0.0.1:55084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-19T13:33:48.35648Z","caller":"traceutil/trace.go:171","msg":"trace[760020332] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-819a2d65-a48d-487e-a4ca-5ec2ff47457f; range_end:; response_count:1; response_revision:1791; }","duration":"400.715866ms","start":"2026-04-19T13:33:47.955692Z","end":"2026-04-19T13:33:48.356407Z","steps":["trace[760020332] 'range keys from in-memory index tree' (duration: 400.322018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:48.357077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:33:47.955673Z","time spent":"401.391513ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-819a2d65-a48d-487e-a4ca-5ec2ff47457f\" "} {"level":"info","ts":"2026-04-19T13:33:48.554452Z","caller":"traceutil/trace.go:171","msg":"trace[791314654] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"192.864078ms","start":"2026-04-19T13:33:48.361564Z","end":"2026-04-19T13:33:48.554428Z","steps":["trace[791314654] 'process raft request' (duration: 125.937085ms)","trace[791314654] 'compare' (duration: 66.619136ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:33:48.554544Z","caller":"traceutil/trace.go:171","msg":"trace[1062523682] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"192.549271ms","start":"2026-04-19T13:33:48.361961Z","end":"2026-04-19T13:33:48.55451Z","steps":["trace[1062523682] 'process raft request' (duration: 192.382817ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:48.554665Z","caller":"traceutil/trace.go:171","msg":"trace[1985414172] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"189.029455ms","start":"2026-04-19T13:33:48.36561Z","end":"2026-04-19T13:33:48.554639Z","steps":["trace[1985414172] 'process raft request' (duration: 188.83599ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:48.815427Z","caller":"traceutil/trace.go:171","msg":"trace[1379838397] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"254.856612ms","start":"2026-04-19T13:33:48.560543Z","end":"2026-04-19T13:33:48.8154Z","steps":["trace[1379838397] 'process raft request' (duration: 248.445947ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:48.815519Z","caller":"traceutil/trace.go:171","msg":"trace[935554395] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"254.897333ms","start":"2026-04-19T13:33:48.56061Z","end":"2026-04-19T13:33:48.815508Z","steps":["trace[935554395] 'process raft request' (duration: 254.709348ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:48.815605Z","caller":"traceutil/trace.go:171","msg":"trace[1184020038] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"254.900413ms","start":"2026-04-19T13:33:48.560698Z","end":"2026-04-19T13:33:48.815598Z","steps":["trace[1184020038] 'process raft request' (duration: 254.710528ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:48.815751Z","caller":"traceutil/trace.go:171","msg":"trace[1221110985] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"254.562075ms","start":"2026-04-19T13:33:48.561182Z","end":"2026-04-19T13:33:48.815744Z","steps":["trace[1221110985] 'process raft request' (duration: 254.281389ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:48.815677Z","caller":"traceutil/trace.go:171","msg":"trace[1523855491] linearizableReadLoop","detail":"{readStateIndex:1872; appliedIndex:1868; }","duration":"254.068913ms","start":"2026-04-19T13:33:48.561519Z","end":"2026-04-19T13:33:48.815588Z","steps":["trace[1523855491] 'read index received' (duration: 247.619468ms)","trace[1523855491] 'applied index is now lower than readState.Index' (duration: 6.448455ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:33:48.816074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.539904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10985"} {"level":"info","ts":"2026-04-19T13:33:48.816157Z","caller":"traceutil/trace.go:171","msg":"trace[792863579] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1800; }","duration":"254.645297ms","start":"2026-04-19T13:33:48.561491Z","end":"2026-04-19T13:33:48.816136Z","steps":["trace[792863579] 'agreement among raft nodes before linearized reading' (duration: 254.361771ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:34:33.898348Z","caller":"traceutil/trace.go:171","msg":"trace[32299914] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"118.170288ms","start":"2026-04-19T13:34:33.780137Z","end":"2026-04-19T13:34:33.898307Z","steps":["trace[32299914] 'process raft request' (duration: 117.890581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:34:41.052327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.243559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-19T13:34:41.052394Z","caller":"traceutil/trace.go:171","msg":"trace[86600474] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2129; }","duration":"270.359902ms","start":"2026-04-19T13:34:40.78202Z","end":"2026-04-19T13:34:41.05238Z","steps":["trace[86600474] 'range keys from in-memory index tree' (duration: 270.056093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:34:41.052444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.756222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:34:41.052523Z","caller":"traceutil/trace.go:171","msg":"trace[264229479] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2129; }","duration":"202.884886ms","start":"2026-04-19T13:34:40.849618Z","end":"2026-04-19T13:34:41.052503Z","steps":["trace[264229479] 'count revisions from in-memory index tree' (duration: 202.671521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:34:41.052526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.234148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-19T13:34:41.05257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.213572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:34:41.052623Z","caller":"traceutil/trace.go:171","msg":"trace[1159413621] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2129; }","duration":"265.349801ms","start":"2026-04-19T13:34:40.787245Z","end":"2026-04-19T13:34:41.052594Z","steps":["trace[1159413621] 'count revisions from in-memory index tree' (duration: 265.183667ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:34:41.052665Z","caller":"traceutil/trace.go:171","msg":"trace[1106185991] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:2129; }","duration":"369.381746ms","start":"2026-04-19T13:34:40.683262Z","end":"2026-04-19T13:34:41.052644Z","steps":["trace[1106185991] 'count revisions from in-memory index tree' (duration: 369.09509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:34:41.052711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:34:40.683245Z","time spent":"369.454677ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-04-19T13:34:41.05271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.746586ms","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-04-19T13:34:41.052795Z","caller":"traceutil/trace.go:171","msg":"trace[1460234401] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2129; }","duration":"389.867449ms","start":"2026-04-19T13:34:40.662908Z","end":"2026-04-19T13:34:41.052776Z","steps":["trace[1460234401] 'range keys from in-memory index tree' (duration: 389.576912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:34:41.052823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.839407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T13:34:41.05271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.790583ms","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-04-19T13:34:41.052885Z","caller":"traceutil/trace.go:171","msg":"trace[79457627] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2129; }","duration":"320.907508ms","start":"2026-04-19T13:34:40.731961Z","end":"2026-04-19T13:34:41.052869Z","steps":["trace[79457627] 'count revisions from in-memory index tree' (duration: 320.695503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:34:41.052843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:34:40.662891Z","time spent":"389.94386ms","remote":"127.0.0.1:55084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-19T13:34:41.052923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:34:40.731926Z","time spent":"320.98508ms","remote":"127.0.0.1:36600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-04-19T13:34:41.052906Z","caller":"traceutil/trace.go:171","msg":"trace[1891876579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2129; }","duration":"291.017679ms","start":"2026-04-19T13:34:40.761874Z","end":"2026-04-19T13:34:41.052891Z","steps":["trace[1891876579] 'range keys from in-memory index tree' (duration: 290.63937ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:34:41.215365Z","caller":"traceutil/trace.go:171","msg":"trace[2028803454] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"159.240575ms","start":"2026-04-19T13:34:41.056098Z","end":"2026-04-19T13:34:41.215339Z","steps":["trace[2028803454] 'process raft request' (duration: 159.078472ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:34:41.331674Z","caller":"traceutil/trace.go:171","msg":"trace[833606946] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"274.823679ms","start":"2026-04-19T13:34:41.056823Z","end":"2026-04-19T13:34:41.331647Z","steps":["trace[833606946] 'process raft request' (duration: 239.312944ms)","trace[833606946] 'compare' (duration: 35.29037ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:34:41.33181Z","caller":"traceutil/trace.go:171","msg":"trace[2089348694] linearizableReadLoop","detail":"{readStateIndex:2214; appliedIndex:2211; }","duration":"154.578913ms","start":"2026-04-19T13:34:41.177195Z","end":"2026-04-19T13:34:41.331774Z","steps":["trace[2089348694] 'read index received' (duration: 37.909843ms)","trace[2089348694] 'applied index is now lower than readState.Index' (duration: 116.6673ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:34:41.331908Z","caller":"traceutil/trace.go:171","msg":"trace[968141208] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"274.44277ms","start":"2026-04-19T13:34:41.057446Z","end":"2026-04-19T13:34:41.331888Z","steps":["trace[968141208] 'process raft request' (duration: 274.157233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:34:41.332087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.88459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-19T13:34:41.332144Z","caller":"traceutil/trace.go:171","msg":"trace[1493035817] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2132; }","duration":"154.999273ms","start":"2026-04-19T13:34:41.177124Z","end":"2026-04-19T13:34:41.332123Z","steps":["trace[1493035817] 'agreement among raft nodes before linearized reading' (duration: 154.810119ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:36:04.234396Z","caller":"traceutil/trace.go:171","msg":"trace[644452831] transaction","detail":"{read_only:false; response_revision:2777; number_of_response:1; }","duration":"177.155983ms","start":"2026-04-19T13:36:04.057211Z","end":"2026-04-19T13:36:04.234367Z","steps":["trace[644452831] 'process raft request' (duration: 176.751734ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:36:04.433079Z","caller":"traceutil/trace.go:171","msg":"trace[939906642] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"103.665359ms","start":"2026-04-19T13:36:04.329396Z","end":"2026-04-19T13:36:04.433061Z","steps":["trace[939906642] 'process raft request' (duration: 103.563936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:36:04.70142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.39478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:36:04.701523Z","caller":"traceutil/trace.go:171","msg":"trace[850296979] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:2778; }","duration":"187.547773ms","start":"2026-04-19T13:36:04.513952Z","end":"2026-04-19T13:36:04.7015Z","steps":["trace[850296979] 'count revisions from in-memory index tree' (duration: 187.308247ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:38:25.046059Z","caller":"traceutil/trace.go:171","msg":"trace[102080882] transaction","detail":"{read_only:false; response_revision:3718; number_of_response:1; }","duration":"189.270902ms","start":"2026-04-19T13:38:24.856759Z","end":"2026-04-19T13:38:25.04603Z","steps":["trace[102080882] 'process raft request' (duration: 189.087298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:38:30.424235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.715551ms","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-04-19T13:38:30.424516Z","caller":"traceutil/trace.go:171","msg":"trace[48905831] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3747; }","duration":"191.829494ms","start":"2026-04-19T13:38:30.232462Z","end":"2026-04-19T13:38:30.424292Z","steps":["trace[48905831] 'range keys from in-memory index tree' (duration: 191.506066ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:39:45.979385Z","caller":"traceutil/trace.go:171","msg":"trace[2145250353] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"124.810333ms","start":"2026-04-19T13:39:45.854534Z","end":"2026-04-19T13:39:45.979344Z","steps":["trace[2145250353] 'process raft request' (duration: 124.628549ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:39:50.548587Z","caller":"traceutil/trace.go:171","msg":"trace[1481999429] linearizableReadLoop","detail":"{readStateIndex:5083; appliedIndex:5081; }","duration":"118.620523ms","start":"2026-04-19T13:39:50.429942Z","end":"2026-04-19T13:39:50.548563Z","steps":["trace[1481999429] 'read index received' (duration: 88.285976ms)","trace[1481999429] 'applied index is now lower than readState.Index' (duration: 30.333547ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:39:50.548672Z","caller":"traceutil/trace.go:171","msg":"trace[1143630610] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"168.681574ms","start":"2026-04-19T13:39:50.379952Z","end":"2026-04-19T13:39:50.548634Z","steps":["trace[1143630610] 'process raft request' (duration: 138.276325ms)","trace[1143630610] 'compare' (duration: 30.159483ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:39:50.548845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.88251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-67be97cf-0587-4a67-90b1-30d12d339e59\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-19T13:39:50.548875Z","caller":"traceutil/trace.go:171","msg":"trace[335707996] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4894; }","duration":"163.442838ms","start":"2026-04-19T13:39:50.38542Z","end":"2026-04-19T13:39:50.548862Z","steps":["trace[335707996] 'process raft request' (duration: 163.1101ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:39:50.548885Z","caller":"traceutil/trace.go:171","msg":"trace[1966735756] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-67be97cf-0587-4a67-90b1-30d12d339e59; range_end:; response_count:1; response_revision:4894; }","duration":"118.954572ms","start":"2026-04-19T13:39:50.429918Z","end":"2026-04-19T13:39:50.548872Z","steps":["trace[1966735756] 'agreement among raft nodes before linearized reading' (duration: 118.808278ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:39:50.865918Z","caller":"traceutil/trace.go:171","msg":"trace[767235679] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"155.18942ms","start":"2026-04-19T13:39:50.710709Z","end":"2026-04-19T13:39:50.865899Z","steps":["trace[767235679] 'process raft request' (duration: 143.296895ms)","trace[767235679] 'compare' (duration: 11.814103ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:39:50.898212Z","caller":"traceutil/trace.go:171","msg":"trace[722009067] linearizableReadLoop","detail":"{readStateIndex:5096; appliedIndex:5093; }","duration":"117.487937ms","start":"2026-04-19T13:39:50.780699Z","end":"2026-04-19T13:39:50.898187Z","steps":["trace[722009067] 'read index received' (duration: 73.372228ms)","trace[722009067] 'applied index is now lower than readState.Index' (duration: 44.114649ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:39:50.898253Z","caller":"traceutil/trace.go:171","msg":"trace[1128317653] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"172.562488ms","start":"2026-04-19T13:39:50.725672Z","end":"2026-04-19T13:39:50.898234Z","steps":["trace[1128317653] 'process raft request' (duration: 172.420424ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:39:50.898226Z","caller":"traceutil/trace.go:171","msg":"trace[1509218754] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"187.356132ms","start":"2026-04-19T13:39:50.710841Z","end":"2026-04-19T13:39:50.898197Z","steps":["trace[1509218754] 'process raft request' (duration: 187.104846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:39:50.898441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.722522ms","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-04-19T13:39:50.89851Z","caller":"traceutil/trace.go:171","msg":"trace[2133524495] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4907; }","duration":"117.835505ms","start":"2026-04-19T13:39:50.780657Z","end":"2026-04-19T13:39:50.898493Z","steps":["trace[2133524495] 'agreement among raft nodes before linearized reading' (duration: 117.61228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:39:50.898517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.76796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-19T13:39:50.898581Z","caller":"traceutil/trace.go:171","msg":"trace[522485226] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4907; }","duration":"111.862442ms","start":"2026-04-19T13:39:50.786698Z","end":"2026-04-19T13:39:50.89856Z","steps":["trace[522485226] 'agreement among raft nodes before linearized reading' (duration: 111.655228ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:40:09.041871Z","caller":"traceutil/trace.go:171","msg":"trace[946663611] linearizableReadLoop","detail":"{readStateIndex:5491; appliedIndex:5490; }","duration":"202.100042ms","start":"2026-04-19T13:40:08.839749Z","end":"2026-04-19T13:40:09.041849Z","steps":["trace[946663611] 'read index received' (duration: 202.011109ms)","trace[946663611] 'applied index is now lower than readState.Index' (duration: 88.113µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:40:09.041942Z","caller":"traceutil/trace.go:171","msg":"trace[1181345362] transaction","detail":"{read_only:false; response_revision:5290; number_of_response:1; }","duration":"205.265368ms","start":"2026-04-19T13:40:08.836655Z","end":"2026-04-19T13:40:09.041921Z","steps":["trace[1181345362] 'process raft request' (duration: 205.062142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:40:09.042296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.532222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:110636"} {"level":"info","ts":"2026-04-19T13:40:09.042386Z","caller":"traceutil/trace.go:171","msg":"trace[786432742] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:5290; }","duration":"202.657196ms","start":"2026-04-19T13:40:08.839713Z","end":"2026-04-19T13:40:09.042371Z","steps":["trace[786432742] 'agreement among raft nodes before linearized reading' (duration: 202.232936ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:40:09.271368Z","caller":"traceutil/trace.go:171","msg":"trace[1021412593] transaction","detail":"{read_only:false; response_revision:5291; number_of_response:1; }","duration":"224.56255ms","start":"2026-04-19T13:40:09.046777Z","end":"2026-04-19T13:40:09.271339Z","steps":["trace[1021412593] 'process raft request' (duration: 131.49703ms)","trace[1021412593] 'compare' (duration: 92.952447ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:40:09.354835Z","caller":"traceutil/trace.go:171","msg":"trace[285310910] linearizableReadLoop","detail":"{readStateIndex:5493; appliedIndex:5491; }","duration":"287.533627ms","start":"2026-04-19T13:40:09.06728Z","end":"2026-04-19T13:40:09.354813Z","steps":["trace[285310910] 'read index received' (duration: 111.001968ms)","trace[285310910] 'applied index is now lower than readState.Index' (duration: 176.530869ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:40:09.354904Z","caller":"traceutil/trace.go:171","msg":"trace[979512082] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"299.227189ms","start":"2026-04-19T13:40:09.05564Z","end":"2026-04-19T13:40:09.354867Z","steps":["trace[979512082] 'process raft request' (duration: 299.033504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:40:09.354986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.699712ms","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":"info","ts":"2026-04-19T13:40:09.355021Z","caller":"traceutil/trace.go:171","msg":"trace[481300993] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:5292; }","duration":"287.785594ms","start":"2026-04-19T13:40:09.067225Z","end":"2026-04-19T13:40:09.35501Z","steps":["trace[481300993] 'agreement among raft nodes before linearized reading' (duration: 287.702762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:40:09.355131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.133735ms","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-04-19T13:40:09.355217Z","caller":"traceutil/trace.go:171","msg":"trace[732722999] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5292; }","duration":"281.247997ms","start":"2026-04-19T13:40:09.073948Z","end":"2026-04-19T13:40:09.355196Z","steps":["trace[732722999] 'agreement among raft nodes before linearized reading' (duration: 281.017912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:40:09.355219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.18539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-19T13:40:09.355334Z","caller":"traceutil/trace.go:171","msg":"trace[786434489] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5292; }","duration":"249.316772ms","start":"2026-04-19T13:40:09.106009Z","end":"2026-04-19T13:40:09.355325Z","steps":["trace[786434489] 'agreement among raft nodes before linearized reading' (duration: 249.009965ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:40:09.459938Z","caller":"traceutil/trace.go:171","msg":"trace[1083512355] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"101.171974ms","start":"2026-04-19T13:40:09.358739Z","end":"2026-04-19T13:40:09.459911Z","steps":["trace[1083512355] 'process raft request' (duration: 15.87451ms)","trace[1083512355] 'compare' (duration: 85.14843ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:40:09.460014Z","caller":"traceutil/trace.go:171","msg":"trace[1528349029] linearizableReadLoop","detail":"{readStateIndex:5494; appliedIndex:5493; }","duration":"100.073737ms","start":"2026-04-19T13:40:09.359918Z","end":"2026-04-19T13:40:09.459992Z","steps":["trace[1528349029] 'read index received' (duration: 14.656571ms)","trace[1528349029] 'applied index is now lower than readState.Index' (duration: 85.415806ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:40:09.460294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.357824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80201"} {"level":"info","ts":"2026-04-19T13:40:09.460329Z","caller":"traceutil/trace.go:171","msg":"trace[15427021] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5294; }","duration":"100.444546ms","start":"2026-04-19T13:40:09.359876Z","end":"2026-04-19T13:40:09.46032Z","steps":["trace[15427021] 'agreement among raft nodes before linearized reading' (duration: 100.19386ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:41:43.57209Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2990} {"level":"info","ts":"2026-04-19T13:41:43.64173Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2990,"took":"68.139614ms","hash":2403396199,"current-db-size-bytes":58642432,"current-db-size":"59 MB","current-db-size-in-use-bytes":34037760,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-19T13:41:43.641792Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2403396199,"revision":2990,"compact-revision":-1} {"level":"info","ts":"2026-04-19T13:46:10.999685Z","caller":"traceutil/trace.go:171","msg":"trace[118790599] linearizableReadLoop","detail":"{readStateIndex:8386; appliedIndex:8385; }","duration":"108.464196ms","start":"2026-04-19T13:46:10.891186Z","end":"2026-04-19T13:46:10.99965Z","steps":["trace[118790599] 'read index received' (duration: 108.162568ms)","trace[118790599] 'applied index is now lower than readState.Index' (duration: 299.778µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:46:10.99969Z","caller":"traceutil/trace.go:171","msg":"trace[2061370805] transaction","detail":"{read_only:false; response_revision:8101; number_of_response:1; }","duration":"158.809286ms","start":"2026-04-19T13:46:10.840856Z","end":"2026-04-19T13:46:10.999665Z","steps":["trace[2061370805] 'process raft request' (duration: 158.516889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:46:10.99992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.697072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-19T13:46:10.99997Z","caller":"traceutil/trace.go:171","msg":"trace[22863969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8101; }","duration":"108.820814ms","start":"2026-04-19T13:46:10.891136Z","end":"2026-04-19T13:46:10.999957Z","steps":["trace[22863969] 'agreement among raft nodes before linearized reading' (duration: 108.602469ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:46:23.345752Z","caller":"traceutil/trace.go:171","msg":"trace[1423734820] transaction","detail":"{read_only:false; response_revision:8165; number_of_response:1; }","duration":"147.695128ms","start":"2026-04-19T13:46:23.198034Z","end":"2026-04-19T13:46:23.345729Z","steps":["trace[1423734820] 'process raft request' (duration: 147.535725ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:46:23.345933Z","caller":"traceutil/trace.go:171","msg":"trace[170701894] transaction","detail":"{read_only:false; response_revision:8166; number_of_response:1; }","duration":"144.955583ms","start":"2026-04-19T13:46:23.200952Z","end":"2026-04-19T13:46:23.345908Z","steps":["trace[170701894] 'process raft request' (duration: 144.740278ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:46:23.515601Z","caller":"traceutil/trace.go:171","msg":"trace[303582982] linearizableReadLoop","detail":"{readStateIndex:8456; appliedIndex:8455; }","duration":"106.128709ms","start":"2026-04-19T13:46:23.409443Z","end":"2026-04-19T13:46:23.515572Z","steps":["trace[303582982] 'read index received' (duration: 39.556561ms)","trace[303582982] 'applied index is now lower than readState.Index' (duration: 66.570778ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:46:23.515621Z","caller":"traceutil/trace.go:171","msg":"trace[688626849] transaction","detail":"{read_only:false; response_revision:8167; number_of_response:1; }","duration":"122.563543ms","start":"2026-04-19T13:46:23.393026Z","end":"2026-04-19T13:46:23.515589Z","steps":["trace[688626849] 'process raft request' (duration: 55.921903ms)","trace[688626849] 'compare' (duration: 66.399025ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:46:23.51599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.537839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-19T13:46:23.516076Z","caller":"traceutil/trace.go:171","msg":"trace[452697076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8167; }","duration":"106.675212ms","start":"2026-04-19T13:46:23.409379Z","end":"2026-04-19T13:46:23.516054Z","steps":["trace[452697076] 'agreement among raft nodes before linearized reading' (duration: 106.368264ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:46:43.577136Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6128} {"level":"info","ts":"2026-04-19T13:46:43.669982Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6128,"took":"90.742322ms","hash":3545567657,"current-db-size-bytes":58642432,"current-db-size":"59 MB","current-db-size-in-use-bytes":22937600,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-19T13:46:43.670057Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3545567657,"revision":6128,"compact-revision":2990} {"level":"warn","ts":"2026-04-19T13:46:51.408571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.265475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-19T13:46:51.408735Z","caller":"traceutil/trace.go:171","msg":"trace[206278019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8370; }","duration":"101.453729ms","start":"2026-04-19T13:46:51.307263Z","end":"2026-04-19T13:46:51.408716Z","steps":["trace[206278019] 'range keys from in-memory index tree' (duration: 101.133472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:46:51.40864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.854729ms","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-04-19T13:46:51.409197Z","caller":"traceutil/trace.go:171","msg":"trace[960295755] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8370; }","duration":"102.446844ms","start":"2026-04-19T13:46:51.30674Z","end":"2026-04-19T13:46:51.409187Z","steps":["trace[960295755] 'range keys from in-memory index tree' (duration: 101.754856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:46:54.33555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.020567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:46:54.335646Z","caller":"traceutil/trace.go:171","msg":"trace[848980099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8386; }","duration":"370.16733ms","start":"2026-04-19T13:46:53.965458Z","end":"2026-04-19T13:46:54.335625Z","steps":["trace[848980099] 'range keys from in-memory index tree' (duration: 369.915164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:46:54.335683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:46:53.965436Z","time spent":"370.239002ms","remote":"127.0.0.1:54804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-19T13:46:54.336155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.012031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:46:54.336476Z","caller":"traceutil/trace.go:171","msg":"trace[1070550407] transaction","detail":"{read_only:false; response_revision:8387; number_of_response:1; }","duration":"253.986086ms","start":"2026-04-19T13:46:54.082459Z","end":"2026-04-19T13:46:54.336445Z","steps":["trace[1070550407] 'process raft request' (duration: 60.555656ms)","trace[1070550407] 'compare' (duration: 192.442667ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:46:54.36909Z","caller":"traceutil/trace.go:171","msg":"trace[1484864912] transaction","detail":"{read_only:false; response_revision:8388; number_of_response:1; }","duration":"216.021854ms","start":"2026-04-19T13:46:54.153047Z","end":"2026-04-19T13:46:54.369068Z","steps":["trace[1484864912] 'process raft request' (duration: 215.912011ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:46:54.38156Z","caller":"traceutil/trace.go:171","msg":"trace[1626034690] linearizableReadLoop","detail":"{readStateIndex:8687; appliedIndex:8686; }","duration":"167.60638ms","start":"2026-04-19T13:46:54.213925Z","end":"2026-04-19T13:46:54.381532Z","steps":["trace[1626034690] 'read index received' (duration: 155.15015ms)","trace[1626034690] 'applied index is now lower than readState.Index' (duration: 12.45512ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:46:54.381627Z","caller":"traceutil/trace.go:171","msg":"trace[190066780] transaction","detail":"{read_only:false; response_revision:8389; number_of_response:1; }","duration":"203.179026ms","start":"2026-04-19T13:46:54.178413Z","end":"2026-04-19T13:46:54.381592Z","steps":["trace[190066780] 'process raft request' (duration: 202.966791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:46:54.381746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.800514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:46:54.381805Z","caller":"traceutil/trace.go:171","msg":"trace[346834171] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:8389; }","duration":"167.914107ms","start":"2026-04-19T13:46:54.213876Z","end":"2026-04-19T13:46:54.38179Z","steps":["trace[346834171] 'agreement among raft nodes before linearized reading' (duration: 167.786094ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:47:03.572092Z","caller":"traceutil/trace.go:171","msg":"trace[1082278641] transaction","detail":"{read_only:false; response_revision:8479; number_of_response:1; }","duration":"100.6345ms","start":"2026-04-19T13:47:03.471438Z","end":"2026-04-19T13:47:03.572072Z","steps":["trace[1082278641] 'process raft request' (duration: 100.517077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:47:03.829279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.495009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:47:03.829383Z","caller":"traceutil/trace.go:171","msg":"trace[1988977068] linearizableReadLoop","detail":"{readStateIndex:8783; appliedIndex:8781; }","duration":"341.042981ms","start":"2026-04-19T13:47:03.488325Z","end":"2026-04-19T13:47:03.829368Z","steps":["trace[1988977068] 'read index received' (duration: 83.701913ms)","trace[1988977068] 'applied index is now lower than readState.Index' (duration: 257.340128ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:47:03.829408Z","caller":"traceutil/trace.go:171","msg":"trace[425806030] transaction","detail":"{read_only:false; response_revision:8480; number_of_response:1; }","duration":"357.677861ms","start":"2026-04-19T13:47:03.471696Z","end":"2026-04-19T13:47:03.829374Z","steps":["trace[425806030] 'process raft request' (duration: 239.012248ms)","trace[425806030] 'compare' (duration: 118.400666ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:47:03.829539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:47:03.47168Z","time spent":"357.786713ms","remote":"127.0.0.1:55084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:47:03.82958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.230175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T13:47:03.82968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.811154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-19T13:47:03.829695Z","caller":"traceutil/trace.go:171","msg":"trace[2141836260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8480; }","duration":"341.367208ms","start":"2026-04-19T13:47:03.488299Z","end":"2026-04-19T13:47:03.829666Z","steps":["trace[2141836260] 'agreement among raft nodes before linearized reading' (duration: 341.167763ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:47:03.829715Z","caller":"traceutil/trace.go:171","msg":"trace[1779150383] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8480; }","duration":"283.859955ms","start":"2026-04-19T13:47:03.545845Z","end":"2026-04-19T13:47:03.829705Z","steps":["trace[1779150383] 'agreement among raft nodes before linearized reading' (duration: 283.746382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:47:03.829746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:47:03.488288Z","time spent":"341.44628ms","remote":"127.0.0.1:54804","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-04-19T13:47:04.810253Z","caller":"traceutil/trace.go:171","msg":"trace[1648696895] transaction","detail":"{read_only:false; response_revision:8491; number_of_response:1; }","duration":"144.211397ms","start":"2026-04-19T13:47:04.666005Z","end":"2026-04-19T13:47:04.810216Z","steps":["trace[1648696895] 'process raft request' (duration: 143.992552ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:47:54.894145Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000245b.wal"} {"level":"info","ts":"2026-04-19T13:49:08.534312Z","caller":"traceutil/trace.go:171","msg":"trace[1758028891] transaction","detail":"{read_only:false; response_revision:9570; number_of_response:1; }","duration":"112.947005ms","start":"2026-04-19T13:49:08.421331Z","end":"2026-04-19T13:49:08.534279Z","steps":["trace[1758028891] 'process raft request' (duration: 112.663609ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:49:21.728067Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"355195d5aae85944","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-19T13:49:21.735122Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-19T13:49:21.735224Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-19T13:51:43.582988Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8282} {"level":"info","ts":"2026-04-19T13:51:43.66131Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8282,"took":"75.773051ms","hash":1597156937,"current-db-size-bytes":58642432,"current-db-size":"59 MB","current-db-size-in-use-bytes":24809472,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-19T13:51:43.661376Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1597156937,"revision":8282,"compact-revision":6128} {"level":"info","ts":"2026-04-19T13:56:43.589078Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10675} {"level":"info","ts":"2026-04-19T13:56:43.662581Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10675,"took":"71.838765ms","hash":926516456,"current-db-size-bytes":58642432,"current-db-size":"59 MB","current-db-size-in-use-bytes":20291584,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-19T13:56:43.663101Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":926516456,"revision":10675,"compact-revision":8282}