{"level":"warn","ts":"2026-05-08T10:20:04.85315Z","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-08T10:20:04.853249Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.217: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.19.213.217:2380","--initial-cluster=instance=https://199.19.213.217:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.217:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.217: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-08T10:20:04.853326Z","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-08T10:20:04.853339Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.217:2380"]} {"level":"info","ts":"2026-05-08T10:20:04.85336Z","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-08T10:20:04.854021Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.217:2379"]} {"level":"info","ts":"2026-05-08T10:20:04.854158Z","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.19.213.217:2380"],"listen-peer-urls":["https://199.19.213.217:2380"],"advertise-client-urls":["https://199.19.213.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.217: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-08T10:20:05.024429Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"170.060095ms"} {"level":"info","ts":"2026-05-08T10:20:05.035124Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"22141a955455b8ec","cluster-id":"30738c1f7233ae60"} {"level":"info","ts":"2026-05-08T10:20:05.035337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22141a955455b8ec switched to configuration voters=()"} {"level":"info","ts":"2026-05-08T10:20:05.035426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22141a955455b8ec became follower at term 0"} {"level":"info","ts":"2026-05-08T10:20:05.035449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 22141a955455b8ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-08T10:20:05.035535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22141a955455b8ec became follower at term 1"} {"level":"info","ts":"2026-05-08T10:20:05.035666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22141a955455b8ec switched to configuration voters=(2455616925491116268)"} {"level":"warn","ts":"2026-05-08T10:20:05.047065Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-08T10:20:05.049042Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-08T10:20:05.050256Z","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-08T10:20:05.053681Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"22141a955455b8ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-08T10:20:05.054122Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"22141a955455b8ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-08T10:20:05.05416Z","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-08T10:20:05.05453Z","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-08T10:20:05.054553Z","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-08T10:20:05.055322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22141a955455b8ec switched to configuration voters=(2455616925491116268)"} {"level":"info","ts":"2026-05-08T10:20:05.055476Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"30738c1f7233ae60","local-member-id":"22141a955455b8ec","added-peer-id":"22141a955455b8ec","added-peer-peer-urls":["https://199.19.213.217:2380"]} {"level":"info","ts":"2026-05-08T10:20:05.056841Z","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-08T10:20:05.057018Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"22141a955455b8ec","initial-advertise-peer-urls":["https://199.19.213.217:2380"],"listen-peer-urls":["https://199.19.213.217:2380"],"advertise-client-urls":["https://199.19.213.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-08T10:20:05.057046Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-08T10:20:05.057231Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.217:2380"} {"level":"info","ts":"2026-05-08T10:20:05.057251Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.217:2380"} {"level":"info","ts":"2026-05-08T10:20:05.836679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22141a955455b8ec is starting a new election at term 1"} {"level":"info","ts":"2026-05-08T10:20:05.836739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22141a955455b8ec became pre-candidate at term 1"} {"level":"info","ts":"2026-05-08T10:20:05.83678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22141a955455b8ec received MsgPreVoteResp from 22141a955455b8ec at term 1"} {"level":"info","ts":"2026-05-08T10:20:05.836799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22141a955455b8ec became candidate at term 2"} {"level":"info","ts":"2026-05-08T10:20:05.836811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22141a955455b8ec received MsgVoteResp from 22141a955455b8ec at term 2"} {"level":"info","ts":"2026-05-08T10:20:05.836833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22141a955455b8ec became leader at term 2"} {"level":"info","ts":"2026-05-08T10:20:05.836846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 22141a955455b8ec elected leader 22141a955455b8ec at term 2"} {"level":"info","ts":"2026-05-08T10:20:05.92144Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T10:20:05.935563Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T10:20:05.935558Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"22141a955455b8ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.217:2379]}","request-path":"/0/members/22141a955455b8ec/attributes","cluster-id":"30738c1f7233ae60","publish-timeout":"7s"} {"level":"info","ts":"2026-05-08T10:20:05.935679Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T10:20:05.936109Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-08T10:20:05.936176Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-08T10:20:05.937354Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"30738c1f7233ae60","local-member-id":"22141a955455b8ec","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T10:20:05.937494Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T10:20:05.937535Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T10:20:05.939268Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-08T10:20:05.939765Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.217:2379"} {"level":"info","ts":"2026-05-08T10:20:40.533586Z","caller":"traceutil/trace.go:171","msg":"trace[1291376554] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"229.965394ms","start":"2026-05-08T10:20:40.30359Z","end":"2026-05-08T10:20:40.533556Z","steps":["trace[1291376554] 'process raft request' (duration: 229.44618ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:20:40.994968Z","caller":"traceutil/trace.go:171","msg":"trace[427920820] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:590; }","duration":"211.633912ms","start":"2026-05-08T10:20:40.783304Z","end":"2026-05-08T10:20:40.994938Z","steps":["trace[427920820] 'read index received' (duration: 150.281271ms)","trace[427920820] 'applied index is now lower than readState.Index' (duration: 61.34345ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:20:40.99505Z","caller":"traceutil/trace.go:171","msg":"trace[1323848834] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"429.949616ms","start":"2026-05-08T10:20:40.56507Z","end":"2026-05-08T10:20:40.99502Z","steps":["trace[1323848834] 'process raft request' (duration: 368.38206ms)","trace[1323848834] 'compare' (duration: 61.248028ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:20:40.995185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.869027ms","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-08T10:20:40.995295Z","caller":"traceutil/trace.go:171","msg":"trace[1405701814] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"212.039422ms","start":"2026-05-08T10:20:40.783239Z","end":"2026-05-08T10:20:40.995279Z","steps":["trace[1405701814] 'agreement among raft nodes before linearized reading' (duration: 211.810077ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:20:40.995531Z","caller":"traceutil/trace.go:171","msg":"trace[49097916] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"408.056072ms","start":"2026-05-08T10:20:40.587464Z","end":"2026-05-08T10:20:40.99552Z","steps":["trace[49097916] 'process raft request' (duration: 407.396985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:20:40.99605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:20:40.565046Z","time spent":"430.068088ms","remote":"127.0.0.1:40462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:20:40.99605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:20:40.587438Z","time spent":"408.122864ms","remote":"127.0.0.1:40774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T10:21:35.902408Z","caller":"traceutil/trace.go:171","msg":"trace[1144918527] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"124.216615ms","start":"2026-05-08T10:21:35.778168Z","end":"2026-05-08T10:21:35.902385Z","steps":["trace[1144918527] 'process raft request' (duration: 124.084032ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:21:35.926517Z","caller":"traceutil/trace.go:171","msg":"trace[79595157] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"147.729932ms","start":"2026-05-08T10:21:35.778748Z","end":"2026-05-08T10:21:35.926477Z","steps":["trace[79595157] 'process raft request' (duration: 147.151632ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:22:23.300532Z","caller":"traceutil/trace.go:171","msg":"trace[397522396] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"225.93213ms","start":"2026-05-08T10:22:23.074571Z","end":"2026-05-08T10:22:23.300503Z","steps":["trace[397522396] 'process raft request' (duration: 225.771538ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:22:23.301214Z","caller":"traceutil/trace.go:171","msg":"trace[1681241075] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"105.263083ms","start":"2026-05-08T10:22:23.195927Z","end":"2026-05-08T10:22:23.30119Z","steps":["trace[1681241075] 'process raft request' (duration: 105.159951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:23.694356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.328596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:22:23.694466Z","caller":"traceutil/trace.go:171","msg":"trace[154189108] linearizableReadLoop","detail":"{readStateIndex:1900; appliedIndex:1899; }","duration":"247.838604ms","start":"2026-05-08T10:22:23.44661Z","end":"2026-05-08T10:22:23.694449Z","steps":["trace[154189108] 'read index received' (duration: 49.621µs)","trace[154189108] 'applied index is now lower than readState.Index' (duration: 247.787653ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:22:23.694536Z","caller":"traceutil/trace.go:171","msg":"trace[908068155] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"381.840535ms","start":"2026-05-08T10:22:23.312664Z","end":"2026-05-08T10:22:23.694504Z","steps":["trace[908068155] 'process raft request' (duration: 71.122634ms)","trace[908068155] 'compare' (duration: 310.01401ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:22:23.694668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.143157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-08T10:22:23.694698Z","caller":"traceutil/trace.go:171","msg":"trace[1797081192] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1835; }","duration":"217.220688ms","start":"2026-05-08T10:22:23.477469Z","end":"2026-05-08T10:22:23.69469Z","steps":["trace[1797081192] 'agreement among raft nodes before linearized reading' (duration: 217.074926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:23.694726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:22:23.312639Z","time spent":"382.002777ms","remote":"127.0.0.1:40462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10970,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:22:23.696091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.400928ms","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-08T10:22:23.696668Z","caller":"traceutil/trace.go:171","msg":"trace[1643229965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1835; }","duration":"250.074599ms","start":"2026-05-08T10:22:23.446556Z","end":"2026-05-08T10:22:23.696631Z","steps":["trace[1643229965] 'agreement among raft nodes before linearized reading' (duration: 248.080028ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:22:38.188704Z","caller":"traceutil/trace.go:171","msg":"trace[1750356144] transaction","detail":"{read_only:false; response_revision:1953; number_of_response:1; }","duration":"149.485586ms","start":"2026-05-08T10:22:38.039155Z","end":"2026-05-08T10:22:38.188641Z","steps":["trace[1750356144] 'process raft request' (duration: 149.10951ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:23:02.491833Z","caller":"traceutil/trace.go:171","msg":"trace[442644107] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"127.680482ms","start":"2026-05-08T10:23:02.364124Z","end":"2026-05-08T10:23:02.491804Z","steps":["trace[442644107] 'process raft request' (duration: 85.4785ms)","trace[442644107] 'compare' (duration: 42.03206ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:23:02.818283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.800018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:23:02.818461Z","caller":"traceutil/trace.go:171","msg":"trace[2122939339] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"112.999816ms","start":"2026-05-08T10:23:02.705444Z","end":"2026-05-08T10:23:02.818444Z","steps":["trace[2122939339] 'process raft request' (duration: 112.939486ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:23:02.818875Z","caller":"traceutil/trace.go:171","msg":"trace[176301760] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"245.090641ms","start":"2026-05-08T10:23:02.573756Z","end":"2026-05-08T10:23:02.818846Z","steps":["trace[176301760] 'process raft request' (duration: 31.636934ms)","trace[176301760] 'compare' (duration: 212.698847ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:23:02.818932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.779476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-05-08T10:23:02.819041Z","caller":"traceutil/trace.go:171","msg":"trace[1835029269] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2103; }","duration":"167.902357ms","start":"2026-05-08T10:23:02.651117Z","end":"2026-05-08T10:23:02.819019Z","steps":["trace[1835029269] 'agreement among raft nodes before linearized reading' (duration: 167.616754ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:23:02.818644Z","caller":"traceutil/trace.go:171","msg":"trace[1405981119] linearizableReadLoop","detail":"{readStateIndex:2176; appliedIndex:2175; }","duration":"167.473022ms","start":"2026-05-08T10:23:02.651135Z","end":"2026-05-08T10:23:02.818608Z","steps":["trace[1405981119] 'read index received' (duration: 42.871µs)","trace[1405981119] 'applied index is now lower than readState.Index' (duration: 167.427631ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:23:03.204348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.640466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-08T10:23:03.204451Z","caller":"traceutil/trace.go:171","msg":"trace[2072628492] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2103; }","duration":"258.783349ms","start":"2026-05-08T10:23:02.945647Z","end":"2026-05-08T10:23:03.20443Z","steps":["trace[2072628492] 'range keys from in-memory index tree' (duration: 258.455744ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:01.674938Z","caller":"traceutil/trace.go:171","msg":"trace[780740944] transaction","detail":"{read_only:false; response_revision:2521; number_of_response:1; }","duration":"149.903723ms","start":"2026-05-08T10:24:01.525012Z","end":"2026-05-08T10:24:01.674916Z","steps":["trace[780740944] 'process raft request' (duration: 149.785779ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:34.493387Z","caller":"traceutil/trace.go:171","msg":"trace[2099271739] transaction","detail":"{read_only:false; response_revision:2700; number_of_response:1; }","duration":"155.274447ms","start":"2026-05-08T10:24:34.338089Z","end":"2026-05-08T10:24:34.493364Z","steps":["trace[2099271739] 'process raft request' (duration: 155.115811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:39.124354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.374649ms","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-05-08T10:25:39.124444Z","caller":"traceutil/trace.go:171","msg":"trace[2119131989] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3037; }","duration":"346.507413ms","start":"2026-05-08T10:25:38.777918Z","end":"2026-05-08T10:25:39.124425Z","steps":["trace[2119131989] 'range keys from in-memory index tree' (duration: 346.246246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:39.124486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:25:38.777896Z","time spent":"346.581535ms","remote":"127.0.0.1:40530","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-08T10:25:39.124503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.018261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T10:25:39.124623Z","caller":"traceutil/trace.go:171","msg":"trace[274789533] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:3037; }","duration":"756.202856ms","start":"2026-05-08T10:25:38.368391Z","end":"2026-05-08T10:25:39.124594Z","steps":["trace[274789533] 'count revisions from in-memory index tree' (duration: 755.902519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:39.124722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:25:38.368351Z","time spent":"756.35144ms","remote":"127.0.0.1:40528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-05-08T10:25:39.124698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.086333ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:25:39.124843Z","caller":"traceutil/trace.go:171","msg":"trace[1084690411] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:3037; }","duration":"614.229477ms","start":"2026-05-08T10:25:38.510584Z","end":"2026-05-08T10:25:39.124813Z","steps":["trace[1084690411] 'range keys from in-memory index tree' (duration: 614.067563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:39.124968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.040704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-08T10:25:39.12504Z","caller":"traceutil/trace.go:171","msg":"trace[375602598] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:3037; }","duration":"277.134636ms","start":"2026-05-08T10:25:38.847889Z","end":"2026-05-08T10:25:39.125023Z","steps":["trace[375602598] 'range keys from in-memory index tree' (duration: 276.885589ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:39.125279Z","caller":"traceutil/trace.go:171","msg":"trace[938501271] transaction","detail":"{read_only:false; response_revision:3038; number_of_response:1; }","duration":"198.935603ms","start":"2026-05-08T10:25:38.926323Z","end":"2026-05-08T10:25:39.125259Z","steps":["trace[938501271] 'process raft request' (duration: 197.835204ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:39.127641Z","caller":"traceutil/trace.go:171","msg":"trace[1786098008] transaction","detail":"{read_only:false; response_revision:3039; number_of_response:1; }","duration":"194.72776ms","start":"2026-05-08T10:25:38.932894Z","end":"2026-05-08T10:25:39.127622Z","steps":["trace[1786098008] 'process raft request' (duration: 194.287008ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:15.971049Z","caller":"traceutil/trace.go:171","msg":"trace[1743853137] transaction","detail":"{read_only:false; response_revision:3241; number_of_response:1; }","duration":"259.959881ms","start":"2026-05-08T10:26:15.711065Z","end":"2026-05-08T10:26:15.971025Z","steps":["trace[1743853137] 'process raft request' (duration: 259.694574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:18.314598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.531933ms","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-08T10:26:18.31467Z","caller":"traceutil/trace.go:171","msg":"trace[645552384] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3247; }","duration":"854.651756ms","start":"2026-05-08T10:26:17.460002Z","end":"2026-05-08T10:26:18.314654Z","steps":["trace[645552384] 'range keys from in-memory index tree' (duration: 854.342768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:18.314708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:17.45998Z","time spent":"854.721157ms","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1391,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-08T10:26:18.314777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.356278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-08T10:26:18.314843Z","caller":"traceutil/trace.go:171","msg":"trace[1987106597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3247; }","duration":"339.464871ms","start":"2026-05-08T10:26:17.975366Z","end":"2026-05-08T10:26:18.314831Z","steps":["trace[1987106597] 'range keys from in-memory index tree' (duration: 339.176374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:18.314885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:17.975347Z","time spent":"339.530713ms","remote":"127.0.0.1:40530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T10:26:18.314948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.366561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T10:26:18.315049Z","caller":"traceutil/trace.go:171","msg":"trace[896524014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3247; }","duration":"852.500914ms","start":"2026-05-08T10:26:17.462524Z","end":"2026-05-08T10:26:18.315025Z","steps":["trace[896524014] 'range keys from in-memory index tree' (duration: 852.187617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:18.31511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:17.46251Z","time spent":"852.586156ms","remote":"127.0.0.1:40530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T10:26:18.315472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.638607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:26:18.315496Z","caller":"traceutil/trace.go:171","msg":"trace[773997232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3247; }","duration":"796.698759ms","start":"2026-05-08T10:26:17.518792Z","end":"2026-05-08T10:26:18.315491Z","steps":["trace[773997232] 'range keys from in-memory index tree' (duration: 796.574066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:18.31551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:17.518775Z","time spent":"796.7319ms","remote":"127.0.0.1:40306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T10:26:18.315575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.932654ms","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-08T10:26:18.315658Z","caller":"traceutil/trace.go:171","msg":"trace[1310835117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3247; }","duration":"853.064777ms","start":"2026-05-08T10:26:17.462575Z","end":"2026-05-08T10:26:18.31564Z","steps":["trace[1310835117] 'range keys from in-memory index tree' (duration: 852.772261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:18.315699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:17.462555Z","time spent":"853.135439ms","remote":"127.0.0.1:40530","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-08T10:26:18.316807Z","caller":"traceutil/trace.go:171","msg":"trace[347964784] transaction","detail":"{read_only:false; response_revision:3248; number_of_response:1; }","duration":"305.363866ms","start":"2026-05-08T10:26:18.01115Z","end":"2026-05-08T10:26:18.316514Z","steps":["trace[347964784] 'process raft request' (duration: 302.640681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:18.317248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:18.011136Z","time spent":"305.767375ms","remote":"127.0.0.1:40530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:26:18.887684Z","caller":"traceutil/trace.go:171","msg":"trace[612347655] transaction","detail":"{read_only:false; response_revision:3252; number_of_response:1; }","duration":"537.32106ms","start":"2026-05-08T10:26:18.350333Z","end":"2026-05-08T10:26:18.887654Z","steps":["trace[612347655] 'process raft request' (duration: 536.268225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:18.887851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:18.350309Z","time spent":"537.463623ms","remote":"127.0.0.1:40530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:26:18.891866Z","caller":"traceutil/trace.go:171","msg":"trace[1432035823] transaction","detail":"{read_only:false; response_revision:3253; number_of_response:1; }","duration":"523.676735ms","start":"2026-05-08T10:26:18.367494Z","end":"2026-05-08T10:26:18.89117Z","steps":["trace[1432035823] 'process raft request' (duration: 523.4872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:18.892066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:18.367474Z","time spent":"524.511134ms","remote":"127.0.0.1:40376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74817,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:26:21.548584Z","caller":"traceutil/trace.go:171","msg":"trace[1992397091] transaction","detail":"{read_only:false; response_revision:3292; number_of_response:1; }","duration":"284.931346ms","start":"2026-05-08T10:26:21.263637Z","end":"2026-05-08T10:26:21.548568Z","steps":["trace[1992397091] 'process raft request' (duration: 284.809173ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:21.549675Z","caller":"traceutil/trace.go:171","msg":"trace[1290235807] linearizableReadLoop","detail":"{readStateIndex:3416; appliedIndex:3413; }","duration":"236.709824ms","start":"2026-05-08T10:26:21.312945Z","end":"2026-05-08T10:26:21.549655Z","steps":["trace[1290235807] 'read index received' (duration: 235.558657ms)","trace[1290235807] 'applied index is now lower than readState.Index' (duration: 1.149997ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:21.54998Z","caller":"traceutil/trace.go:171","msg":"trace[1593303899] transaction","detail":"{read_only:false; response_revision:3294; number_of_response:1; }","duration":"280.46103ms","start":"2026-05-08T10:26:21.269509Z","end":"2026-05-08T10:26:21.54997Z","steps":["trace[1593303899] 'process raft request' (duration: 280.110082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:21.550017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.058133ms","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-05-08T10:26:21.550099Z","caller":"traceutil/trace.go:171","msg":"trace[1564910143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3294; }","duration":"237.190766ms","start":"2026-05-08T10:26:21.31289Z","end":"2026-05-08T10:26:21.550081Z","steps":["trace[1564910143] 'agreement among raft nodes before linearized reading' (duration: 236.94586ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:21.549891Z","caller":"traceutil/trace.go:171","msg":"trace[1232756017] transaction","detail":"{read_only:false; response_revision:3293; number_of_response:1; }","duration":"282.396836ms","start":"2026-05-08T10:26:21.267482Z","end":"2026-05-08T10:26:21.549879Z","steps":["trace[1232756017] 'process raft request' (duration: 281.848273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:21.550181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.605792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-08T10:26:21.550299Z","caller":"traceutil/trace.go:171","msg":"trace[1340868045] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3294; }","duration":"187.729445ms","start":"2026-05-08T10:26:21.362524Z","end":"2026-05-08T10:26:21.550253Z","steps":["trace[1340868045] 'agreement among raft nodes before linearized reading' (duration: 187.447208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:23.665306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.377165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:26:23.665516Z","caller":"traceutil/trace.go:171","msg":"trace[906195670] linearizableReadLoop","detail":"{readStateIndex:3465; appliedIndex:3464; }","duration":"764.506716ms","start":"2026-05-08T10:26:22.900989Z","end":"2026-05-08T10:26:23.665495Z","steps":["trace[906195670] 'read index received' (duration: 39.031µs)","trace[906195670] 'applied index is now lower than readState.Index' (duration: 764.465785ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:23.665703Z","caller":"traceutil/trace.go:171","msg":"trace[861482734] transaction","detail":"{read_only:false; response_revision:3343; number_of_response:1; }","duration":"796.756547ms","start":"2026-05-08T10:26:22.868938Z","end":"2026-05-08T10:26:23.665695Z","steps":[],"step_count":0} {"level":"warn","ts":"2026-05-08T10:26:23.665769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:22.868915Z","time spent":"796.826558ms","remote":"127.0.0.1:40530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:26:23.668801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.745751ms","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-05-08T10:26:23.668895Z","caller":"traceutil/trace.go:171","msg":"trace[637613543] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3343; }","duration":"767.936556ms","start":"2026-05-08T10:26:22.900939Z","end":"2026-05-08T10:26:23.668876Z","steps":["trace[637613543] 'agreement among raft nodes before linearized reading' (duration: 764.731781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:23.668943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:22.900921Z","time spent":"768.010687ms","remote":"127.0.0.1:40530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-08T10:26:23.669839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:23.26918Z","time spent":"400.652908ms","remote":"127.0.0.1:39604","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-08T10:26:23.670641Z","caller":"traceutil/trace.go:171","msg":"trace[1994581692] transaction","detail":"{read_only:false; response_revision:3344; number_of_response:1; }","duration":"116.237608ms","start":"2026-05-08T10:26:23.554385Z","end":"2026-05-08T10:26:23.670622Z","steps":["trace[1994581692] 'process raft request' (duration: 111.097917ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:23.670944Z","caller":"traceutil/trace.go:171","msg":"trace[300096151] transaction","detail":"{read_only:false; response_revision:3345; number_of_response:1; }","duration":"115.880059ms","start":"2026-05-08T10:26:23.555047Z","end":"2026-05-08T10:26:23.670928Z","steps":["trace[300096151] 'process raft request' (duration: 115.331416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:23.671214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.575812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7508"} {"level":"warn","ts":"2026-05-08T10:26:23.671224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.80189ms","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-05-08T10:26:23.671251Z","caller":"traceutil/trace.go:171","msg":"trace[177326304] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:3346; }","duration":"275.653574ms","start":"2026-05-08T10:26:23.395589Z","end":"2026-05-08T10:26:23.671242Z","steps":["trace[177326304] 'agreement among raft nodes before linearized reading' (duration: 275.541331ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:23.671286Z","caller":"traceutil/trace.go:171","msg":"trace[635664749] transaction","detail":"{read_only:false; response_revision:3346; number_of_response:1; }","duration":"115.167093ms","start":"2026-05-08T10:26:23.55611Z","end":"2026-05-08T10:26:23.671277Z","steps":["trace[635664749] 'process raft request' (duration: 114.6175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:23.671531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.937469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-08T10:26:23.67157Z","caller":"traceutil/trace.go:171","msg":"trace[1458527340] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3346; }","duration":"110.003691ms","start":"2026-05-08T10:26:23.561547Z","end":"2026-05-08T10:26:23.671551Z","steps":["trace[1458527340] 'agreement among raft nodes before linearized reading' (duration: 109.924669ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:23.671254Z","caller":"traceutil/trace.go:171","msg":"trace[525442394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3346; }","duration":"110.865512ms","start":"2026-05-08T10:26:23.560381Z","end":"2026-05-08T10:26:23.671246Z","steps":["trace[525442394] 'agreement among raft nodes before linearized reading' (duration: 110.739599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:30.203957Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13325199150820641079,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T10:26:30.259525Z","caller":"traceutil/trace.go:171","msg":"trace[1795376470] transaction","detail":"{read_only:false; response_revision:3385; number_of_response:1; }","duration":"565.763205ms","start":"2026-05-08T10:26:29.69373Z","end":"2026-05-08T10:26:30.259494Z","steps":["trace[1795376470] 'process raft request' (duration: 565.580261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:30.259688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:29.693709Z","time spent":"565.892198ms","remote":"127.0.0.1:40530","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-05-08T10:26:30.706556Z","caller":"traceutil/trace.go:171","msg":"trace[308863094] linearizableReadLoop","detail":"{readStateIndex:3510; appliedIndex:3508; }","duration":"1.003489708s","start":"2026-05-08T10:26:29.703048Z","end":"2026-05-08T10:26:30.706538Z","steps":["trace[308863094] 'read index received' (duration: 556.244274ms)","trace[308863094] 'applied index is now lower than readState.Index' (duration: 447.244784ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:30.706669Z","caller":"traceutil/trace.go:171","msg":"trace[1469545342] transaction","detail":"{read_only:false; response_revision:3387; number_of_response:1; }","duration":"481.705973ms","start":"2026-05-08T10:26:30.224939Z","end":"2026-05-08T10:26:30.706645Z","steps":["trace[1469545342] 'process raft request' (duration: 481.544039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:30.706737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003673193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-08T10:26:30.70686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:30.224923Z","time spent":"481.838265ms","remote":"127.0.0.1:40530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:26:30.707041Z","caller":"traceutil/trace.go:171","msg":"trace[2118184687] transaction","detail":"{read_only:false; response_revision:3386; number_of_response:1; }","duration":"518.915133ms","start":"2026-05-08T10:26:30.188111Z","end":"2026-05-08T10:26:30.707026Z","steps":["trace[2118184687] 'process raft request' (duration: 516.670892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:30.707156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:30.188084Z","time spent":"519.010876ms","remote":"127.0.0.1:40530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:26:30.707319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004231466s","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-05-08T10:26:30.707356Z","caller":"traceutil/trace.go:171","msg":"trace[1653246320] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3387; }","duration":"1.004278707s","start":"2026-05-08T10:26:29.703068Z","end":"2026-05-08T10:26:30.707346Z","steps":["trace[1653246320] 'agreement among raft nodes before linearized reading' (duration: 1.004198135s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:30.707381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:29.703064Z","time spent":"1.004310608s","remote":"127.0.0.1:40530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-08T10:26:30.707427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.794737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T10:26:30.707448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004332998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-08T10:26:30.707469Z","caller":"traceutil/trace.go:171","msg":"trace[851610562] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:3387; }","duration":"336.892819ms","start":"2026-05-08T10:26:30.370566Z","end":"2026-05-08T10:26:30.707459Z","steps":["trace[851610562] 'agreement among raft nodes before linearized reading' (duration: 336.796747ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:30.707497Z","caller":"traceutil/trace.go:171","msg":"trace[1718164560] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3387; }","duration":"1.00439312s","start":"2026-05-08T10:26:29.703088Z","end":"2026-05-08T10:26:30.707481Z","steps":["trace[1718164560] 'agreement among raft nodes before linearized reading' (duration: 1.004270507s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:30.707537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:30.370546Z","time spent":"336.980202ms","remote":"127.0.0.1:40524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-05-08T10:26:30.707539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:29.703085Z","time spent":"1.004446121s","remote":"127.0.0.1:40530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-08T10:26:30.707546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.957361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T10:26:30.707634Z","caller":"traceutil/trace.go:171","msg":"trace[164257922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3387; }","duration":"318.051943ms","start":"2026-05-08T10:26:30.389573Z","end":"2026-05-08T10:26:30.707625Z","steps":["trace[164257922] 'agreement among raft nodes before linearized reading' (duration: 317.90466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:30.707666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:30.389565Z","time spent":"318.093624ms","remote":"127.0.0.1:40530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T10:26:30.707725Z","caller":"traceutil/trace.go:171","msg":"trace[585888017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3387; }","duration":"1.003740054s","start":"2026-05-08T10:26:29.703023Z","end":"2026-05-08T10:26:30.706763Z","steps":["trace[585888017] 'agreement among raft nodes before linearized reading' (duration: 1.003622722s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:30.708029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:29.703015Z","time spent":"1.005004583s","remote":"127.0.0.1:40530","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-08T10:26:30.707703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.167746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T10:26:30.708089Z","caller":"traceutil/trace.go:171","msg":"trace[1919186922] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:3387; }","duration":"562.646627ms","start":"2026-05-08T10:26:30.145429Z","end":"2026-05-08T10:26:30.708076Z","steps":["trace[1919186922] 'agreement among raft nodes before linearized reading' (duration: 561.92601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:30.708212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:30.145409Z","time spent":"562.78653ms","remote":"127.0.0.1:40474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":61,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-05-08T10:26:30.707919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.171549ms","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-08T10:26:30.708467Z","caller":"traceutil/trace.go:171","msg":"trace[1993137621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3387; }","duration":"322.718551ms","start":"2026-05-08T10:26:30.385729Z","end":"2026-05-08T10:26:30.708448Z","steps":["trace[1993137621] 'agreement among raft nodes before linearized reading' (duration: 322.097977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:30.708527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:30.385724Z","time spent":"322.788493ms","remote":"127.0.0.1:40530","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-08T10:26:30.707926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.770065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-08T10:26:30.708722Z","caller":"traceutil/trace.go:171","msg":"trace[1813230512] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3387; }","duration":"330.580123ms","start":"2026-05-08T10:26:30.378128Z","end":"2026-05-08T10:26:30.708708Z","steps":["trace[1813230512] 'agreement among raft nodes before linearized reading' (duration: 329.223662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:30.708765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:30.378123Z","time spent":"330.629854ms","remote":"127.0.0.1:40530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-08T10:28:19.404116Z","caller":"traceutil/trace.go:171","msg":"trace[827155100] linearizableReadLoop","detail":"{readStateIndex:4742; appliedIndex:4741; }","duration":"142.805758ms","start":"2026-05-08T10:28:19.26128Z","end":"2026-05-08T10:28:19.404085Z","steps":["trace[827155100] 'read index received' (duration: 96.405925ms)","trace[827155100] 'applied index is now lower than readState.Index' (duration: 46.398683ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:28:19.404125Z","caller":"traceutil/trace.go:171","msg":"trace[2131352994] transaction","detail":"{read_only:false; response_revision:4571; number_of_response:1; }","duration":"199.340694ms","start":"2026-05-08T10:28:19.204755Z","end":"2026-05-08T10:28:19.404096Z","steps":["trace[2131352994] 'process raft request' (duration: 152.950411ms)","trace[2131352994] 'compare' (duration: 46.176649ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:28:19.404293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.997602ms","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-08T10:28:19.404342Z","caller":"traceutil/trace.go:171","msg":"trace[1205524216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4571; }","duration":"143.083504ms","start":"2026-05-08T10:28:19.261247Z","end":"2026-05-08T10:28:19.404331Z","steps":["trace[1205524216] 'agreement among raft nodes before linearized reading' (duration: 142.940081ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:28:24.780473Z","caller":"traceutil/trace.go:171","msg":"trace[83666090] transaction","detail":"{read_only:false; response_revision:4622; number_of_response:1; }","duration":"163.027123ms","start":"2026-05-08T10:28:24.617415Z","end":"2026-05-08T10:28:24.780442Z","steps":["trace[83666090] 'process raft request' (duration: 162.662766ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:28:24.801172Z","caller":"traceutil/trace.go:171","msg":"trace[843984676] transaction","detail":"{read_only:false; response_revision:4623; number_of_response:1; }","duration":"114.579237ms","start":"2026-05-08T10:28:24.686558Z","end":"2026-05-08T10:28:24.801137Z","steps":["trace[843984676] 'process raft request' (duration: 114.445984ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:28:25.411737Z","caller":"traceutil/trace.go:171","msg":"trace[871901561] linearizableReadLoop","detail":"{readStateIndex:4829; appliedIndex:4828; }","duration":"149.061386ms","start":"2026-05-08T10:28:25.262645Z","end":"2026-05-08T10:28:25.411706Z","steps":["trace[871901561] 'read index received' (duration: 89.937819ms)","trace[871901561] 'applied index is now lower than readState.Index' (duration: 59.122297ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:28:25.412073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.409353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-05-08T10:28:25.412113Z","caller":"traceutil/trace.go:171","msg":"trace[15402259] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4657; }","duration":"149.468914ms","start":"2026-05-08T10:28:25.262634Z","end":"2026-05-08T10:28:25.412103Z","steps":["trace[15402259] 'agreement among raft nodes before linearized reading' (duration: 149.21837ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:28:25.412377Z","caller":"traceutil/trace.go:171","msg":"trace[1453989860] transaction","detail":"{read_only:false; response_revision:4657; number_of_response:1; }","duration":"149.711108ms","start":"2026-05-08T10:28:25.262067Z","end":"2026-05-08T10:28:25.411778Z","steps":["trace[1453989860] 'process raft request' (duration: 90.570711ms)","trace[1453989860] 'compare' (duration: 58.855992ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:30:05.966534Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2867} {"level":"info","ts":"2026-05-08T10:30:06.047459Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2867,"took":"79.816361ms","hash":2611848992,"current-db-size-bytes":53428224,"current-db-size":"53 MB","current-db-size-in-use-bytes":29024256,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-08T10:30:06.047521Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2611848992,"revision":2867,"compact-revision":-1} {"level":"info","ts":"2026-05-08T10:35:05.973331Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5579} {"level":"info","ts":"2026-05-08T10:35:06.046131Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5579,"took":"71.704951ms","hash":3808724979,"current-db-size-bytes":53428224,"current-db-size":"53 MB","current-db-size-in-use-bytes":18255872,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-08T10:35:06.046181Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3808724979,"revision":5579,"compact-revision":2867}