{"level":"warn","ts":"2026-05-19T07:56:08.867689Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T07:56:08.867991Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-19T07:56:08.868208Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T07:56:08.868379Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-05-19T07:56:08.868495Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-19T07:56:08.869475Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-05-19T07:56:08.869673Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-19T07:56:08.873441Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.385901ms"} {"level":"info","ts":"2026-05-19T07:56:08.880601Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-05-19T07:56:08.880676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T07:56:08.880697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-05-19T07:56:08.880754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T07:56:08.880766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-05-19T07:56:08.880813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-05-19T07:56:08.888493Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T07:56:08.890066Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T07:56:08.891074Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-19T07:56:08.89285Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T07:56:08.893092Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T07:56:08.894419Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T07:56:08.894488Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T07:56:08.894708Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T07:56:08.895061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-05-19T07:56:08.895198Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-05-19T07:56:08.897355Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-19T07:56:08.897557Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T07:56:08.897592Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T07:56:08.897654Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-05-19T07:56:08.89767Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-05-19T07:56:09.389851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T07:56:09.389948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T07:56:09.390045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-05-19T07:56:09.390096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-05-19T07:56:09.390113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-05-19T07:56:09.390148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-05-19T07:56:09.390166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-05-19T07:56:09.391046Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T07:56:09.391129Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T07:56:09.391295Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T07:56:09.391275Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T07:56:09.395862Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-05-19T07:56:09.396025Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T07:56:09.396092Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T07:56:09.396213Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T07:56:09.39633Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T07:56:09.396404Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T07:56:09.397417Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-19T07:56:15.134781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.659828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T07:56:15.134861Z","caller":"traceutil/trace.go:171","msg":"trace[204740616] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:0; response_revision:262; }","duration":"220.805281ms","start":"2026-05-19T07:56:14.914041Z","end":"2026-05-19T07:56:15.134846Z","steps":["trace[204740616] 'range keys from in-memory index tree' (duration: 220.538336ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T07:56:15.305059Z","caller":"traceutil/trace.go:171","msg":"trace[55917534] transaction","detail":"{read_only:false; number_of_response:0; response_revision:262; }","duration":"260.567149ms","start":"2026-05-19T07:56:15.044472Z","end":"2026-05-19T07:56:15.305039Z","steps":["trace[55917534] 'process raft request' (duration: 260.504668ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T07:56:15.315003Z","caller":"traceutil/trace.go:171","msg":"trace[503182084] linearizableReadLoop","detail":"{readStateIndex:273; appliedIndex:272; }","duration":"200.85231ms","start":"2026-05-19T07:56:15.114131Z","end":"2026-05-19T07:56:15.314984Z","steps":["trace[503182084] 'read index received' (duration: 190.901464ms)","trace[503182084] 'applied index is now lower than readState.Index' (duration: 9.950176ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T07:56:15.315081Z","caller":"traceutil/trace.go:171","msg":"trace[647542732] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"163.645584ms","start":"2026-05-19T07:56:15.151412Z","end":"2026-05-19T07:56:15.315057Z","steps":["trace[647542732] 'process raft request' (duration: 163.500692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T07:56:15.315143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.98542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-19T07:56:15.315163Z","caller":"traceutil/trace.go:171","msg":"trace[2097480509] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:263; }","duration":"178.021731ms","start":"2026-05-19T07:56:15.137133Z","end":"2026-05-19T07:56:15.315155Z","steps":["trace[2097480509] 'agreement among raft nodes before linearized reading' (duration: 177.99171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T07:56:15.315105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.958212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T07:56:15.315241Z","caller":"traceutil/trace.go:171","msg":"trace[352650041] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:263; }","duration":"201.124644ms","start":"2026-05-19T07:56:15.114083Z","end":"2026-05-19T07:56:15.315208Z","steps":["trace[352650041] 'agreement among raft nodes before linearized reading' (duration: 200.979662ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T07:56:15.315449Z","caller":"traceutil/trace.go:171","msg":"trace[356501392] transaction","detail":"{read_only:false; number_of_response:0; response_revision:262; }","duration":"270.78723ms","start":"2026-05-19T07:56:15.044636Z","end":"2026-05-19T07:56:15.315423Z","steps":["trace[356501392] 'process raft request' (duration: 270.213061ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T07:56:41.899531Z","caller":"traceutil/trace.go:171","msg":"trace[1020414497] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"143.856052ms","start":"2026-05-19T07:56:41.755656Z","end":"2026-05-19T07:56:41.899512Z","steps":["trace[1020414497] 'process raft request' (duration: 143.762552ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T07:56:41.899534Z","caller":"traceutil/trace.go:171","msg":"trace[1714382691] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"144.109275ms","start":"2026-05-19T07:56:41.755401Z","end":"2026-05-19T07:56:41.89951Z","steps":["trace[1714382691] 'process raft request' (duration: 96.648436ms)","trace[1714382691] 'compare' (duration: 47.152116ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T07:56:45.969627Z","caller":"traceutil/trace.go:171","msg":"trace[1052177286] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"345.715287ms","start":"2026-05-19T07:56:45.623893Z","end":"2026-05-19T07:56:45.969608Z","steps":["trace[1052177286] 'process raft request' (duration: 345.292993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T07:56:45.970069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T07:56:45.623864Z","time spent":"345.840978ms","remote":"127.0.0.1:59334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T07:56:46.62553Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265933860156630,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T07:56:46.639501Z","caller":"traceutil/trace.go:171","msg":"trace[166809802] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"514.983467ms","start":"2026-05-19T07:56:46.124473Z","end":"2026-05-19T07:56:46.639456Z","steps":["trace[166809802] 'read index received' (duration: 514.783195ms)","trace[166809802] 'applied index is now lower than readState.Index' (duration: 199.082µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T07:56:46.63952Z","caller":"traceutil/trace.go:171","msg":"trace[300558566] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"559.830663ms","start":"2026-05-19T07:56:46.07966Z","end":"2026-05-19T07:56:46.639491Z","steps":["trace[300558566] 'process raft request' (duration: 559.654821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T07:56:46.63978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T07:56:46.079638Z","time spent":"559.967144ms","remote":"127.0.0.1:59430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T07:56:46.639826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.29645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vp2sc\" ","response":"range_response_count:1 size:16957"} {"level":"warn","ts":"2026-05-19T07:56:46.639821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.244154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T07:56:46.639874Z","caller":"traceutil/trace.go:171","msg":"trace[491005512] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vp2sc; range_end:; response_count:1; response_revision:580; }","duration":"515.430042ms","start":"2026-05-19T07:56:46.12443Z","end":"2026-05-19T07:56:46.63986Z","steps":["trace[491005512] 'agreement among raft nodes before linearized reading' (duration: 515.1979ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T07:56:46.639931Z","caller":"traceutil/trace.go:171","msg":"trace[984643809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"345.400076ms","start":"2026-05-19T07:56:46.294503Z","end":"2026-05-19T07:56:46.639903Z","steps":["trace[984643809] 'agreement among raft nodes before linearized reading' (duration: 345.209644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T07:56:46.639962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.68525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-19T07:56:46.640058Z","caller":"traceutil/trace.go:171","msg":"trace[864099854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"328.830311ms","start":"2026-05-19T07:56:46.311205Z","end":"2026-05-19T07:56:46.640036Z","steps":["trace[864099854] 'agreement among raft nodes before linearized reading' (duration: 328.554868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T07:56:46.640118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T07:56:46.311182Z","time spent":"328.926572ms","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T07:56:46.639994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T07:56:46.294482Z","time spent":"345.500497ms","remote":"127.0.0.1:59234","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-05-19T07:56:46.639956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T07:56:46.124409Z","time spent":"515.533813ms","remote":"127.0.0.1:59430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16980,"request content":"key:\"/registry/pods/kube-system/cilium-vp2sc\" "} {"level":"warn","ts":"2026-05-19T07:56:46.63993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.823019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-19T07:56:46.640288Z","caller":"traceutil/trace.go:171","msg":"trace[96002228] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"237.212302ms","start":"2026-05-19T07:56:46.403052Z","end":"2026-05-19T07:56:46.640264Z","steps":["trace[96002228] 'agreement among raft nodes before linearized reading' (duration: 236.664397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T07:57:01.391184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.809295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-q6mjf\" ","response":"range_response_count:1 size:3963"} {"level":"info","ts":"2026-05-19T07:57:01.39127Z","caller":"traceutil/trace.go:171","msg":"trace[1446816025] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-q6mjf; range_end:; response_count:1; response_revision:733; }","duration":"173.931326ms","start":"2026-05-19T07:57:01.217321Z","end":"2026-05-19T07:57:01.391252Z","steps":["trace[1446816025] 'range keys from in-memory index tree' (duration: 173.593723ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T07:57:34.411851Z","caller":"traceutil/trace.go:171","msg":"trace[976500240] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"168.634837ms","start":"2026-05-19T07:57:34.243187Z","end":"2026-05-19T07:57:34.411822Z","steps":["trace[976500240] 'process raft request' (duration: 155.010424ms)","trace[976500240] 'compare' (duration: 13.452734ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T07:58:38.029045Z","caller":"traceutil/trace.go:171","msg":"trace[233358186] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"227.584671ms","start":"2026-05-19T07:58:37.801435Z","end":"2026-05-19T07:58:38.02902Z","steps":["trace[233358186] 'process raft request' (duration: 227.367665ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T07:58:56.627894Z","caller":"traceutil/trace.go:171","msg":"trace[1266769046] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"123.880098ms","start":"2026-05-19T07:58:56.503994Z","end":"2026-05-19T07:58:56.627874Z","steps":["trace[1266769046] 'process raft request' (duration: 123.743825ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T07:58:56.633199Z","caller":"traceutil/trace.go:171","msg":"trace[687034171] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"120.114686ms","start":"2026-05-19T07:58:56.513071Z","end":"2026-05-19T07:58:56.633185Z","steps":["trace[687034171] 'process raft request' (duration: 120.030974ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T07:58:57.018237Z","caller":"traceutil/trace.go:171","msg":"trace[1459183632] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"126.368185ms","start":"2026-05-19T07:58:56.891845Z","end":"2026-05-19T07:58:57.018213Z","steps":["trace[1459183632] 'process raft request' (duration: 126.235882ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T07:58:57.041342Z","caller":"traceutil/trace.go:171","msg":"trace[1008462617] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"132.192663ms","start":"2026-05-19T07:58:56.909111Z","end":"2026-05-19T07:58:57.041304Z","steps":["trace[1008462617] 'process raft request' (duration: 131.981708ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T07:58:57.466943Z","caller":"traceutil/trace.go:171","msg":"trace[1261282268] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"101.698738ms","start":"2026-05-19T07:58:57.365204Z","end":"2026-05-19T07:58:57.466903Z","steps":["trace[1261282268] 'process raft request' (duration: 95.96666ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T07:58:57.478954Z","caller":"traceutil/trace.go:171","msg":"trace[590191821] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"113.546975ms","start":"2026-05-19T07:58:57.365371Z","end":"2026-05-19T07:58:57.478918Z","steps":["trace[590191821] 'process raft request' (duration: 113.375371ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T08:00:10.752924Z","caller":"traceutil/trace.go:171","msg":"trace[566897721] linearizableReadLoop","detail":"{readStateIndex:2679; appliedIndex:2678; }","duration":"312.415529ms","start":"2026-05-19T08:00:10.440487Z","end":"2026-05-19T08:00:10.752902Z","steps":["trace[566897721] 'read index received' (duration: 312.218486ms)","trace[566897721] 'applied index is now lower than readState.Index' (duration: 196.153µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T08:00:10.753232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.522662ms","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-05-19T08:00:10.75324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.726983ms","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-05-19T08:00:10.753312Z","caller":"traceutil/trace.go:171","msg":"trace[2010227938] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2581; }","duration":"199.652893ms","start":"2026-05-19T08:00:10.553647Z","end":"2026-05-19T08:00:10.7533Z","steps":["trace[2010227938] 'agreement among raft nodes before linearized reading' (duration: 199.46073ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T08:00:10.753354Z","caller":"traceutil/trace.go:171","msg":"trace[690594444] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2581; }","duration":"312.900517ms","start":"2026-05-19T08:00:10.440429Z","end":"2026-05-19T08:00:10.753329Z","steps":["trace[690594444] 'agreement among raft nodes before linearized reading' (duration: 312.567191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:00:10.753648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:00:10.440403Z","time spent":"313.063219ms","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-05-19T08:00:10.752983Z","caller":"traceutil/trace.go:171","msg":"trace[1132966844] transaction","detail":"{read_only:false; response_revision:2581; number_of_response:1; }","duration":"348.182305ms","start":"2026-05-19T08:00:10.404774Z","end":"2026-05-19T08:00:10.752956Z","steps":["trace[1132966844] 'process raft request' (duration: 347.979471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:00:10.754074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:00:10.404735Z","time spent":"349.15759ms","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T08:00:12.294829Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265933860160878,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T08:00:12.794977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265933860160878,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T08:00:13.209234Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.480723127s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T08:00:13.20962Z","caller":"traceutil/trace.go:171","msg":"trace[342533542] transaction","detail":"{read_only:false; response_revision:2587; number_of_response:1; }","duration":"1.481175593s","start":"2026-05-19T08:00:11.728424Z","end":"2026-05-19T08:00:13.209599Z","steps":["trace[342533542] 'process raft request' (duration: 1.481057192s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:00:13.209772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:00:11.728404Z","time spent":"1.481256464s","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T08:00:13.210889Z","caller":"traceutil/trace.go:171","msg":"trace[241646250] transaction","detail":"{read_only:false; response_revision:2588; number_of_response:1; }","duration":"1.341488813s","start":"2026-05-19T08:00:11.869376Z","end":"2026-05-19T08:00:13.210865Z","steps":["trace[241646250] 'process raft request' (duration: 1.341179188s)"],"step_count":1} {"level":"info","ts":"2026-05-19T08:00:13.211041Z","caller":"traceutil/trace.go:171","msg":"trace[1111286153] transaction","detail":"{read_only:false; response_revision:2589; number_of_response:1; }","duration":"450.539088ms","start":"2026-05-19T08:00:12.760486Z","end":"2026-05-19T08:00:13.211025Z","steps":["trace[1111286153] 'process raft request' (duration: 450.190843ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T08:00:13.211054Z","caller":"traceutil/trace.go:171","msg":"trace[1858041259] linearizableReadLoop","detail":"{readStateIndex:2687; appliedIndex:2685; }","duration":"1.416447745s","start":"2026-05-19T08:00:11.794592Z","end":"2026-05-19T08:00:13.21104Z","steps":["trace[1858041259] 'read index received' (duration: 1.415055593s)","trace[1858041259] 'applied index is now lower than readState.Index' (duration: 1.391272ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T08:00:13.211121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:00:12.760467Z","time spent":"450.619159ms","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T08:00:13.21123Z","caller":"traceutil/trace.go:171","msg":"trace[1072161500] transaction","detail":"{read_only:false; response_revision:2591; number_of_response:1; }","duration":"318.869333ms","start":"2026-05-19T08:00:12.892353Z","end":"2026-05-19T08:00:13.211223Z","steps":["trace[1072161500] 'process raft request' (duration: 318.618559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:00:13.211287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:00:12.892325Z","time spent":"318.941395ms","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T08:00:13.211368Z","caller":"traceutil/trace.go:171","msg":"trace[510127818] transaction","detail":"{read_only:false; response_revision:2590; number_of_response:1; }","duration":"331.203101ms","start":"2026-05-19T08:00:12.880156Z","end":"2026-05-19T08:00:13.211359Z","steps":["trace[510127818] 'process raft request' (duration: 330.767284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:00:13.211428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:00:12.88013Z","time spent":"331.277381ms","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T08:00:13.211405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:00:11.869348Z","time spent":"1.341644856s","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T08:00:13.211726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.363402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T08:00:13.211703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.417069055s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-19T08:00:13.211779Z","caller":"traceutil/trace.go:171","msg":"trace[652834307] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2591; }","duration":"595.461524ms","start":"2026-05-19T08:00:12.616309Z","end":"2026-05-19T08:00:13.211771Z","steps":["trace[652834307] 'agreement among raft nodes before linearized reading' (duration: 595.385663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:00:13.211835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:00:12.616285Z","time spent":"595.542435ms","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-05-19T08:00:13.211842Z","caller":"traceutil/trace.go:171","msg":"trace[908815295] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2591; }","duration":"1.417237288s","start":"2026-05-19T08:00:11.794561Z","end":"2026-05-19T08:00:13.211798Z","steps":["trace[908815295] 'agreement among raft nodes before linearized reading' (duration: 1.416964453s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:00:13.211911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:00:11.794546Z","time spent":"1.417348169s","remote":"127.0.0.1:59494","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":"warn","ts":"2026-05-19T08:00:13.212184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.222322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"warn","ts":"2026-05-19T08:00:13.212275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.764675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-19T08:00:13.212315Z","caller":"traceutil/trace.go:171","msg":"trace[1044044272] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2591; }","duration":"449.824507ms","start":"2026-05-19T08:00:12.762484Z","end":"2026-05-19T08:00:13.212308Z","steps":["trace[1044044272] 'agreement among raft nodes before linearized reading' (duration: 449.716365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:00:13.21235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:00:12.762475Z","time spent":"449.867547ms","remote":"127.0.0.1:59494","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":"info","ts":"2026-05-19T08:00:13.212301Z","caller":"traceutil/trace.go:171","msg":"trace[1296082400] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:2591; }","duration":"714.291922ms","start":"2026-05-19T08:00:12.497922Z","end":"2026-05-19T08:00:13.212214Z","steps":["trace[1296082400] 'agreement among raft nodes before linearized reading' (duration: 713.867525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:00:13.212476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:00:12.497904Z","time spent":"714.564456ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"warn","ts":"2026-05-19T08:00:13.212695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.948635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T08:00:13.212744Z","caller":"traceutil/trace.go:171","msg":"trace[956545402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2591; }","duration":"921.019306ms","start":"2026-05-19T08:00:12.291716Z","end":"2026-05-19T08:00:13.212735Z","steps":["trace[956545402] 'agreement among raft nodes before linearized reading' (duration: 920.958685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:00:13.212777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:00:12.291702Z","time spent":"921.068737ms","remote":"127.0.0.1:59232","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-05-19T08:00:13.212751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.34445ms","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-05-19T08:00:13.212883Z","caller":"traceutil/trace.go:171","msg":"trace[513002172] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2591; }","duration":"772.537443ms","start":"2026-05-19T08:00:12.440336Z","end":"2026-05-19T08:00:13.212873Z","steps":["trace[513002172] 'agreement among raft nodes before linearized reading' (duration: 772.040245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:00:13.212942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:00:12.440319Z","time spent":"772.612625ms","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-19T08:00:13.211747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.411036109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-19T08:00:13.213357Z","caller":"traceutil/trace.go:171","msg":"trace[402278057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2591; }","duration":"1.412509042s","start":"2026-05-19T08:00:11.800682Z","end":"2026-05-19T08:00:13.213191Z","steps":["trace[402278057] 'agreement among raft nodes before linearized reading' (duration: 1.410973007s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:00:13.213474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:00:11.800675Z","time spent":"1.412728385s","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T08:00:25.435596Z","caller":"traceutil/trace.go:171","msg":"trace[881601000] transaction","detail":"{read_only:false; response_revision:2653; number_of_response:1; }","duration":"168.787174ms","start":"2026-05-19T08:00:25.266771Z","end":"2026-05-19T08:00:25.435558Z","steps":["trace[881601000] 'process raft request' (duration: 155.538941ms)","trace[881601000] 'compare' (duration: 13.093531ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T08:00:25.435978Z","caller":"traceutil/trace.go:171","msg":"trace[755386674] transaction","detail":"{read_only:false; response_revision:2654; number_of_response:1; }","duration":"168.457569ms","start":"2026-05-19T08:00:25.267492Z","end":"2026-05-19T08:00:25.43595Z","steps":["trace[755386674] 'process raft request' (duration: 168.022543ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T08:00:25.436276Z","caller":"traceutil/trace.go:171","msg":"trace[288418388] linearizableReadLoop","detail":"{readStateIndex:2757; appliedIndex:2753; }","duration":"166.475349ms","start":"2026-05-19T08:00:25.269757Z","end":"2026-05-19T08:00:25.436232Z","steps":["trace[288418388] 'read index received' (duration: 48.32524ms)","trace[288418388] 'applied index is now lower than readState.Index' (duration: 118.149169ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T08:00:25.436349Z","caller":"traceutil/trace.go:171","msg":"trace[737206421] transaction","detail":"{read_only:false; response_revision:2655; number_of_response:1; }","duration":"168.453899ms","start":"2026-05-19T08:00:25.267878Z","end":"2026-05-19T08:00:25.436332Z","steps":["trace[737206421] 'process raft request' (duration: 167.808769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:00:25.436713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.935756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-19T08:00:25.436763Z","caller":"traceutil/trace.go:171","msg":"trace[550509535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2655; }","duration":"167.049438ms","start":"2026-05-19T08:00:25.2697Z","end":"2026-05-19T08:00:25.43675Z","steps":["trace[550509535] 'agreement among raft nodes before linearized reading' (duration: 166.778093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:00:25.437207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.931466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T08:00:25.437268Z","caller":"traceutil/trace.go:171","msg":"trace[1351963813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2655; }","duration":"154.040798ms","start":"2026-05-19T08:00:25.283215Z","end":"2026-05-19T08:00:25.437256Z","steps":["trace[1351963813] 'agreement among raft nodes before linearized reading' (duration: 153.849495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:00:25.437472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.563055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-19T08:00:25.437498Z","caller":"traceutil/trace.go:171","msg":"trace[1757815991] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2655; }","duration":"152.599696ms","start":"2026-05-19T08:00:25.284892Z","end":"2026-05-19T08:00:25.437492Z","steps":["trace[1757815991] 'agreement among raft nodes before linearized reading' (duration: 152.516065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:00:25.43758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.16636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-19T08:00:25.437601Z","caller":"traceutil/trace.go:171","msg":"trace[1902040499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2655; }","duration":"154.226011ms","start":"2026-05-19T08:00:25.28337Z","end":"2026-05-19T08:00:25.437596Z","steps":["trace[1902040499] 'agreement among raft nodes before linearized reading' (duration: 154.17292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:00:25.437229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.057361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T08:00:25.437737Z","caller":"traceutil/trace.go:171","msg":"trace[359992397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2655; }","duration":"145.527068ms","start":"2026-05-19T08:00:25.292149Z","end":"2026-05-19T08:00:25.437676Z","steps":["trace[359992397] 'agreement among raft nodes before linearized reading' (duration: 145.04794ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T08:05:07.01574Z","caller":"traceutil/trace.go:171","msg":"trace[1649322144] transaction","detail":"{read_only:false; response_revision:5147; number_of_response:1; }","duration":"587.683101ms","start":"2026-05-19T08:05:06.428035Z","end":"2026-05-19T08:05:07.015718Z","steps":["trace[1649322144] 'process raft request' (duration: 587.528148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:07.015881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:06.428008Z","time spent":"587.799574ms","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T08:05:07.025539Z","caller":"traceutil/trace.go:171","msg":"trace[1525193202] transaction","detail":"{read_only:false; response_revision:5148; number_of_response:1; }","duration":"397.876916ms","start":"2026-05-19T08:05:06.627618Z","end":"2026-05-19T08:05:07.025495Z","steps":["trace[1525193202] 'process raft request' (duration: 397.677582ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T08:05:07.025576Z","caller":"traceutil/trace.go:171","msg":"trace[1797744102] linearizableReadLoop","detail":"{readStateIndex:5340; appliedIndex:5338; }","duration":"360.567078ms","start":"2026-05-19T08:05:06.664985Z","end":"2026-05-19T08:05:07.025552Z","steps":["trace[1797744102] 'read index received' (duration: 350.639284ms)","trace[1797744102] 'applied index is now lower than readState.Index' (duration: 9.926194ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T08:05:07.025754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:06.627602Z","time spent":"398.012268ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6069,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T08:05:07.025875Z","caller":"traceutil/trace.go:171","msg":"trace[55421155] transaction","detail":"{read_only:false; response_revision:5151; number_of_response:1; }","duration":"302.79966ms","start":"2026-05-19T08:05:06.723064Z","end":"2026-05-19T08:05:07.025863Z","steps":["trace[55421155] 'process raft request' (duration: 302.437232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:07.025926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:06.723049Z","time spent":"302.85107ms","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T08:05:07.025998Z","caller":"traceutil/trace.go:171","msg":"trace[801784616] transaction","detail":"{read_only:false; response_revision:5149; number_of_response:1; }","duration":"314.366997ms","start":"2026-05-19T08:05:06.711623Z","end":"2026-05-19T08:05:07.025991Z","steps":["trace[801784616] 'process raft request' (duration: 313.784655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:07.026001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.605959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T08:05:07.026031Z","caller":"traceutil/trace.go:171","msg":"trace[1028487897] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5151; }","duration":"360.685651ms","start":"2026-05-19T08:05:06.665336Z","end":"2026-05-19T08:05:07.026022Z","steps":["trace[1028487897] 'agreement among raft nodes before linearized reading' (duration: 360.63673ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T08:05:07.026061Z","caller":"traceutil/trace.go:171","msg":"trace[1909873937] transaction","detail":"{read_only:false; response_revision:5150; number_of_response:1; }","duration":"313.728365ms","start":"2026-05-19T08:05:06.712326Z","end":"2026-05-19T08:05:07.026054Z","steps":["trace[1909873937] 'process raft request' (duration: 313.134152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:07.026061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:06.665314Z","time spent":"360.741662ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"warn","ts":"2026-05-19T08:05:07.026094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:06.712313Z","time spent":"313.764345ms","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T08:05:07.026146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.885716ms","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-05-19T08:05:07.026177Z","caller":"traceutil/trace.go:171","msg":"trace[1115964863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5151; }","duration":"102.932117ms","start":"2026-05-19T08:05:06.923238Z","end":"2026-05-19T08:05:07.02617Z","steps":["trace[1115964863] 'agreement among raft nodes before linearized reading' (duration: 102.842685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:07.026214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.77426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T08:05:07.026246Z","caller":"traceutil/trace.go:171","msg":"trace[866204363] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5151; }","duration":"198.8395ms","start":"2026-05-19T08:05:06.827397Z","end":"2026-05-19T08:05:07.026237Z","steps":["trace[866204363] 'agreement among raft nodes before linearized reading' (duration: 198.757199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:07.026254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.640511ms","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-05-19T08:05:07.02626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.275213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T08:05:07.026273Z","caller":"traceutil/trace.go:171","msg":"trace[1104766626] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5151; }","duration":"101.669621ms","start":"2026-05-19T08:05:06.924599Z","end":"2026-05-19T08:05:07.026268Z","steps":["trace[1104766626] 'agreement among raft nodes before linearized reading' (duration: 101.61863ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T08:05:07.026296Z","caller":"traceutil/trace.go:171","msg":"trace[1178665791] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5151; }","duration":"361.338744ms","start":"2026-05-19T08:05:06.664948Z","end":"2026-05-19T08:05:07.026287Z","steps":["trace[1178665791] 'agreement among raft nodes before linearized reading' (duration: 361.287913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:07.026323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.571061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6084"} {"level":"warn","ts":"2026-05-19T08:05:07.026324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:06.664935Z","time spent":"361.382605ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"info","ts":"2026-05-19T08:05:07.026339Z","caller":"traceutil/trace.go:171","msg":"trace[675441739] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5151; }","duration":"347.611791ms","start":"2026-05-19T08:05:06.678723Z","end":"2026-05-19T08:05:07.026334Z","steps":["trace[675441739] 'agreement among raft nodes before linearized reading' (duration: 347.586181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:07.026351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.01756ms","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-05-19T08:05:07.02638Z","caller":"traceutil/trace.go:171","msg":"trace[1284980815] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5151; }","duration":"210.092332ms","start":"2026-05-19T08:05:06.816281Z","end":"2026-05-19T08:05:07.026373Z","steps":["trace[1284980815] 'agreement among raft nodes before linearized reading' (duration: 210.024851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:07.026382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.545865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T08:05:07.026408Z","caller":"traceutil/trace.go:171","msg":"trace[262374552] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:5151; }","duration":"303.607176ms","start":"2026-05-19T08:05:06.722796Z","end":"2026-05-19T08:05:07.026403Z","steps":["trace[262374552] 'agreement among raft nodes before linearized reading' (duration: 303.570055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:07.026034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:06.711607Z","time spent":"314.406899ms","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T08:05:07.026424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:06.722777Z","time spent":"303.643077ms","remote":"127.0.0.1:32850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":1,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true "} {"level":"warn","ts":"2026-05-19T08:05:07.026355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:06.678711Z","time spent":"347.640643ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6107,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"warn","ts":"2026-05-19T08:05:09.840095Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265933860167152,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T08:05:10.252978Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.208993074s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T08:05:10.253424Z","caller":"traceutil/trace.go:171","msg":"trace[511684005] transaction","detail":"{read_only:false; response_revision:5160; number_of_response:1; }","duration":"2.209618597s","start":"2026-05-19T08:05:08.043777Z","end":"2026-05-19T08:05:10.253395Z","steps":["trace[511684005] 'process raft request' (duration: 2.209416963s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:10.253689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:08.043754Z","time spent":"2.209851702s","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6172,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T08:05:10.276163Z","caller":"traceutil/trace.go:171","msg":"trace[1114496898] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"935.614179ms","start":"2026-05-19T08:05:09.340534Z","end":"2026-05-19T08:05:10.276148Z","steps":["trace[1114496898] 'process raft request' (duration: 935.502606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:10.276258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:09.340524Z","time spent":"935.68218ms","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T08:05:10.276563Z","caller":"traceutil/trace.go:171","msg":"trace[514283731] transaction","detail":"{read_only:false; response_revision:5162; number_of_response:1; }","duration":"935.902145ms","start":"2026-05-19T08:05:09.340645Z","end":"2026-05-19T08:05:10.276547Z","steps":["trace[514283731] 'process raft request' (duration: 935.474936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:10.276642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:09.340641Z","time spent":"935.957106ms","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T08:05:10.276754Z","caller":"traceutil/trace.go:171","msg":"trace[1141740336] transaction","detail":"{read_only:false; response_revision:5164; number_of_response:1; }","duration":"936.045098ms","start":"2026-05-19T08:05:09.340699Z","end":"2026-05-19T08:05:10.276744Z","steps":["trace[1141740336] 'process raft request' (duration: 935.841244ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T08:05:10.276629Z","caller":"traceutil/trace.go:171","msg":"trace[2052226415] linearizableReadLoop","detail":"{readStateIndex:5358; appliedIndex:5352; }","duration":"936.598219ms","start":"2026-05-19T08:05:09.340014Z","end":"2026-05-19T08:05:10.276612Z","steps":["trace[2052226415] 'read index received' (duration: 913.16216ms)","trace[2052226415] 'applied index is now lower than readState.Index' (duration: 23.434869ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T08:05:10.276882Z","caller":"traceutil/trace.go:171","msg":"trace[1604072230] transaction","detail":"{read_only:false; response_revision:5165; number_of_response:1; }","duration":"933.073546ms","start":"2026-05-19T08:05:09.343799Z","end":"2026-05-19T08:05:10.276872Z","steps":["trace[1604072230] 'process raft request' (duration: 932.77946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:10.276941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:09.343792Z","time spent":"933.119767ms","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T08:05:10.276712Z","caller":"traceutil/trace.go:171","msg":"trace[698178560] transaction","detail":"{read_only:false; response_revision:5163; number_of_response:1; }","duration":"936.015377ms","start":"2026-05-19T08:05:09.340672Z","end":"2026-05-19T08:05:10.276687Z","steps":["trace[698178560] 'process raft request' (duration: 935.812252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:10.276809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:09.340695Z","time spent":"936.086298ms","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T08:05:10.277123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.103189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T08:05:10.277139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:09.340669Z","time spent":"936.348903ms","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T08:05:10.277177Z","caller":"traceutil/trace.go:171","msg":"trace[1725049582] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:5165; }","duration":"937.18966ms","start":"2026-05-19T08:05:09.339977Z","end":"2026-05-19T08:05:10.277167Z","steps":["trace[1725049582] 'agreement among raft nodes before linearized reading' (duration: 937.112539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:10.277208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:09.339957Z","time spent":"937.241712ms","remote":"127.0.0.1:32866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"warn","ts":"2026-05-19T08:05:10.287781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.628144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6187"} {"level":"info","ts":"2026-05-19T08:05:10.287845Z","caller":"traceutil/trace.go:171","msg":"trace[969859430] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5166; }","duration":"946.696125ms","start":"2026-05-19T08:05:09.341133Z","end":"2026-05-19T08:05:10.287829Z","steps":["trace[969859430] 'agreement among raft nodes before linearized reading' (duration: 946.589233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:10.287876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:09.34113Z","time spent":"946.738756ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6210,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"warn","ts":"2026-05-19T08:05:10.287883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.755996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T08:05:10.287916Z","caller":"traceutil/trace.go:171","msg":"trace[199795175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5166; }","duration":"946.791327ms","start":"2026-05-19T08:05:09.341112Z","end":"2026-05-19T08:05:10.287903Z","steps":["trace[199795175] 'agreement among raft nodes before linearized reading' (duration: 946.714125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:10.287952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:09.341106Z","time spent":"946.838727ms","remote":"127.0.0.1:59494","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":"warn","ts":"2026-05-19T08:05:10.288054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.869959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T08:05:10.288091Z","caller":"traceutil/trace.go:171","msg":"trace[1291476994] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5166; }","duration":"946.901059ms","start":"2026-05-19T08:05:09.341172Z","end":"2026-05-19T08:05:10.288073Z","steps":["trace[1291476994] 'agreement among raft nodes before linearized reading' (duration: 946.863808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:10.288122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:09.34117Z","time spent":"946.94835ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"warn","ts":"2026-05-19T08:05:10.287778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.553083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T08:05:10.288177Z","caller":"traceutil/trace.go:171","msg":"trace[1578332018] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5166; }","duration":"946.982821ms","start":"2026-05-19T08:05:09.341184Z","end":"2026-05-19T08:05:10.288167Z","steps":["trace[1578332018] 'agreement among raft nodes before linearized reading' (duration: 946.523782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:10.288217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:09.341182Z","time spent":"947.025332ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-05-19T08:05:10.288299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.589563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-19T08:05:10.288333Z","caller":"traceutil/trace.go:171","msg":"trace[1392730298] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5166; }","duration":"859.641234ms","start":"2026-05-19T08:05:09.428682Z","end":"2026-05-19T08:05:10.288323Z","steps":["trace[1392730298] 'agreement among raft nodes before linearized reading' (duration: 859.549392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:10.288339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.167834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-19T08:05:10.288353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:09.428676Z","time spent":"859.672375ms","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-05-19T08:05:10.288368Z","caller":"traceutil/trace.go:171","msg":"trace[1787912525] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5166; }","duration":"947.202275ms","start":"2026-05-19T08:05:09.341157Z","end":"2026-05-19T08:05:10.288359Z","steps":["trace[1787912525] 'agreement among raft nodes before linearized reading' (duration: 947.119403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:10.288405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:09.341154Z","time spent":"947.243546ms","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T08:05:10.287799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.001211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T08:05:10.288481Z","caller":"traceutil/trace.go:171","msg":"trace[1675440502] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:5166; }","duration":"947.703795ms","start":"2026-05-19T08:05:09.340768Z","end":"2026-05-19T08:05:10.288472Z","steps":["trace[1675440502] 'agreement among raft nodes before linearized reading' (duration: 946.97807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:10.288514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:09.340765Z","time spent":"947.739196ms","remote":"127.0.0.1:40310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T08:05:10.288538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.145234ms","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-05-19T08:05:10.288567Z","caller":"traceutil/trace.go:171","msg":"trace[1975609045] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5166; }","duration":"861.201726ms","start":"2026-05-19T08:05:09.427358Z","end":"2026-05-19T08:05:10.28856Z","steps":["trace[1975609045] 'agreement among raft nodes before linearized reading' (duration: 861.113054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:10.288586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:09.427342Z","time spent":"861.239226ms","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T08:05:10.288731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.119004ms","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-05-19T08:05:10.288773Z","caller":"traceutil/trace.go:171","msg":"trace[992281065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5166; }","duration":"948.159935ms","start":"2026-05-19T08:05:09.340603Z","end":"2026-05-19T08:05:10.288763Z","steps":["trace[992281065] 'agreement among raft nodes before linearized reading' (duration: 948.061383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:10.288807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:09.340591Z","time spent":"948.210026ms","remote":"127.0.0.1:59494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T08:05:10.289074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.9162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-19T08:05:10.289116Z","caller":"traceutil/trace.go:171","msg":"trace[1761641383] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5166; }","duration":"948.966201ms","start":"2026-05-19T08:05:09.34014Z","end":"2026-05-19T08:05:10.289106Z","steps":["trace[1761641383] 'agreement among raft nodes before linearized reading' (duration: 947.531802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T08:05:10.289145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T08:05:09.340137Z","time spent":"949.002162ms","remote":"127.0.0.1:59430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-19T08:06:09.556242Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2885} {"level":"info","ts":"2026-05-19T08:06:09.643383Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2885,"took":"85.61147ms","hash":2637852422,"current-db-size-bytes":54239232,"current-db-size":"54 MB","current-db-size-in-use-bytes":29163520,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-19T08:06:09.64348Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2637852422,"revision":2885,"compact-revision":-1} {"level":"info","ts":"2026-05-19T08:08:49.544325Z","caller":"traceutil/trace.go:171","msg":"trace[1009474386] transaction","detail":"{read_only:false; response_revision:6667; number_of_response:1; }","duration":"102.448523ms","start":"2026-05-19T08:08:49.44183Z","end":"2026-05-19T08:08:49.544279Z","steps":["trace[1009474386] 'process raft request' (duration: 102.216419ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T08:08:49.544349Z","caller":"traceutil/trace.go:171","msg":"trace[795464298] transaction","detail":"{read_only:false; response_revision:6668; number_of_response:1; }","duration":"101.438627ms","start":"2026-05-19T08:08:49.442864Z","end":"2026-05-19T08:08:49.544302Z","steps":["trace[795464298] 'process raft request' (duration: 101.334155ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T08:11:09.561781Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5571} {"level":"info","ts":"2026-05-19T08:11:09.630218Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5571,"took":"67.273478ms","hash":1228610371,"current-db-size-bytes":54239232,"current-db-size":"54 MB","current-db-size-in-use-bytes":18075648,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-19T08:11:09.63027Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1228610371,"revision":5571,"compact-revision":2885}