{"level":"warn","ts":"2026-07-25T19:12:19.941236Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T19:12:19.941321Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223: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.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-07-25T19:12:19.941381Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T19:12:19.941391Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-07-25T19:12:19.941429Z","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-07-25T19:12:19.942061Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-07-25T19:12:19.942247Z","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.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-07-25T19:12:19.94605Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.586875ms"} {"level":"info","ts":"2026-07-25T19:12:19.95399Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-07-25T19:12:19.954107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-07-25T19:12:19.95414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-07-25T19:12:19.954162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-25T19:12:19.954271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-07-25T19:12:19.954361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-07-25T19:12:19.966428Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-25T19:12:19.969946Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-25T19:12:19.97137Z","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-07-25T19:12:19.974592Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-25T19:12:19.97499Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-25T19:12:19.974973Z","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-07-25T19:12:19.975232Z","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-07-25T19:12:19.975292Z","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-07-25T19:12:19.975828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-07-25T19:12:19.975943Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-07-25T19:12:19.977371Z","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-07-25T19:12:19.97757Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-07-25T19:12:19.977659Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-25T19:12:19.977732Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-07-25T19:12:19.977604Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-25T19:12:20.355261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-07-25T19:12:20.355324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-25T19:12:20.355363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-07-25T19:12:20.355384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-07-25T19:12:20.355396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-07-25T19:12:20.355411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-07-25T19:12:20.355425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-07-25T19:12:20.356279Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T19:12:20.357163Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T19:12:20.357163Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-07-25T19:12:20.357393Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T19:12:20.357502Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T19:12:20.357908Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T19:12:20.35796Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T19:12:20.358185Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-25T19:12:20.358217Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-25T19:12:20.360149Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-07-25T19:12:20.360468Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-25T19:12:46.535761Z","caller":"traceutil/trace.go:171","msg":"trace[912957750] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"223.098569ms","start":"2026-07-25T19:12:46.312605Z","end":"2026-07-25T19:12:46.535704Z","steps":["trace[912957750] 'process raft request' (duration: 222.872383ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:13:06.136133Z","caller":"traceutil/trace.go:171","msg":"trace[2070288008] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"154.579201ms","start":"2026-07-25T19:13:05.981523Z","end":"2026-07-25T19:13:06.136102Z","steps":["trace[2070288008] 'process raft request' (duration: 153.960765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:13:06.476137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.03952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-25T19:13:06.47643Z","caller":"traceutil/trace.go:171","msg":"trace[457235348] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"274.118861ms","start":"2026-07-25T19:13:06.20226Z","end":"2026-07-25T19:13:06.476379Z","steps":["trace[457235348] 'process raft request' (duration: 109.139038ms)","trace[457235348] 'compare' (duration: 163.751933ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T19:13:06.476519Z","caller":"traceutil/trace.go:171","msg":"trace[2001506861] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"214.264698ms","start":"2026-07-25T19:13:06.262211Z","end":"2026-07-25T19:13:06.476476Z","steps":["trace[2001506861] 'process raft request' (duration: 214.083094ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:13:06.606636Z","caller":"traceutil/trace.go:171","msg":"trace[1315936699] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"115.455092ms","start":"2026-07-25T19:13:06.491152Z","end":"2026-07-25T19:13:06.606607Z","steps":["trace[1315936699] 'process raft request' (duration: 105.669604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:13:07.235223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.295998ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T19:13:07.235379Z","caller":"traceutil/trace.go:171","msg":"trace[1216719172] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"581.163183ms","start":"2026-07-25T19:13:06.654199Z","end":"2026-07-25T19:13:07.235362Z","steps":["trace[1216719172] 'read index received' (duration: 348.551717ms)","trace[1216719172] 'applied index is now lower than readState.Index' (duration: 232.609746ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T19:13:07.235535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.329688ms","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-07-25T19:13:07.235483Z","caller":"traceutil/trace.go:171","msg":"trace[696211717] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"582.606758ms","start":"2026-07-25T19:13:06.652805Z","end":"2026-07-25T19:13:07.235412Z","steps":["trace[696211717] 'process raft request' (duration: 349.869829ms)","trace[696211717] 'compare' (duration: 231.784526ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T19:13:07.235576Z","caller":"traceutil/trace.go:171","msg":"trace[351243492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"581.387309ms","start":"2026-07-25T19:13:06.654177Z","end":"2026-07-25T19:13:07.235564Z","steps":["trace[351243492] 'agreement among raft nodes before linearized reading' (duration: 581.231315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:13:07.235688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:13:06.654168Z","time spent":"581.484221ms","remote":"127.0.0.1:59460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-25T19:13:07.235785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:13:06.652782Z","time spent":"582.837913ms","remote":"127.0.0.1:59460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T19:13:07.265256Z","caller":"traceutil/trace.go:171","msg":"trace[1989092975] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"365.44671ms","start":"2026-07-25T19:13:06.899777Z","end":"2026-07-25T19:13:07.265224Z","steps":["trace[1989092975] 'process raft request' (duration: 363.410461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:13:07.265487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:13:06.89968Z","time spent":"365.682915ms","remote":"127.0.0.1:59316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T19:13:46.931644Z","caller":"traceutil/trace.go:171","msg":"trace[1275896149] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"169.059094ms","start":"2026-07-25T19:13:46.762559Z","end":"2026-07-25T19:13:46.931618Z","steps":["trace[1275896149] 'read index received' (duration: 168.90459ms)","trace[1275896149] 'applied index is now lower than readState.Index' (duration: 153.154µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T19:13:46.931768Z","caller":"traceutil/trace.go:171","msg":"trace[1803607075] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"194.726825ms","start":"2026-07-25T19:13:46.737015Z","end":"2026-07-25T19:13:46.931742Z","steps":["trace[1803607075] 'process raft request' (duration: 194.389797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:13:46.931794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.219577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T19:13:46.931842Z","caller":"traceutil/trace.go:171","msg":"trace[1778998334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1264; }","duration":"169.334581ms","start":"2026-07-25T19:13:46.762485Z","end":"2026-07-25T19:13:46.931819Z","steps":["trace[1778998334] 'agreement among raft nodes before linearized reading' (duration: 169.246679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:13:52.155001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.638268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T19:13:52.155147Z","caller":"traceutil/trace.go:171","msg":"trace[945226038] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1332; }","duration":"109.877244ms","start":"2026-07-25T19:13:52.045236Z","end":"2026-07-25T19:13:52.155113Z","steps":["trace[945226038] 'count revisions from in-memory index tree' (duration: 109.492884ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:13:59.084386Z","caller":"traceutil/trace.go:171","msg":"trace[46058552] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"203.067663ms","start":"2026-07-25T19:13:58.88129Z","end":"2026-07-25T19:13:59.084357Z","steps":["trace[46058552] 'process raft request' (duration: 202.876628ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:13:59.087641Z","caller":"traceutil/trace.go:171","msg":"trace[24201426] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1416; }","duration":"102.67908ms","start":"2026-07-25T19:13:58.984937Z","end":"2026-07-25T19:13:59.087616Z","steps":["trace[24201426] 'read index received' (duration: 99.269035ms)","trace[24201426] 'applied index is now lower than readState.Index' (duration: 3.408844ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T19:13:59.087804Z","caller":"traceutil/trace.go:171","msg":"trace[2098653638] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"108.495003ms","start":"2026-07-25T19:13:58.979263Z","end":"2026-07-25T19:13:59.087758Z","steps":["trace[2098653638] 'process raft request' (duration: 108.189325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:13:59.087878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.929046ms","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-07-25T19:13:59.087923Z","caller":"traceutil/trace.go:171","msg":"trace[1706995770] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1382; }","duration":"103.038399ms","start":"2026-07-25T19:13:58.984874Z","end":"2026-07-25T19:13:59.087913Z","steps":["trace[1706995770] 'agreement among raft nodes before linearized reading' (duration: 102.841194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:13:59.462328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.217912ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-25T19:14:50.843875Z","caller":"traceutil/trace.go:171","msg":"trace[402126962] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"110.32255ms","start":"2026-07-25T19:14:50.733513Z","end":"2026-07-25T19:14:50.843836Z","steps":["trace[402126962] 'process raft request' (duration: 110.154706ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:14:51.517953Z","caller":"traceutil/trace.go:171","msg":"trace[62336684] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"211.451242ms","start":"2026-07-25T19:14:51.306471Z","end":"2026-07-25T19:14:51.517922Z","steps":["trace[62336684] 'process raft request' (duration: 175.317281ms)","trace[62336684] 'compare' (duration: 35.669339ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T19:14:51.518298Z","caller":"traceutil/trace.go:171","msg":"trace[143453103] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"102.730191ms","start":"2026-07-25T19:14:51.415547Z","end":"2026-07-25T19:14:51.518278Z","steps":["trace[143453103] 'process raft request' (duration: 102.629488ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:14:51.518297Z","caller":"traceutil/trace.go:171","msg":"trace[137647199] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"183.781972ms","start":"2026-07-25T19:14:51.33447Z","end":"2026-07-25T19:14:51.518252Z","steps":["trace[137647199] 'process raft request' (duration: 183.378822ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:14:51.51841Z","caller":"traceutil/trace.go:171","msg":"trace[973688462] linearizableReadLoop","detail":"{readStateIndex:1906; appliedIndex:1903; }","duration":"169.120017ms","start":"2026-07-25T19:14:51.349238Z","end":"2026-07-25T19:14:51.518358Z","steps":["trace[973688462] 'read index received' (duration: 27.923716ms)","trace[973688462] 'applied index is now lower than readState.Index' (duration: 141.19007ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T19:14:51.518594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.348972ms","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-07-25T19:14:51.518674Z","caller":"traceutil/trace.go:171","msg":"trace[1809647724] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1836; }","duration":"169.467095ms","start":"2026-07-25T19:14:51.349189Z","end":"2026-07-25T19:14:51.518656Z","steps":["trace[1809647724] 'agreement among raft nodes before linearized reading' (duration: 169.306281ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:14:52.031424Z","caller":"traceutil/trace.go:171","msg":"trace[650861813] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"117.187571ms","start":"2026-07-25T19:14:51.91419Z","end":"2026-07-25T19:14:52.031377Z","steps":["trace[650861813] 'process raft request' (duration: 99.983522ms)","trace[650861813] 'compare' (duration: 17.010354ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T19:14:52.047652Z","caller":"traceutil/trace.go:171","msg":"trace[584320092] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"133.061387ms","start":"2026-07-25T19:14:51.914556Z","end":"2026-07-25T19:14:52.047617Z","steps":["trace[584320092] 'process raft request' (duration: 132.884753ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:14:52.436522Z","caller":"traceutil/trace.go:171","msg":"trace[631512927] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"111.782655ms","start":"2026-07-25T19:14:52.32471Z","end":"2026-07-25T19:14:52.436493Z","steps":["trace[631512927] 'process raft request' (duration: 111.540529ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:14:52.822376Z","caller":"traceutil/trace.go:171","msg":"trace[558337708] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"107.331415ms","start":"2026-07-25T19:14:52.715015Z","end":"2026-07-25T19:14:52.822347Z","steps":["trace[558337708] 'process raft request' (duration: 107.160421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:14:53.659101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.139729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T19:14:53.659274Z","caller":"traceutil/trace.go:171","msg":"trace[142394037] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1844; }","duration":"139.328363ms","start":"2026-07-25T19:14:53.519923Z","end":"2026-07-25T19:14:53.659251Z","steps":["trace[142394037] 'count revisions from in-memory index tree' (duration: 139.002325ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:14:53.69206Z","caller":"traceutil/trace.go:171","msg":"trace[1106230575] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"169.411694ms","start":"2026-07-25T19:14:53.522628Z","end":"2026-07-25T19:14:53.69204Z","steps":["trace[1106230575] 'process raft request' (duration: 169.26179ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:14:53.806334Z","caller":"traceutil/trace.go:171","msg":"trace[975911597] linearizableReadLoop","detail":"{readStateIndex:1917; appliedIndex:1915; }","duration":"226.218349ms","start":"2026-07-25T19:14:53.580096Z","end":"2026-07-25T19:14:53.806314Z","steps":["trace[975911597] 'read index received' (duration: 111.862329ms)","trace[975911597] 'applied index is now lower than readState.Index' (duration: 114.35502ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T19:14:53.806414Z","caller":"traceutil/trace.go:171","msg":"trace[1941905344] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"283.175209ms","start":"2026-07-25T19:14:53.523201Z","end":"2026-07-25T19:14:53.806377Z","steps":["trace[1941905344] 'process raft request' (duration: 262.637918ms)","trace[1941905344] 'compare' (duration: 20.394628ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T19:14:53.806539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.615961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-25T19:14:53.806543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.426084ms","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-07-25T19:14:53.806583Z","caller":"traceutil/trace.go:171","msg":"trace[738189306] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:1846; }","duration":"141.715113ms","start":"2026-07-25T19:14:53.664856Z","end":"2026-07-25T19:14:53.806571Z","steps":["trace[738189306] 'agreement among raft nodes before linearized reading' (duration: 141.594239ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:14:53.806602Z","caller":"traceutil/trace.go:171","msg":"trace[651335729] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1846; }","duration":"226.555167ms","start":"2026-07-25T19:14:53.580031Z","end":"2026-07-25T19:14:53.806586Z","steps":["trace[651335729] 'agreement among raft nodes before linearized reading' (duration: 226.344751ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:14:53.995799Z","caller":"traceutil/trace.go:171","msg":"trace[370184449] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"183.442403ms","start":"2026-07-25T19:14:53.812121Z","end":"2026-07-25T19:14:53.995564Z","steps":["trace[370184449] 'process raft request' (duration: 183.215867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:14:53.995953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.976426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T19:14:53.995836Z","caller":"traceutil/trace.go:171","msg":"trace[1706907057] linearizableReadLoop","detail":"{readStateIndex:1918; appliedIndex:1917; }","duration":"187.843083ms","start":"2026-07-25T19:14:53.807969Z","end":"2026-07-25T19:14:53.995812Z","steps":["trace[1706907057] 'read index received' (duration: 139.58625ms)","trace[1706907057] 'applied index is now lower than readState.Index' (duration: 48.254833ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T19:14:53.996004Z","caller":"traceutil/trace.go:171","msg":"trace[811045403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1848; }","duration":"188.107509ms","start":"2026-07-25T19:14:53.807887Z","end":"2026-07-25T19:14:53.995994Z","steps":["trace[811045403] 'agreement among raft nodes before linearized reading' (duration: 187.998627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:15:57.676617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.184379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T19:15:57.676714Z","caller":"traceutil/trace.go:171","msg":"trace[1528572772] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2178; }","duration":"191.342943ms","start":"2026-07-25T19:15:57.485353Z","end":"2026-07-25T19:15:57.676696Z","steps":["trace[1528572772] 'count revisions from in-memory index tree' (duration: 191.106268ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:16:15.155688Z","caller":"traceutil/trace.go:171","msg":"trace[890486517] transaction","detail":"{read_only:false; response_revision:2268; number_of_response:1; }","duration":"313.972717ms","start":"2026-07-25T19:16:14.841683Z","end":"2026-07-25T19:16:15.155656Z","steps":["trace[890486517] 'process raft request' (duration: 313.791302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:16:15.155918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:16:14.841656Z","time spent":"314.150403ms","remote":"127.0.0.1:59460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T19:16:15.467988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.577159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T19:16:15.468106Z","caller":"traceutil/trace.go:171","msg":"trace[1010161444] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2268; }","duration":"189.759674ms","start":"2026-07-25T19:16:15.278313Z","end":"2026-07-25T19:16:15.468072Z","steps":["trace[1010161444] 'count revisions from in-memory index tree' (duration: 189.483087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:16:15.468284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.480135ms","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-07-25T19:16:15.468384Z","caller":"traceutil/trace.go:171","msg":"trace[1869734008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2268; }","duration":"180.631069ms","start":"2026-07-25T19:16:15.287727Z","end":"2026-07-25T19:16:15.468358Z","steps":["trace[1869734008] 'range keys from in-memory index tree' (duration: 180.302131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:16:43.951527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.021578ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-07-25T19:16:43.951724Z","caller":"traceutil/trace.go:171","msg":"trace[771684096] linearizableReadLoop","detail":"{readStateIndex:2505; appliedIndex:2504; }","duration":"189.425382ms","start":"2026-07-25T19:16:43.762253Z","end":"2026-07-25T19:16:43.951678Z","steps":["trace[771684096] 'read index received' (duration: 77.296092ms)","trace[771684096] 'applied index is now lower than readState.Index' (duration: 112.12665ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T19:16:43.951849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.590366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T19:16:43.9519Z","caller":"traceutil/trace.go:171","msg":"trace[1634278567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2411; }","duration":"189.709419ms","start":"2026-07-25T19:16:43.762177Z","end":"2026-07-25T19:16:43.951886Z","steps":["trace[1634278567] 'agreement among raft nodes before linearized reading' (duration: 189.601207ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:16:44.52693Z","caller":"traceutil/trace.go:171","msg":"trace[798191420] transaction","detail":"{read_only:false; response_revision:2416; number_of_response:1; }","duration":"120.696867ms","start":"2026-07-25T19:16:44.406204Z","end":"2026-07-25T19:16:44.526901Z","steps":["trace[798191420] 'process raft request' (duration: 120.520962ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:16:44.743247Z","caller":"traceutil/trace.go:171","msg":"trace[1067840092] linearizableReadLoop","detail":"{readStateIndex:2511; appliedIndex:2510; }","duration":"104.375254ms","start":"2026-07-25T19:16:44.638847Z","end":"2026-07-25T19:16:44.743222Z","steps":["trace[1067840092] 'read index received' (duration: 104.122198ms)","trace[1067840092] 'applied index is now lower than readState.Index' (duration: 252.146µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T19:16:44.743529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.37638ms","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-07-25T19:16:44.743572Z","caller":"traceutil/trace.go:171","msg":"trace[1152360469] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2417; }","duration":"103.506152ms","start":"2026-07-25T19:16:44.640055Z","end":"2026-07-25T19:16:44.743561Z","steps":["trace[1152360469] 'agreement among raft nodes before linearized reading' (duration: 103.315018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:16:44.743563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.690612ms","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-07-25T19:16:44.743704Z","caller":"traceutil/trace.go:171","msg":"trace[2004466829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2417; }","duration":"104.872956ms","start":"2026-07-25T19:16:44.638805Z","end":"2026-07-25T19:16:44.743678Z","steps":["trace[2004466829] 'agreement among raft nodes before linearized reading' (duration: 104.524898ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:16:44.743494Z","caller":"traceutil/trace.go:171","msg":"trace[662829460] transaction","detail":"{read_only:false; response_revision:2417; number_of_response:1; }","duration":"139.8608ms","start":"2026-07-25T19:16:44.603438Z","end":"2026-07-25T19:16:44.743299Z","steps":["trace[662829460] 'process raft request' (duration: 139.608604ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:16:44.849728Z","caller":"traceutil/trace.go:171","msg":"trace[854127321] transaction","detail":"{read_only:false; response_revision:2418; number_of_response:1; }","duration":"101.658646ms","start":"2026-07-25T19:16:44.748033Z","end":"2026-07-25T19:16:44.849692Z","steps":["trace[854127321] 'process raft request' (duration: 78.254715ms)","trace[854127321] 'compare' (duration: 23.164595ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T19:16:44.849783Z","caller":"traceutil/trace.go:171","msg":"trace[1678218119] transaction","detail":"{read_only:false; response_revision:2419; number_of_response:1; }","duration":"101.207155ms","start":"2026-07-25T19:16:44.748535Z","end":"2026-07-25T19:16:44.849742Z","steps":["trace[1678218119] 'process raft request' (duration: 101.054841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:18:27.852327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227758492584372,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T19:18:27.858414Z","caller":"traceutil/trace.go:171","msg":"trace[1862603069] transaction","detail":"{read_only:false; response_revision:2934; number_of_response:1; }","duration":"566.770284ms","start":"2026-07-25T19:18:27.29161Z","end":"2026-07-25T19:18:27.858381Z","steps":["trace[1862603069] 'process raft request' (duration: 566.589779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:18:27.859363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:18:27.291584Z","time spent":"567.572373ms","remote":"127.0.0.1:59460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T19:18:27.859674Z","caller":"traceutil/trace.go:171","msg":"trace[612078436] linearizableReadLoop","detail":"{readStateIndex:3049; appliedIndex:3047; }","duration":"507.938615ms","start":"2026-07-25T19:18:27.351691Z","end":"2026-07-25T19:18:27.85963Z","steps":["trace[612078436] 'read index received' (duration: 506.554512ms)","trace[612078436] 'applied index is now lower than readState.Index' (duration: 1.382303ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T19:18:27.859808Z","caller":"traceutil/trace.go:171","msg":"trace[573490403] transaction","detail":"{read_only:false; response_revision:2935; number_of_response:1; }","duration":"533.471961ms","start":"2026-07-25T19:18:27.326277Z","end":"2026-07-25T19:18:27.859749Z","steps":["trace[573490403] 'process raft request' (duration: 533.209305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:18:27.859906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.198632ms","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-07-25T19:18:27.859957Z","caller":"traceutil/trace.go:171","msg":"trace[1599364000] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2935; }","duration":"508.328666ms","start":"2026-07-25T19:18:27.351615Z","end":"2026-07-25T19:18:27.859944Z","steps":["trace[1599364000] 'agreement among raft nodes before linearized reading' (duration: 508.13937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:18:27.859985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:18:27.351572Z","time spent":"508.406378ms","remote":"127.0.0.1:59460","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-07-25T19:18:27.860113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:18:27.326258Z","time spent":"533.706777ms","remote":"127.0.0.1:59460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T19:18:27.860107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.173717ms","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-07-25T19:18:27.860244Z","caller":"traceutil/trace.go:171","msg":"trace[1357014434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2935; }","duration":"507.376782ms","start":"2026-07-25T19:18:27.352859Z","end":"2026-07-25T19:18:27.860236Z","steps":["trace[1357014434] 'agreement among raft nodes before linearized reading' (duration: 506.977991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:18:27.860272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:18:27.352836Z","time spent":"507.428055ms","remote":"127.0.0.1:59460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-25T19:19:01.009742Z","caller":"traceutil/trace.go:171","msg":"trace[694269370] transaction","detail":"{read_only:false; response_revision:3102; number_of_response:1; }","duration":"479.311525ms","start":"2026-07-25T19:19:00.530398Z","end":"2026-07-25T19:19:01.00971Z","steps":["trace[694269370] 'process raft request' (duration: 479.144271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:19:01.009965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:19:00.530368Z","time spent":"479.498039ms","remote":"127.0.0.1:59460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T19:19:01.011128Z","caller":"traceutil/trace.go:171","msg":"trace[1559221190] linearizableReadLoop","detail":"{readStateIndex:3225; appliedIndex:3223; }","duration":"249.994291ms","start":"2026-07-25T19:19:00.761112Z","end":"2026-07-25T19:19:01.011106Z","steps":["trace[1559221190] 'read index received' (duration: 248.649749ms)","trace[1559221190] 'applied index is now lower than readState.Index' (duration: 1.342782ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T19:19:01.01121Z","caller":"traceutil/trace.go:171","msg":"trace[651089816] transaction","detail":"{read_only:false; response_revision:3103; number_of_response:1; }","duration":"360.191828ms","start":"2026-07-25T19:19:00.650997Z","end":"2026-07-25T19:19:01.011188Z","steps":["trace[651089816] 'process raft request' (duration: 359.919602ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:19:01.011269Z","caller":"traceutil/trace.go:171","msg":"trace[2130293389] transaction","detail":"{read_only:false; response_revision:3104; number_of_response:1; }","duration":"315.568112ms","start":"2026-07-25T19:19:00.695658Z","end":"2026-07-25T19:19:01.011226Z","steps":["trace[2130293389] 'process raft request' (duration: 315.365497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:19:01.011342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:19:00.65097Z","time spent":"360.318172ms","remote":"127.0.0.1:59460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T19:19:01.01149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:19:00.695627Z","time spent":"315.722096ms","remote":"127.0.0.1:59460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T19:19:01.011649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.536085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T19:19:01.01176Z","caller":"traceutil/trace.go:171","msg":"trace[443375662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3104; }","duration":"250.632748ms","start":"2026-07-25T19:19:00.761059Z","end":"2026-07-25T19:19:01.011691Z","steps":["trace[443375662] 'agreement among raft nodes before linearized reading' (duration: 250.177276ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:20:17.623218Z","caller":"traceutil/trace.go:171","msg":"trace[1216439278] transaction","detail":"{read_only:false; response_revision:3487; number_of_response:1; }","duration":"323.982764ms","start":"2026-07-25T19:20:17.299193Z","end":"2026-07-25T19:20:17.623175Z","steps":["trace[1216439278] 'process raft request' (duration: 323.759288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:20:17.623472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:20:17.299164Z","time spent":"324.201809ms","remote":"127.0.0.1:59460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T19:20:17.624876Z","caller":"traceutil/trace.go:171","msg":"trace[1643711175] transaction","detail":"{read_only:false; response_revision:3488; number_of_response:1; }","duration":"307.483754ms","start":"2026-07-25T19:20:17.31736Z","end":"2026-07-25T19:20:17.624844Z","steps":["trace[1643711175] 'process raft request' (duration: 306.939481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:20:17.625046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:20:17.317324Z","time spent":"307.634528ms","remote":"127.0.0.1:59460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T19:20:19.055578Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227758492585571,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T19:20:19.156703Z","caller":"traceutil/trace.go:171","msg":"trace[1667938405] transaction","detail":"{read_only:false; response_revision:3491; number_of_response:1; }","duration":"603.220165ms","start":"2026-07-25T19:20:18.553458Z","end":"2026-07-25T19:20:19.156678Z","steps":["trace[1667938405] 'process raft request' (duration: 603.089352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:20:19.156852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:20:18.553436Z","time spent":"603.319898ms","remote":"127.0.0.1:59460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T19:20:19.157798Z","caller":"traceutil/trace.go:171","msg":"trace[1005798734] transaction","detail":"{read_only:false; response_revision:3492; number_of_response:1; }","duration":"603.625674ms","start":"2026-07-25T19:20:18.554135Z","end":"2026-07-25T19:20:19.15776Z","steps":["trace[1005798734] 'process raft request' (duration: 603.4591ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:20:19.157882Z","caller":"traceutil/trace.go:171","msg":"trace[352162252] transaction","detail":"{read_only:false; response_revision:3493; number_of_response:1; }","duration":"603.649604ms","start":"2026-07-25T19:20:18.554204Z","end":"2026-07-25T19:20:19.157853Z","steps":["trace[352162252] 'process raft request' (duration: 603.46788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:20:19.158053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:20:18.554114Z","time spent":"603.786567ms","remote":"127.0.0.1:59460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T19:20:19.158074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:20:18.554181Z","time spent":"603.759737ms","remote":"127.0.0.1:59460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T19:20:19.160484Z","caller":"traceutil/trace.go:171","msg":"trace[571848737] linearizableReadLoop","detail":"{readStateIndex:3630; appliedIndex:3627; }","duration":"605.538611ms","start":"2026-07-25T19:20:18.554927Z","end":"2026-07-25T19:20:19.160466Z","steps":["trace[571848737] 'read index received' (duration: 601.748299ms)","trace[571848737] 'applied index is now lower than readState.Index' (duration: 3.789062ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T19:20:19.160684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.756336ms","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-07-25T19:20:19.160733Z","caller":"traceutil/trace.go:171","msg":"trace[664114155] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3493; }","duration":"605.810757ms","start":"2026-07-25T19:20:18.554912Z","end":"2026-07-25T19:20:19.160722Z","steps":["trace[664114155] 'agreement among raft nodes before linearized reading' (duration: 605.660053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:20:19.160731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.626083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T19:20:19.16077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:20:18.55487Z","time spent":"605.890479ms","remote":"127.0.0.1:59460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-25T19:20:19.160811Z","caller":"traceutil/trace.go:171","msg":"trace[1371451418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3493; }","duration":"400.773257ms","start":"2026-07-25T19:20:18.760024Z","end":"2026-07-25T19:20:19.160797Z","steps":["trace[1371451418] 'agreement among raft nodes before linearized reading' (duration: 400.632583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:20:19.160796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.204952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-25T19:20:19.160845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:20:18.760006Z","time spent":"400.832768ms","remote":"127.0.0.1:59216","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-07-25T19:20:19.160889Z","caller":"traceutil/trace.go:171","msg":"trace[1708359794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3493; }","duration":"407.351295ms","start":"2026-07-25T19:20:18.753516Z","end":"2026-07-25T19:20:19.160867Z","steps":["trace[1708359794] 'agreement among raft nodes before linearized reading' (duration: 407.107179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:20:19.160966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:20:18.753495Z","time spent":"407.461347ms","remote":"127.0.0.1:59460","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-07-25T19:22:03.000521Z","caller":"traceutil/trace.go:171","msg":"trace[333414088] transaction","detail":"{read_only:false; response_revision:4214; number_of_response:1; }","duration":"104.628824ms","start":"2026-07-25T19:22:02.895864Z","end":"2026-07-25T19:22:03.000493Z","steps":["trace[333414088] 'process raft request' (duration: 104.427279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:22:09.572304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.488343ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-25T19:22:20.574138Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2597} {"level":"info","ts":"2026-07-25T19:22:20.65043Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2597,"took":"75.095811ms","hash":3072346629,"current-db-size-bytes":34566144,"current-db-size":"35 MB","current-db-size-in-use-bytes":9109504,"current-db-size-in-use":"9.1 MB"} {"level":"info","ts":"2026-07-25T19:22:20.650511Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3072346629,"revision":2597,"compact-revision":-1} {"level":"info","ts":"2026-07-25T19:22:56.519369Z","caller":"traceutil/trace.go:171","msg":"trace[1151556323] transaction","detail":"{read_only:false; response_revision:4616; number_of_response:1; }","duration":"133.872996ms","start":"2026-07-25T19:22:56.385463Z","end":"2026-07-25T19:22:56.519336Z","steps":["trace[1151556323] 'process raft request' (duration: 133.679741ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:22:58.426922Z","caller":"traceutil/trace.go:171","msg":"trace[1796110411] linearizableReadLoop","detail":"{readStateIndex:4795; appliedIndex:4794; }","duration":"173.782538ms","start":"2026-07-25T19:22:58.253114Z","end":"2026-07-25T19:22:58.426896Z","steps":["trace[1796110411] 'read index received' (duration: 173.596714ms)","trace[1796110411] 'applied index is now lower than readState.Index' (duration: 182.184µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T19:22:58.427106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.079445ms","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-07-25T19:22:58.427168Z","caller":"traceutil/trace.go:171","msg":"trace[1634494007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4620; }","duration":"174.218648ms","start":"2026-07-25T19:22:58.25293Z","end":"2026-07-25T19:22:58.427148Z","steps":["trace[1634494007] 'agreement among raft nodes before linearized reading' (duration: 174.093875ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:22:58.427694Z","caller":"traceutil/trace.go:171","msg":"trace[416981038] transaction","detail":"{read_only:false; response_revision:4620; number_of_response:1; }","duration":"176.397952ms","start":"2026-07-25T19:22:58.250546Z","end":"2026-07-25T19:22:58.426944Z","steps":["trace[416981038] 'process raft request' (duration: 176.162226ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:22:58.54643Z","caller":"traceutil/trace.go:171","msg":"trace[2120989469] transaction","detail":"{read_only:false; response_revision:4621; number_of_response:1; }","duration":"289.532892ms","start":"2026-07-25T19:22:58.256852Z","end":"2026-07-25T19:22:58.546385Z","steps":["trace[2120989469] 'process raft request' (duration: 284.668733ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:22:58.546721Z","caller":"traceutil/trace.go:171","msg":"trace[1682091844] transaction","detail":"{read_only:false; response_revision:4622; number_of_response:1; }","duration":"285.37274ms","start":"2026-07-25T19:22:58.2613Z","end":"2026-07-25T19:22:58.546672Z","steps":["trace[1682091844] 'process raft request' (duration: 285.028992ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:22:58.546825Z","caller":"traceutil/trace.go:171","msg":"trace[801968671] transaction","detail":"{read_only:false; response_revision:4623; number_of_response:1; }","duration":"279.963438ms","start":"2026-07-25T19:22:58.266823Z","end":"2026-07-25T19:22:58.546787Z","steps":["trace[801968671] 'process raft request' (duration: 279.759333ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:22:58.546905Z","caller":"traceutil/trace.go:171","msg":"trace[108914711] linearizableReadLoop","detail":"{readStateIndex:4799; appliedIndex:4795; }","duration":"119.855543ms","start":"2026-07-25T19:22:58.427Z","end":"2026-07-25T19:22:58.546856Z","steps":["trace[108914711] 'read index received' (duration: 114.539313ms)","trace[108914711] 'applied index is now lower than readState.Index' (duration: 5.3147ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T19:22:58.547185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.785043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-07-25T19:22:58.547276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.394705ms","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-07-25T19:22:58.547299Z","caller":"traceutil/trace.go:171","msg":"trace[1770322022] transaction","detail":"{read_only:false; response_revision:4624; number_of_response:1; }","duration":"275.003536ms","start":"2026-07-25T19:22:58.272273Z","end":"2026-07-25T19:22:58.547276Z","steps":["trace[1770322022] 'process raft request' (duration: 274.463313ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:22:58.547314Z","caller":"traceutil/trace.go:171","msg":"trace[683189156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4624; }","duration":"293.452857ms","start":"2026-07-25T19:22:58.253851Z","end":"2026-07-25T19:22:58.547304Z","steps":["trace[683189156] 'agreement among raft nodes before linearized reading' (duration: 293.365085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:22:58.547375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.556057ms","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":"warn","ts":"2026-07-25T19:22:58.547189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.319563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-013eb13e-735e-4f36-b895-7779d3a61cd4\" ","response":"range_response_count:1 size:3936"} {"level":"info","ts":"2026-07-25T19:22:58.547429Z","caller":"traceutil/trace.go:171","msg":"trace[699330692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4624; }","duration":"288.68316ms","start":"2026-07-25T19:22:58.258732Z","end":"2026-07-25T19:22:58.547415Z","steps":["trace[699330692] 'agreement among raft nodes before linearized reading' (duration: 288.528166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:22:58.54744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.211162ms","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-07-25T19:22:58.547645Z","caller":"traceutil/trace.go:171","msg":"trace[812674759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4624; }","duration":"291.477558ms","start":"2026-07-25T19:22:58.25614Z","end":"2026-07-25T19:22:58.547618Z","steps":["trace[812674759] 'agreement among raft nodes before linearized reading' (duration: 291.054718ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:22:58.547486Z","caller":"traceutil/trace.go:171","msg":"trace[1347853458] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-013eb13e-735e-4f36-b895-7779d3a61cd4; range_end:; response_count:1; response_revision:4624; }","duration":"195.663622ms","start":"2026-07-25T19:22:58.351796Z","end":"2026-07-25T19:22:58.54746Z","steps":["trace[1347853458] 'agreement among raft nodes before linearized reading' (duration: 195.239881ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:22:58.548021Z","caller":"traceutil/trace.go:171","msg":"trace[1195889013] transaction","detail":"{read_only:false; response_revision:4625; number_of_response:1; }","duration":"116.034469ms","start":"2026-07-25T19:22:58.431974Z","end":"2026-07-25T19:22:58.548008Z","steps":["trace[1195889013] 'process raft request' (duration: 115.764222ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:22:58.548075Z","caller":"traceutil/trace.go:171","msg":"trace[469505450] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4624; }","duration":"163.874606ms","start":"2026-07-25T19:22:58.383352Z","end":"2026-07-25T19:22:58.547227Z","steps":["trace[469505450] 'agreement among raft nodes before linearized reading' (duration: 163.61784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:23:08.739815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.61977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T19:23:08.740167Z","caller":"traceutil/trace.go:171","msg":"trace[1547347302] transaction","detail":"{read_only:false; response_revision:4706; number_of_response:1; }","duration":"156.020665ms","start":"2026-07-25T19:23:08.584105Z","end":"2026-07-25T19:23:08.740126Z","steps":["trace[1547347302] 'process raft request' (duration: 16.989344ms)","trace[1547347302] 'compare' (duration: 138.473786ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T19:23:08.740447Z","caller":"traceutil/trace.go:171","msg":"trace[1048723326] linearizableReadLoop","detail":"{readStateIndex:4886; appliedIndex:4882; }","duration":"122.19134ms","start":"2026-07-25T19:23:08.618236Z","end":"2026-07-25T19:23:08.740427Z","steps":["trace[1048723326] 'read index received' (duration: 10.826354ms)","trace[1048723326] 'applied index is now lower than readState.Index' (duration: 111.363636ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T19:23:08.740408Z","caller":"traceutil/trace.go:171","msg":"trace[1541109240] transaction","detail":"{read_only:false; response_revision:4707; number_of_response:1; }","duration":"155.642256ms","start":"2026-07-25T19:23:08.584728Z","end":"2026-07-25T19:23:08.74037Z","steps":["trace[1541109240] 'process raft request' (duration: 155.204854ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:23:08.740561Z","caller":"traceutil/trace.go:171","msg":"trace[779872296] transaction","detail":"{read_only:false; response_revision:4708; number_of_response:1; }","duration":"151.3116ms","start":"2026-07-25T19:23:08.589149Z","end":"2026-07-25T19:23:08.740461Z","steps":["trace[779872296] 'process raft request' (duration: 151.072384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:23:08.740681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.426366ms","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-07-25T19:23:08.740736Z","caller":"traceutil/trace.go:171","msg":"trace[631280311] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4709; }","duration":"122.528758ms","start":"2026-07-25T19:23:08.618195Z","end":"2026-07-25T19:23:08.740723Z","steps":["trace[631280311] 'agreement among raft nodes before linearized reading' (duration: 122.339814ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:23:08.740561Z","caller":"traceutil/trace.go:171","msg":"trace[1118392247] transaction","detail":"{read_only:false; response_revision:4709; number_of_response:1; }","duration":"149.462056ms","start":"2026-07-25T19:23:08.591032Z","end":"2026-07-25T19:23:08.740494Z","steps":["trace[1118392247] 'process raft request' (duration: 149.345023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:23:08.74096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.206756ms","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":"warn","ts":"2026-07-25T19:23:08.740897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.365805ms","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-07-25T19:23:08.74101Z","caller":"traceutil/trace.go:171","msg":"trace[176781494] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4709; }","duration":"121.279777ms","start":"2026-07-25T19:23:08.619719Z","end":"2026-07-25T19:23:08.740999Z","steps":["trace[176781494] 'agreement among raft nodes before linearized reading' (duration: 120.938199ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:23:08.741027Z","caller":"traceutil/trace.go:171","msg":"trace[968174824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4709; }","duration":"120.555479ms","start":"2026-07-25T19:23:08.620458Z","end":"2026-07-25T19:23:08.741014Z","steps":["trace[968174824] 'agreement among raft nodes before linearized reading' (duration: 120.195731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:23:08.741186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.862427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-25T19:23:08.741246Z","caller":"traceutil/trace.go:171","msg":"trace[1372804878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4709; }","duration":"120.97311ms","start":"2026-07-25T19:23:08.620259Z","end":"2026-07-25T19:23:08.741232Z","steps":["trace[1372804878] 'agreement among raft nodes before linearized reading' (duration: 120.550939ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:23:08.923556Z","caller":"traceutil/trace.go:171","msg":"trace[846378563] transaction","detail":"{read_only:false; response_revision:4710; number_of_response:1; }","duration":"177.337884ms","start":"2026-07-25T19:23:08.746149Z","end":"2026-07-25T19:23:08.923487Z","steps":["trace[846378563] 'process raft request' (duration: 165.372282ms)","trace[846378563] 'compare' (duration: 11.640354ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T19:23:09.078001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.6237ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-25T19:23:09.078431Z","caller":"traceutil/trace.go:171","msg":"trace[1499595823] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"330.152402ms","start":"2026-07-25T19:23:08.748257Z","end":"2026-07-25T19:23:09.078409Z","steps":["trace[1499595823] 'process raft request' (duration: 329.887976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:23:09.078671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:23:08.748235Z","time spent":"330.251964ms","remote":"127.0.0.1:59460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T19:23:09.07883Z","caller":"traceutil/trace.go:171","msg":"trace[102075767] transaction","detail":"{read_only:false; response_revision:4712; number_of_response:1; }","duration":"329.781291ms","start":"2026-07-25T19:23:08.749014Z","end":"2026-07-25T19:23:09.078795Z","steps":["trace[102075767] 'process raft request' (duration: 329.352011ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:23:09.078834Z","caller":"traceutil/trace.go:171","msg":"trace[1355371700] linearizableReadLoop","detail":"{readStateIndex:4891; appliedIndex:4886; }","duration":"320.031694ms","start":"2026-07-25T19:23:08.758763Z","end":"2026-07-25T19:23:09.078795Z","steps":["trace[1355371700] 'read index received' (duration: 152.842677ms)","trace[1355371700] 'applied index is now lower than readState.Index' (duration: 167.187097ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T19:23:09.078879Z","caller":"traceutil/trace.go:171","msg":"trace[702019596] transaction","detail":"{read_only:false; response_revision:4713; number_of_response:1; }","duration":"328.88099ms","start":"2026-07-25T19:23:08.749947Z","end":"2026-07-25T19:23:09.078828Z","steps":["trace[702019596] 'process raft request' (duration: 328.654715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:23:09.079012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:23:08.748995Z","time spent":"329.922135ms","remote":"127.0.0.1:59460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T19:23:09.079039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.23995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T19:23:09.07915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:23:08.749919Z","time spent":"329.092086ms","remote":"127.0.0.1:59460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T19:23:09.079165Z","caller":"traceutil/trace.go:171","msg":"trace[1146695959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4713; }","duration":"320.392954ms","start":"2026-07-25T19:23:08.758744Z","end":"2026-07-25T19:23:09.079137Z","steps":["trace[1146695959] 'agreement among raft nodes before linearized reading' (duration: 320.166688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:23:09.079233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T19:23:08.758734Z","time spent":"320.483996ms","remote":"127.0.0.1:59228","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-07-25T19:23:09.079225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.266498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-25T19:23:09.079305Z","caller":"traceutil/trace.go:171","msg":"trace[2130786230] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4713; }","duration":"276.398951ms","start":"2026-07-25T19:23:08.802889Z","end":"2026-07-25T19:23:09.079288Z","steps":["trace[2130786230] 'agreement among raft nodes before linearized reading' (duration: 276.164935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T19:23:09.079414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.301602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-25T19:23:09.079475Z","caller":"traceutil/trace.go:171","msg":"trace[1973798854] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:4713; }","duration":"190.492036ms","start":"2026-07-25T19:23:08.888969Z","end":"2026-07-25T19:23:09.079461Z","steps":["trace[1973798854] 'agreement among raft nodes before linearized reading' (duration: 190.088256ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:25:59.663873Z","caller":"traceutil/trace.go:171","msg":"trace[2089612737] transaction","detail":"{read_only:false; response_revision:5755; number_of_response:1; }","duration":"113.805296ms","start":"2026-07-25T19:25:59.550033Z","end":"2026-07-25T19:25:59.663838Z","steps":["trace[2089612737] 'process raft request' (duration: 113.626541ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:27:20.580872Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4309} {"level":"info","ts":"2026-07-25T19:27:20.626071Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4309,"took":"43.711419ms","hash":3281889698,"current-db-size-bytes":34566144,"current-db-size":"35 MB","current-db-size-in-use-bytes":12283904,"current-db-size-in-use":"12 MB"} {"level":"info","ts":"2026-07-25T19:27:20.626206Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3281889698,"revision":4309,"compact-revision":2597} {"level":"info","ts":"2026-07-25T19:29:18.420985Z","caller":"traceutil/trace.go:171","msg":"trace[925712233] transaction","detail":"{read_only:false; response_revision:7073; number_of_response:1; }","duration":"245.708215ms","start":"2026-07-25T19:29:18.175254Z","end":"2026-07-25T19:29:18.420963Z","steps":["trace[925712233] 'process raft request' (duration: 245.543861ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:29:18.425034Z","caller":"traceutil/trace.go:171","msg":"trace[1297222188] transaction","detail":"{read_only:false; response_revision:7074; number_of_response:1; }","duration":"231.720915ms","start":"2026-07-25T19:29:18.193282Z","end":"2026-07-25T19:29:18.425003Z","steps":["trace[1297222188] 'process raft request' (duration: 231.281185ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:29:18.425249Z","caller":"traceutil/trace.go:171","msg":"trace[1583676136] transaction","detail":"{read_only:false; response_revision:7075; number_of_response:1; }","duration":"230.056885ms","start":"2026-07-25T19:29:18.195162Z","end":"2026-07-25T19:29:18.425219Z","steps":["trace[1583676136] 'process raft request' (duration: 229.590623ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:29:56.708586Z","caller":"traceutil/trace.go:171","msg":"trace[1061423103] transaction","detail":"{read_only:false; response_revision:7471; number_of_response:1; }","duration":"146.188904ms","start":"2026-07-25T19:29:56.562362Z","end":"2026-07-25T19:29:56.708551Z","steps":["trace[1061423103] 'process raft request' (duration: 145.912067ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:29:56.776712Z","caller":"traceutil/trace.go:171","msg":"trace[1118479741] transaction","detail":"{read_only:false; response_revision:7472; number_of_response:1; }","duration":"201.745851ms","start":"2026-07-25T19:29:56.574931Z","end":"2026-07-25T19:29:56.776677Z","steps":["trace[1118479741] 'process raft request' (duration: 192.274699ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:29:56.777158Z","caller":"traceutil/trace.go:171","msg":"trace[1395196525] transaction","detail":"{read_only:false; response_revision:7474; number_of_response:1; }","duration":"185.799831ms","start":"2026-07-25T19:29:56.591346Z","end":"2026-07-25T19:29:56.777145Z","steps":["trace[1395196525] 'process raft request' (duration: 185.586006ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:29:56.777183Z","caller":"traceutil/trace.go:171","msg":"trace[1562170431] transaction","detail":"{read_only:false; response_revision:7473; number_of_response:1; }","duration":"200.592462ms","start":"2026-07-25T19:29:56.576559Z","end":"2026-07-25T19:29:56.777151Z","steps":["trace[1562170431] 'process raft request' (duration: 200.056499ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:29:56.777133Z","caller":"traceutil/trace.go:171","msg":"trace[714944543] linearizableReadLoop","detail":"{readStateIndex:7748; appliedIndex:7744; }","duration":"153.682466ms","start":"2026-07-25T19:29:56.62342Z","end":"2026-07-25T19:29:56.777102Z","steps":["trace[714944543] 'read index received' (duration: 84.888185ms)","trace[714944543] 'applied index is now lower than readState.Index' (duration: 68.792041ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T19:29:56.77739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.951582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-25T19:29:56.777461Z","caller":"traceutil/trace.go:171","msg":"trace[1701394653] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7474; }","duration":"154.109567ms","start":"2026-07-25T19:29:56.623333Z","end":"2026-07-25T19:29:56.777443Z","steps":["trace[1701394653] 'agreement among raft nodes before linearized reading' (duration: 153.861141ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T19:32:20.587481Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6302} {"level":"info","ts":"2026-07-25T19:32:20.635436Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6302,"took":"46.285805ms","hash":1855488668,"current-db-size-bytes":34566144,"current-db-size":"35 MB","current-db-size-in-use-bytes":15634432,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-07-25T19:32:20.635544Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1855488668,"revision":6302,"compact-revision":4309} {"level":"info","ts":"2026-07-25T19:35:54.268472Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-25T19:35:54.27449Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-25T19:35:54.27458Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-25T19:37:20.595678Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8444} {"level":"info","ts":"2026-07-25T19:37:20.657141Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8444,"took":"59.736331ms","hash":2319134280,"current-db-size-bytes":34566144,"current-db-size":"35 MB","current-db-size-in-use-bytes":11059200,"current-db-size-in-use":"11 MB"} {"level":"info","ts":"2026-07-25T19:37:20.657225Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2319134280,"revision":8444,"compact-revision":6302} {"level":"info","ts":"2026-07-25T19:42:20.60345Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10076} {"level":"info","ts":"2026-07-25T19:42:20.655478Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10076,"took":"50.333421ms","hash":1395372779,"current-db-size-bytes":34566144,"current-db-size":"35 MB","current-db-size-in-use-bytes":10276864,"current-db-size-in-use":"10 MB"} {"level":"info","ts":"2026-07-25T19:42:20.655567Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1395372779,"revision":10076,"compact-revision":8444}