{"level":"warn","ts":"2026-05-18T15:46:55.347685Z","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-18T15:46:55.347777Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-18T15:46:55.347989Z","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-18T15:46:55.348005Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-05-18T15:46:55.348027Z","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-18T15:46:55.348656Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-05-18T15:46:55.348754Z","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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-18T15:46:55.354087Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.111507ms"} {"level":"info","ts":"2026-05-18T15:46:55.364215Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-05-18T15:46:55.364295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-05-18T15:46:55.364319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-05-18T15:46:55.36433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-18T15:46:55.364359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-05-18T15:46:55.364409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-05-18T15:46:55.372839Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-18T15:46:55.374421Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-18T15:46:55.376037Z","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-18T15:46:55.378884Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-18T15:46:55.379158Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-18T15:46:55.379347Z","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-18T15:46:55.379671Z","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-18T15:46:55.379743Z","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-18T15:46:55.383587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-05-18T15:46:55.383699Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-05-18T15:46:55.385009Z","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-18T15:46:55.385299Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-18T15:46:55.385332Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-18T15:46:55.38538Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-05-18T15:46:55.38552Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-05-18T15:46:55.565635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-05-18T15:46:55.565697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-05-18T15:46:55.565733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-05-18T15:46:55.565754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-05-18T15:46:55.565909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-05-18T15:46:55.565937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-05-18T15:46:55.565952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-05-18T15:46:55.566989Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T15:46:55.567779Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-05-18T15:46:55.567899Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-18T15:46:55.567967Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-18T15:46:55.56811Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-18T15:46:55.568183Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-18T15:46:55.568376Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T15:46:55.568513Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T15:46:55.568605Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T15:46:55.56996Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-05-18T15:46:55.57084Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-18T15:47:11.669343Z","caller":"traceutil/trace.go:171","msg":"trace[287885271] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"119.145632ms","start":"2026-05-18T15:47:11.550156Z","end":"2026-05-18T15:47:11.669302Z","steps":["trace[287885271] 'process raft request' (duration: 118.847667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:47:12.095312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.797935ms","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-18T15:47:12.095417Z","caller":"traceutil/trace.go:171","msg":"trace[1035139389] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"338.960317ms","start":"2026-05-18T15:47:11.756439Z","end":"2026-05-18T15:47:12.095399Z","steps":["trace[1035139389] 'range keys from in-memory index tree' (duration: 338.619894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:47:12.095449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:47:11.756412Z","time spent":"339.028943ms","remote":"127.0.0.1:60148","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-18T15:47:32.334809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.833935ms","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-18T15:47:32.334901Z","caller":"traceutil/trace.go:171","msg":"trace[499847677] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"112.983543ms","start":"2026-05-18T15:47:32.221897Z","end":"2026-05-18T15:47:32.334881Z","steps":["trace[499847677] 'range keys from in-memory index tree' (duration: 112.645708ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:47:32.774652Z","caller":"traceutil/trace.go:171","msg":"trace[197011676] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"303.876137ms","start":"2026-05-18T15:47:32.470732Z","end":"2026-05-18T15:47:32.774608Z","steps":["trace[197011676] 'process raft request' (duration: 270.753987ms)","trace[197011676] 'compare' (duration: 32.709626ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:47:32.77486Z","caller":"traceutil/trace.go:171","msg":"trace[1942811081] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"231.489976ms","start":"2026-05-18T15:47:32.54334Z","end":"2026-05-18T15:47:32.77483Z","steps":["trace[1942811081] 'process raft request' (duration: 231.162491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:47:32.775311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:47:32.47071Z","time spent":"304.032435ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:47:32.967706Z","caller":"traceutil/trace.go:171","msg":"trace[395244336] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"176.631222ms","start":"2026-05-18T15:47:32.791052Z","end":"2026-05-18T15:47:32.967683Z","steps":["trace[395244336] 'read index received' (duration: 174.707651ms)","trace[395244336] 'applied index is now lower than readState.Index' (duration: 1.922581ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:47:32.96805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.894798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:47:32.968114Z","caller":"traceutil/trace.go:171","msg":"trace[803127050] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:589; }","duration":"177.101545ms","start":"2026-05-18T15:47:32.790996Z","end":"2026-05-18T15:47:32.968098Z","steps":["trace[803127050] 'agreement among raft nodes before linearized reading' (duration: 176.905098ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:47:32.968652Z","caller":"traceutil/trace.go:171","msg":"trace[1722876735] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"180.22819ms","start":"2026-05-18T15:47:32.788267Z","end":"2026-05-18T15:47:32.968495Z","steps":["trace[1722876735] 'process raft request' (duration: 177.619838ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:49:22.652358Z","caller":"traceutil/trace.go:171","msg":"trace[204958955] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1962; }","duration":"198.434996ms","start":"2026-05-18T15:49:22.453916Z","end":"2026-05-18T15:49:22.652351Z","steps":["trace[204958955] 'read index received' (duration: 189.742818ms)","trace[204958955] 'applied index is now lower than readState.Index' (duration: 8.691498ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:49:22.652344Z","caller":"traceutil/trace.go:171","msg":"trace[213200303] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"158.913193ms","start":"2026-05-18T15:49:22.493406Z","end":"2026-05-18T15:49:22.652319Z","steps":["trace[213200303] 'process raft request' (duration: 158.831608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:49:22.652581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.649929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d39a4251-ff88-4ee7-b053-81a7e44ebe81\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-18T15:49:22.652671Z","caller":"traceutil/trace.go:171","msg":"trace[213538529] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d39a4251-ff88-4ee7-b053-81a7e44ebe81; range_end:; response_count:1; response_revision:1889; }","duration":"198.733924ms","start":"2026-05-18T15:49:22.453865Z","end":"2026-05-18T15:49:22.652599Z","steps":["trace[213538529] 'agreement among raft nodes before linearized reading' (duration: 198.587335ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:49:22.652343Z","caller":"traceutil/trace.go:171","msg":"trace[1165864780] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"260.929115ms","start":"2026-05-18T15:49:22.391378Z","end":"2026-05-18T15:49:22.652307Z","steps":["trace[1165864780] 'process raft request' (duration: 252.253308ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:49:23.024167Z","caller":"traceutil/trace.go:171","msg":"trace[599175515] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"152.132217ms","start":"2026-05-18T15:49:22.871981Z","end":"2026-05-18T15:49:23.024113Z","steps":["trace[599175515] 'process raft request' (duration: 120.018339ms)","trace[599175515] 'compare' (duration: 31.816409ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:50:10.963104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.717532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:50:10.96322Z","caller":"traceutil/trace.go:171","msg":"trace[1617382552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2198; }","duration":"257.854931ms","start":"2026-05-18T15:50:10.705326Z","end":"2026-05-18T15:50:10.963181Z","steps":["trace[1617382552] 'range keys from in-memory index tree' (duration: 257.635246ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:50:10.963523Z","caller":"traceutil/trace.go:171","msg":"trace[1520881030] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"148.332851ms","start":"2026-05-18T15:50:10.815165Z","end":"2026-05-18T15:50:10.963498Z","steps":["trace[1520881030] 'process raft request' (duration: 60.095549ms)","trace[1520881030] 'compare' (duration: 88.121064ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:50:10.987588Z","caller":"traceutil/trace.go:171","msg":"trace[1142246617] linearizableReadLoop","detail":"{readStateIndex:2286; appliedIndex:2284; }","duration":"123.874145ms","start":"2026-05-18T15:50:10.863688Z","end":"2026-05-18T15:50:10.987562Z","steps":["trace[1142246617] 'read index received' (duration: 11.587983ms)","trace[1142246617] 'applied index is now lower than readState.Index' (duration: 112.284971ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:50:10.987826Z","caller":"traceutil/trace.go:171","msg":"trace[1129314422] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"134.970835ms","start":"2026-05-18T15:50:10.852743Z","end":"2026-05-18T15:50:10.987714Z","steps":["trace[1129314422] 'process raft request' (duration: 134.675605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:50:10.988179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.469306ms","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-18T15:50:10.988236Z","caller":"traceutil/trace.go:171","msg":"trace[365556003] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2200; }","duration":"124.538461ms","start":"2026-05-18T15:50:10.863681Z","end":"2026-05-18T15:50:10.98822Z","steps":["trace[365556003] 'agreement among raft nodes before linearized reading' (duration: 124.388161ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:50:11.156037Z","caller":"traceutil/trace.go:171","msg":"trace[1884890309] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"155.26045ms","start":"2026-05-18T15:50:11.000747Z","end":"2026-05-18T15:50:11.156007Z","steps":["trace[1884890309] 'process raft request' (duration: 79.944825ms)","trace[1884890309] 'compare' (duration: 75.169745ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:51:37.884938Z","caller":"traceutil/trace.go:171","msg":"trace[1416749935] linearizableReadLoop","detail":"{readStateIndex:2887; appliedIndex:2886; }","duration":"102.274167ms","start":"2026-05-18T15:51:37.782634Z","end":"2026-05-18T15:51:37.884908Z","steps":["trace[1416749935] 'read index received' (duration: 32.471608ms)","trace[1416749935] 'applied index is now lower than readState.Index' (duration: 69.800939ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:51:37.885109Z","caller":"traceutil/trace.go:171","msg":"trace[917351281] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"117.284584ms","start":"2026-05-18T15:51:37.767805Z","end":"2026-05-18T15:51:37.88509Z","steps":["trace[917351281] 'process raft request' (duration: 47.306403ms)","trace[917351281] 'compare' (duration: 69.659366ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:51:37.885381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.497031ms","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-18T15:51:37.885469Z","caller":"traceutil/trace.go:171","msg":"trace[1860328821] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2782; }","duration":"100.619735ms","start":"2026-05-18T15:51:37.784834Z","end":"2026-05-18T15:51:37.885454Z","steps":["trace[1860328821] 'agreement among raft nodes before linearized reading' (duration: 100.397826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:51:37.885807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.163375ms","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-18T15:51:37.885864Z","caller":"traceutil/trace.go:171","msg":"trace[145961772] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2782; }","duration":"103.2509ms","start":"2026-05-18T15:51:37.782599Z","end":"2026-05-18T15:51:37.88585Z","steps":["trace[145961772] 'agreement among raft nodes before linearized reading' (duration: 103.04964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:06.207182Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828732988103959,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:52:06.708242Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828732988103959,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:52:07.209301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828732988103959,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:52:07.70704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000083709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-18T15:52:07.707165Z","caller":"traceutil/trace.go:171","msg":"trace[2001944539] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000267104s","start":"2026-05-18T15:52:05.706878Z","end":"2026-05-18T15:52:07.707145Z","steps":["trace[2001944539] 'agreement among raft nodes before linearized reading' (duration: 2.000116218s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:07.707225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:05.706856Z","time spent":"2.000356293s","remote":"127.0.0.1:59906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/18 15:52:07 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-18T15:52:07.710263Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828732988103959,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:52:08.211141Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828732988103959,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:52:08.711779Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828732988103959,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:52:08.901886Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.256645777s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-18T15:52:08.903933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.671843992s","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-18T15:52:08.904045Z","caller":"traceutil/trace.go:171","msg":"trace[1634049386] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2927; }","duration":"2.671946189s","start":"2026-05-18T15:52:06.232063Z","end":"2026-05-18T15:52:08.904009Z","steps":["trace[1634049386] 'range keys from in-memory index tree' (duration: 2.671826162s)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:08.906985Z","caller":"traceutil/trace.go:171","msg":"trace[274858291] transaction","detail":"{read_only:false; response_revision:2928; number_of_response:1; }","duration":"3.261822815s","start":"2026-05-18T15:52:05.645138Z","end":"2026-05-18T15:52:08.906961Z","steps":["trace[274858291] 'process raft request' (duration: 3.257139765s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.907138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:05.645118Z","time spent":"3.261947152s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:52:08.908823Z","caller":"traceutil/trace.go:171","msg":"trace[1635665039] transaction","detail":"{read_only:false; response_revision:2929; number_of_response:1; }","duration":"2.748254457s","start":"2026-05-18T15:52:06.160524Z","end":"2026-05-18T15:52:08.908779Z","steps":["trace[1635665039] 'process raft request' (duration: 2.747608483s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.909046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:06.1605Z","time spent":"2.748425524s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:52:08.909498Z","caller":"traceutil/trace.go:171","msg":"trace[767145987] transaction","detail":"{read_only:false; response_revision:2930; number_of_response:1; }","duration":"1.797137459s","start":"2026-05-18T15:52:07.112327Z","end":"2026-05-18T15:52:08.909464Z","steps":["trace[767145987] 'process raft request' (duration: 1.796388607s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.90976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:07.112302Z","time spent":"1.797260547s","remote":"127.0.0.1:60148","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-18T15:52:08.910581Z","caller":"traceutil/trace.go:171","msg":"trace[791290013] transaction","detail":"{read_only:false; response_revision:2931; number_of_response:1; }","duration":"1.795530399s","start":"2026-05-18T15:52:07.115039Z","end":"2026-05-18T15:52:08.910569Z","steps":["trace[791290013] 'process raft request' (duration: 1.794360726s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.910653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:07.115028Z","time spent":"1.795591878s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:52:08.911357Z","caller":"traceutil/trace.go:171","msg":"trace[1515124147] transaction","detail":"{read_only:false; response_revision:2932; number_of_response:1; }","duration":"1.503362233s","start":"2026-05-18T15:52:07.407969Z","end":"2026-05-18T15:52:08.911331Z","steps":["trace[1515124147] 'process raft request' (duration: 1.502521563s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.911458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:07.407943Z","time spent":"1.50346469s","remote":"127.0.0.1:60148","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-18T15:52:08.911481Z","caller":"traceutil/trace.go:171","msg":"trace[1727684580] transaction","detail":"{read_only:false; response_revision:2933; number_of_response:1; }","duration":"1.31674523s","start":"2026-05-18T15:52:07.594725Z","end":"2026-05-18T15:52:08.91147Z","steps":["trace[1727684580] 'process raft request' (duration: 1.316533405s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.911537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:07.594695Z","time spent":"1.316810578s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:52:08.911995Z","caller":"traceutil/trace.go:171","msg":"trace[1252681338] linearizableReadLoop","detail":"{readStateIndex:3045; appliedIndex:3038; }","duration":"3.205036676s","start":"2026-05-18T15:52:05.706924Z","end":"2026-05-18T15:52:08.91196Z","steps":["trace[1252681338] 'read index received' (duration: 3.195336075s)","trace[1252681338] 'applied index is now lower than readState.Index' (duration: 9.699071ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:52:08.91211Z","caller":"traceutil/trace.go:171","msg":"trace[676485295] transaction","detail":"{read_only:false; response_revision:2934; number_of_response:1; }","duration":"1.264787808s","start":"2026-05-18T15:52:07.647298Z","end":"2026-05-18T15:52:08.912085Z","steps":["trace[676485295] 'process raft request' (duration: 1.264123064s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.91218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:07.647275Z","time spent":"1.264867516s","remote":"127.0.0.1:60148","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-18T15:52:08.912208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.895213115s","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-18T15:52:08.912259Z","caller":"traceutil/trace.go:171","msg":"trace[1620899229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2934; }","duration":"2.895296873s","start":"2026-05-18T15:52:06.016951Z","end":"2026-05-18T15:52:08.912248Z","steps":["trace[1620899229] 'agreement among raft nodes before linearized reading' (duration: 2.895179256s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.912301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:06.016933Z","time spent":"2.895353472s","remote":"127.0.0.1:60148","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-18T15:52:08.912459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.047681021s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-18T15:52:08.91248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.374285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T15:52:08.912511Z","caller":"traceutil/trace.go:171","msg":"trace[173956554] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2934; }","duration":"2.04780212s","start":"2026-05-18T15:52:06.864697Z","end":"2026-05-18T15:52:08.912499Z","steps":["trace[173956554] 'agreement among raft nodes before linearized reading' (duration: 2.047720551s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.912564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:06.86467Z","time spent":"2.047875238s","remote":"127.0.0.1:36626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-18T15:52:08.912669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322626619s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b0b3bd1a9e0077\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-18T15:52:08.912708Z","caller":"traceutil/trace.go:171","msg":"trace[2108329101] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b0b3bd1a9e0077; range_end:; response_count:1; response_revision:2934; }","duration":"1.322705568s","start":"2026-05-18T15:52:07.589987Z","end":"2026-05-18T15:52:08.912693Z","steps":["trace[2108329101] 'agreement among raft nodes before linearized reading' (duration: 1.32261803s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.912737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:07.589968Z","time spent":"1.322762446s","remote":"127.0.0.1:59962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18b0b3bd1a9e0077\" "} {"level":"warn","ts":"2026-05-18T15:52:08.912782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.547309423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:52:08.912824Z","caller":"traceutil/trace.go:171","msg":"trace[1306748809] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2934; }","duration":"2.547389881s","start":"2026-05-18T15:52:06.365423Z","end":"2026-05-18T15:52:08.912813Z","steps":["trace[1306748809] 'agreement among raft nodes before linearized reading' (duration: 2.547319562s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.912865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:06.365406Z","time spent":"2.54744963s","remote":"127.0.0.1:36640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-18T15:52:08.912985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.583356368s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:52:08.913052Z","caller":"traceutil/trace.go:171","msg":"trace[627435027] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:2934; }","duration":"1.583437356s","start":"2026-05-18T15:52:07.329575Z","end":"2026-05-18T15:52:08.913012Z","steps":["trace[627435027] 'agreement among raft nodes before linearized reading' (duration: 1.583300389s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.913097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.623079558s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-05-18T15:52:08.913119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:07.329555Z","time spent":"1.583553134s","remote":"127.0.0.1:60134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-05-18T15:52:08.91325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.665273168s","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-18T15:52:08.913338Z","caller":"traceutil/trace.go:171","msg":"trace[491390868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2934; }","duration":"2.665397095s","start":"2026-05-18T15:52:06.247906Z","end":"2026-05-18T15:52:08.913303Z","steps":["trace[491390868] 'agreement among raft nodes before linearized reading' (duration: 2.665249608s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.913386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:06.247888Z","time spent":"2.665482783s","remote":"127.0.0.1:60148","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-18T15:52:08.913138Z","caller":"traceutil/trace.go:171","msg":"trace[956258074] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2934; }","duration":"2.623137486s","start":"2026-05-18T15:52:06.289982Z","end":"2026-05-18T15:52:08.91312Z","steps":["trace[956258074] 'agreement among raft nodes before linearized reading' (duration: 2.623024519s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.91348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:06.289965Z","time spent":"2.623495948s","remote":"127.0.0.1:60092","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-18T15:52:08.913565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.861911006s","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-18T15:52:08.913599Z","caller":"traceutil/trace.go:171","msg":"trace[1264917252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2934; }","duration":"2.861990124s","start":"2026-05-18T15:52:06.051599Z","end":"2026-05-18T15:52:08.91359Z","steps":["trace[1264917252] 'agreement among raft nodes before linearized reading' (duration: 2.861892166s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.913633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:06.051545Z","time spent":"2.862082192s","remote":"127.0.0.1:60148","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-18T15:52:08.913767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.863317401s","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-18T15:52:08.913792Z","caller":"traceutil/trace.go:171","msg":"trace[285552093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2934; }","duration":"2.863379529s","start":"2026-05-18T15:52:06.050406Z","end":"2026-05-18T15:52:08.913785Z","steps":["trace[285552093] 'agreement among raft nodes before linearized reading' (duration: 2.863308961s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.913818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:06.050386Z","time spent":"2.863427029s","remote":"127.0.0.1:60148","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":"info","ts":"2026-05-18T15:52:08.912527Z","caller":"traceutil/trace.go:171","msg":"trace[1227444764] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2934; }","duration":"335.454873ms","start":"2026-05-18T15:52:08.577049Z","end":"2026-05-18T15:52:08.912504Z","steps":["trace[1227444764] 'agreement among raft nodes before linearized reading' (duration: 335.386934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.913913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:08.577032Z","time spent":"336.8701ms","remote":"127.0.0.1:60292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-18T15:52:08.912591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.205192252s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:52:08.914031Z","caller":"traceutil/trace.go:171","msg":"trace[912673517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2934; }","duration":"1.206688996s","start":"2026-05-18T15:52:07.707335Z","end":"2026-05-18T15:52:08.914024Z","steps":["trace[912673517] 'agreement among raft nodes before linearized reading' (duration: 1.205206061s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.91406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:07.70732Z","time spent":"1.206734405s","remote":"127.0.0.1:59896","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-18T15:52:08.914609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.660622568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-18T15:52:08.914683Z","caller":"traceutil/trace.go:171","msg":"trace[483121606] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2934; }","duration":"1.660745335s","start":"2026-05-18T15:52:07.25392Z","end":"2026-05-18T15:52:08.914666Z","steps":["trace[483121606] 'agreement among raft nodes before linearized reading' (duration: 1.65841496s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.914713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:07.253898Z","time spent":"1.660807834s","remote":"127.0.0.1:60078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-18T15:52:17.443186Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828732988104083,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-18T15:52:17.842894Z","caller":"traceutil/trace.go:171","msg":"trace[395714560] transaction","detail":"{read_only:false; response_revision:2972; number_of_response:1; }","duration":"907.671173ms","start":"2026-05-18T15:52:16.935196Z","end":"2026-05-18T15:52:17.842867Z","steps":["trace[395714560] 'process raft request' (duration: 907.460116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:17.843036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:16.935172Z","time spent":"907.794971ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:52:17.84376Z","caller":"traceutil/trace.go:171","msg":"trace[839355606] transaction","detail":"{read_only:false; response_revision:2973; number_of_response:1; }","duration":"904.52169ms","start":"2026-05-18T15:52:16.939206Z","end":"2026-05-18T15:52:17.843727Z","steps":["trace[839355606] 'process raft request' (duration: 904.313764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:17.844131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:16.93919Z","time spent":"904.794336ms","remote":"127.0.0.1:60148","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-18T15:52:17.84417Z","caller":"traceutil/trace.go:171","msg":"trace[214307100] transaction","detail":"{read_only:false; response_revision:2974; number_of_response:1; }","duration":"895.895329ms","start":"2026-05-18T15:52:16.948247Z","end":"2026-05-18T15:52:17.844142Z","steps":["trace[214307100] 'process raft request' (duration: 895.413277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:17.844298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:16.948237Z","time spent":"895.992097ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:52:17.844143Z","caller":"traceutil/trace.go:171","msg":"trace[1537965941] linearizableReadLoop","detail":"{readStateIndex:3089; appliedIndex:3085; }","duration":"903.798034ms","start":"2026-05-18T15:52:16.940316Z","end":"2026-05-18T15:52:17.844114Z","steps":["trace[1537965941] 'read index received' (duration: 902.33394ms)","trace[1537965941] 'applied index is now lower than readState.Index' (duration: 1.462754ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:52:17.844333Z","caller":"traceutil/trace.go:171","msg":"trace[1344259124] transaction","detail":"{read_only:false; response_revision:2975; number_of_response:1; }","duration":"888.550254ms","start":"2026-05-18T15:52:16.955734Z","end":"2026-05-18T15:52:17.844284Z","steps":["trace[1344259124] 'process raft request' (duration: 888.150831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:17.844603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.295202ms","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-18T15:52:17.84463Z","caller":"traceutil/trace.go:171","msg":"trace[158964198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2975; }","duration":"890.343562ms","start":"2026-05-18T15:52:16.954278Z","end":"2026-05-18T15:52:17.844622Z","steps":["trace[158964198] 'agreement among raft nodes before linearized reading' (duration: 890.159185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:17.84465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:16.954264Z","time spent":"890.381672ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-18T15:52:17.844756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.442912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b0b3bd1a9e0077\" ","response":"range_response_count:1 size:781"} {"level":"warn","ts":"2026-05-18T15:52:17.844761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:16.955713Z","time spent":"888.847649ms","remote":"127.0.0.1:60148","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-18T15:52:17.844831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.208093ms","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-18T15:52:17.844851Z","caller":"traceutil/trace.go:171","msg":"trace[104957395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2975; }","duration":"884.242973ms","start":"2026-05-18T15:52:16.960604Z","end":"2026-05-18T15:52:17.844847Z","steps":["trace[104957395] 'agreement among raft nodes before linearized reading' (duration: 884.194594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:17.844868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:16.960598Z","time spent":"884.266393ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-18T15:52:17.845003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.451439ms","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-18T15:52:17.845026Z","caller":"traceutil/trace.go:171","msg":"trace[1743432556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2975; }","duration":"884.504239ms","start":"2026-05-18T15:52:16.960516Z","end":"2026-05-18T15:52:17.84502Z","steps":["trace[1743432556] 'agreement among raft nodes before linearized reading' (duration: 884.44879ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:17.844787Z","caller":"traceutil/trace.go:171","msg":"trace[1552898062] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b0b3bd1a9e0077; range_end:; response_count:1; response_revision:2975; }","duration":"255.509441ms","start":"2026-05-18T15:52:17.589271Z","end":"2026-05-18T15:52:17.844781Z","steps":["trace[1552898062] 'agreement among raft nodes before linearized reading' (duration: 255.437392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:17.845089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.002262ms","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-18T15:52:17.845105Z","caller":"traceutil/trace.go:171","msg":"trace[1946340662] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2975; }","duration":"886.04929ms","start":"2026-05-18T15:52:16.959052Z","end":"2026-05-18T15:52:17.845101Z","steps":["trace[1946340662] 'agreement among raft nodes before linearized reading' (duration: 886.001561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:17.845128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:16.959037Z","time spent":"886.08702ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-18T15:52:17.845043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:16.960503Z","time spent":"884.536047ms","remote":"127.0.0.1:60148","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-18T15:52:17.845938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.609342ms","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-18T15:52:17.846016Z","caller":"traceutil/trace.go:171","msg":"trace[2074416635] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2975; }","duration":"905.72874ms","start":"2026-05-18T15:52:16.940267Z","end":"2026-05-18T15:52:17.845996Z","steps":["trace[2074416635] 'agreement among raft nodes before linearized reading' (duration: 903.910342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:17.846101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:16.940246Z","time spent":"905.841368ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-18T15:52:39.64277Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828732988104410,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:52:40.143456Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828732988104410,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:52:40.64414Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828732988104410,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:52:41.144667Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828732988104410,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:52:41.229307Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.291126873s","expected-duration":"1s"} {"level":"info","ts":"2026-05-18T15:52:41.229735Z","caller":"traceutil/trace.go:171","msg":"trace[1060436859] transaction","detail":"{read_only:false; response_revision:3088; number_of_response:1; }","duration":"2.29165157s","start":"2026-05-18T15:52:38.938059Z","end":"2026-05-18T15:52:41.229711Z","steps":["trace[1060436859] 'process raft request' (duration: 2.291471621s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.229937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:38.938037Z","time spent":"2.29180719s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:52:41.493064Z","caller":"traceutil/trace.go:171","msg":"trace[1945164871] transaction","detail":"{read_only:false; response_revision:3089; number_of_response:1; }","duration":"1.802560187s","start":"2026-05-18T15:52:39.690461Z","end":"2026-05-18T15:52:41.493021Z","steps":["trace[1945164871] 'process raft request' (duration: 1.794360711s)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:41.493128Z","caller":"traceutil/trace.go:171","msg":"trace[477647447] linearizableReadLoop","detail":"{readStateIndex:3211; appliedIndex:3205; }","duration":"2.351018606s","start":"2026-05-18T15:52:39.142094Z","end":"2026-05-18T15:52:41.493113Z","steps":["trace[477647447] 'read index received' (duration: 2.087420788s)","trace[477647447] 'applied index is now lower than readState.Index' (duration: 263.597028ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:52:41.493232Z","caller":"traceutil/trace.go:171","msg":"trace[532017531] transaction","detail":"{read_only:false; response_revision:3090; number_of_response:1; }","duration":"1.583322888s","start":"2026-05-18T15:52:39.909899Z","end":"2026-05-18T15:52:41.493222Z","steps":["trace[532017531] 'process raft request' (duration: 1.583004519s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.493233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.690442Z","time spent":"1.802706696s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:52:41.493292Z","caller":"traceutil/trace.go:171","msg":"trace[1999413787] transaction","detail":"{read_only:false; response_revision:3093; number_of_response:1; }","duration":"1.56773598s","start":"2026-05-18T15:52:39.925525Z","end":"2026-05-18T15:52:41.493261Z","steps":["trace[1999413787] 'process raft request' (duration: 1.567553841s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.493465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.279097574s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T15:52:41.493522Z","caller":"traceutil/trace.go:171","msg":"trace[1167239488] transaction","detail":"{read_only:false; response_revision:3092; number_of_response:1; }","duration":"1.568220239s","start":"2026-05-18T15:52:39.925293Z","end":"2026-05-18T15:52:41.493513Z","steps":["trace[1167239488] 'process raft request' (duration: 1.567754981s)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:41.493517Z","caller":"traceutil/trace.go:171","msg":"trace[793717968] transaction","detail":"{read_only:false; response_revision:3091; number_of_response:1; }","duration":"1.579295889s","start":"2026-05-18T15:52:39.914191Z","end":"2026-05-18T15:52:41.493487Z","steps":["trace[793717968] 'process raft request' (duration: 1.578804561s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.493592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.925275Z","time spent":"1.568283958s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:52:41.493593Z","caller":"traceutil/trace.go:171","msg":"trace[681324160] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:3093; }","duration":"2.279244773s","start":"2026-05-18T15:52:39.214302Z","end":"2026-05-18T15:52:41.493547Z","steps":["trace[681324160] 'agreement among raft nodes before linearized reading' (duration: 2.279030824s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.493672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.914178Z","time spent":"1.579407458s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T15:52:41.493732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.351642853s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-18T15:52:41.493707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.066961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-18T15:52:41.493865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.696885062s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-18T15:52:41.493872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.53555787s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-18T15:52:41.493884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.550353262s","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-18T15:52:41.493887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.560802518s","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-18T15:52:41.493913Z","caller":"traceutil/trace.go:171","msg":"trace[953167264] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:3093; }","duration":"1.696972892s","start":"2026-05-18T15:52:39.796927Z","end":"2026-05-18T15:52:41.4939Z","steps":["trace[953167264] 'agreement among raft nodes before linearized reading' (duration: 1.696901912s)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:41.493925Z","caller":"traceutil/trace.go:171","msg":"trace[413802533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3093; }","duration":"1.550425732s","start":"2026-05-18T15:52:39.943488Z","end":"2026-05-18T15:52:41.493914Z","steps":["trace[413802533] 'agreement among raft nodes before linearized reading' (duration: 1.550309642s)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:41.493924Z","caller":"traceutil/trace.go:171","msg":"trace[1216514356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3093; }","duration":"1.53566463s","start":"2026-05-18T15:52:39.958245Z","end":"2026-05-18T15:52:41.493909Z","steps":["trace[1216514356] 'agreement among raft nodes before linearized reading' (duration: 1.53553685s)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:41.493952Z","caller":"traceutil/trace.go:171","msg":"trace[2064412754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3093; }","duration":"1.560897527s","start":"2026-05-18T15:52:39.933027Z","end":"2026-05-18T15:52:41.493924Z","steps":["trace[2064412754] 'agreement among raft nodes before linearized reading' (duration: 1.560767467s)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:41.493901Z","caller":"traceutil/trace.go:171","msg":"trace[1321755125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3093; }","duration":"788.3125ms","start":"2026-05-18T15:52:40.705529Z","end":"2026-05-18T15:52:41.493841Z","steps":["trace[1321755125] 'agreement among raft nodes before linearized reading' (duration: 787.983382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.493977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.796902Z","time spent":"1.697060622s","remote":"127.0.0.1:36742","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/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-18T15:52:41.493991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.958229Z","time spent":"1.535751369s","remote":"127.0.0.1:60148","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-18T15:52:41.494001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.932969Z","time spent":"1.561016846s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-18T15:52:41.494064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.540962183s","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-18T15:52:41.494129Z","caller":"traceutil/trace.go:171","msg":"trace[576486922] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3093; }","duration":"1.541062003s","start":"2026-05-18T15:52:39.953056Z","end":"2026-05-18T15:52:41.494118Z","steps":["trace[576486922] 'agreement among raft nodes before linearized reading' (duration: 1.540758153s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.494075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.943474Z","time spent":"1.550586042s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-18T15:52:41.493463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.925515Z","time spent":"1.567860879s","remote":"127.0.0.1:60148","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-18T15:52:41.494075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:40.705513Z","time spent":"788.5439ms","remote":"127.0.0.1:59906","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-18T15:52:41.49422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.064773052s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-05-18T15:52:41.49423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.95304Z","time spent":"1.541145773s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-18T15:52:41.4938Z","caller":"traceutil/trace.go:171","msg":"trace[946455300] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:3093; }","duration":"2.351757202s","start":"2026-05-18T15:52:39.142027Z","end":"2026-05-18T15:52:41.493785Z","steps":["trace[946455300] 'agreement among raft nodes before linearized reading' (duration: 2.351569053s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.494388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.142004Z","time spent":"2.352367121s","remote":"127.0.0.1:59918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.169\" "} {"level":"warn","ts":"2026-05-18T15:52:41.493707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.214278Z","time spent":"2.279414533s","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-05-18T15:52:41.494272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.537722669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-18T15:52:41.493733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203557095s","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-18T15:52:41.494637Z","caller":"traceutil/trace.go:171","msg":"trace[650958274] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3093; }","duration":"1.204493712s","start":"2026-05-18T15:52:40.290125Z","end":"2026-05-18T15:52:41.494619Z","steps":["trace[650958274] 'agreement among raft nodes before linearized reading' (duration: 1.203483005s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.494704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:40.290106Z","time spent":"1.204579901s","remote":"127.0.0.1:60092","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-18T15:52:41.494285Z","caller":"traceutil/trace.go:171","msg":"trace[2131247507] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3093; }","duration":"2.064876211s","start":"2026-05-18T15:52:39.429394Z","end":"2026-05-18T15:52:41.49427Z","steps":["trace[2131247507] 'agreement among raft nodes before linearized reading' (duration: 2.064722512s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.49484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.429366Z","time spent":"2.065454179s","remote":"127.0.0.1:60078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-18T15:52:41.494492Z","caller":"traceutil/trace.go:171","msg":"trace[1403135319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3093; }","duration":"1.537983008s","start":"2026-05-18T15:52:39.956485Z","end":"2026-05-18T15:52:41.494468Z","steps":["trace[1403135319] 'agreement among raft nodes before linearized reading' (duration: 1.537014852s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.493319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.909874Z","time spent":"1.583392778s","remote":"127.0.0.1:60148","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-18T15:52:41.494915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.956463Z","time spent":"1.538435996s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-18T15:52:41.874195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.49208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T15:52:41.874418Z","caller":"traceutil/trace.go:171","msg":"trace[1557770666] transaction","detail":"{read_only:false; response_revision:3094; number_of_response:1; }","duration":"374.559619ms","start":"2026-05-18T15:52:41.499814Z","end":"2026-05-18T15:52:41.874374Z","steps":["trace[1557770666] 'process raft request' (duration: 165.636731ms)","trace[1557770666] 'compare' (duration: 208.337611ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:52:41.874694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:41.499792Z","time spent":"374.755768ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:52:41.874829Z","caller":"traceutil/trace.go:171","msg":"trace[1812783291] transaction","detail":"{read_only:false; response_revision:3095; number_of_response:1; }","duration":"374.028172ms","start":"2026-05-18T15:52:41.50079Z","end":"2026-05-18T15:52:41.874818Z","steps":["trace[1812783291] 'process raft request' (duration: 373.514634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.874891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:41.500779Z","time spent":"374.079312ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:52:41.87498Z","caller":"traceutil/trace.go:171","msg":"trace[166767813] transaction","detail":"{read_only:false; response_revision:3096; number_of_response:1; }","duration":"374.073082ms","start":"2026-05-18T15:52:41.500824Z","end":"2026-05-18T15:52:41.874897Z","steps":["trace[166767813] 'process raft request' (duration: 373.844963ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:41.875044Z","caller":"traceutil/trace.go:171","msg":"trace[441771369] transaction","detail":"{read_only:false; response_revision:3097; number_of_response:1; }","duration":"373.795314ms","start":"2026-05-18T15:52:41.501229Z","end":"2026-05-18T15:52:41.875024Z","steps":["trace[441771369] 'process raft request' (duration: 373.523084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.875157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:41.501222Z","time spent":"373.883094ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T15:52:41.875236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:41.500809Z","time spent":"374.231632ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:52:41.87536Z","caller":"traceutil/trace.go:171","msg":"trace[1633859835] linearizableReadLoop","detail":"{readStateIndex:3217; appliedIndex:3212; }","duration":"357.536743ms","start":"2026-05-18T15:52:41.51781Z","end":"2026-05-18T15:52:41.875347Z","steps":["trace[1633859835] 'read index received' (duration: 147.629008ms)","trace[1633859835] 'applied index is now lower than readState.Index' (duration: 209.906445ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:52:41.875414Z","caller":"traceutil/trace.go:171","msg":"trace[383629623] transaction","detail":"{read_only:false; response_revision:3098; number_of_response:1; }","duration":"373.593865ms","start":"2026-05-18T15:52:41.501779Z","end":"2026-05-18T15:52:41.875373Z","steps":["trace[383629623] 'process raft request' (duration: 373.188535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.875561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.076728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:52:41.875442Z","caller":"traceutil/trace.go:171","msg":"trace[1448467086] transaction","detail":"{read_only:false; response_revision:3099; number_of_response:1; }","duration":"356.785325ms","start":"2026-05-18T15:52:41.51864Z","end":"2026-05-18T15:52:41.875425Z","steps":["trace[1448467086] 'process raft request' (duration: 356.593606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.875586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:41.501772Z","time spent":"373.729124ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:52:41.875616Z","caller":"traceutil/trace.go:171","msg":"trace[1811550455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3099; }","duration":"169.160137ms","start":"2026-05-18T15:52:41.706443Z","end":"2026-05-18T15:52:41.875603Z","steps":["trace[1811550455] 'agreement among raft nodes before linearized reading' (duration: 169.078477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.875722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.157237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-18T15:52:41.875786Z","caller":"traceutil/trace.go:171","msg":"trace[2124399964] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3099; }","duration":"375.301757ms","start":"2026-05-18T15:52:41.500467Z","end":"2026-05-18T15:52:41.875769Z","steps":["trace[2124399964] 'agreement among raft nodes before linearized reading' (duration: 374.939488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.875721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:41.518625Z","time spent":"357.013234ms","remote":"127.0.0.1:59918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T15:52:41.87594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:41.500455Z","time spent":"375.467216ms","remote":"127.0.0.1:60078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-18T15:52:41.87625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.764612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1445"} {"level":"info","ts":"2026-05-18T15:52:41.876301Z","caller":"traceutil/trace.go:171","msg":"trace[237283662] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:3099; }","duration":"376.845281ms","start":"2026-05-18T15:52:41.499445Z","end":"2026-05-18T15:52:41.87629Z","steps":["trace[237283662] 'agreement among raft nodes before linearized reading' (duration: 375.998125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.876334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:41.499429Z","time spent":"376.898021ms","remote":"127.0.0.1:60092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1468,"request content":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" "} {"level":"info","ts":"2026-05-18T15:52:43.673654Z","caller":"traceutil/trace.go:171","msg":"trace[2068836660] transaction","detail":"{read_only:false; response_revision:3104; number_of_response:1; }","duration":"172.424709ms","start":"2026-05-18T15:52:43.501207Z","end":"2026-05-18T15:52:43.673632Z","steps":["trace[2068836660] 'process raft request' (duration: 172.380069ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:43.673824Z","caller":"traceutil/trace.go:171","msg":"trace[1590490687] transaction","detail":"{read_only:false; response_revision:3102; number_of_response:1; }","duration":"173.730094ms","start":"2026-05-18T15:52:43.500057Z","end":"2026-05-18T15:52:43.673787Z","steps":["trace[1590490687] 'process raft request' (duration: 155.342272ms)","trace[1590490687] 'compare' (duration: 18.061013ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:52:43.673922Z","caller":"traceutil/trace.go:171","msg":"trace[1712018630] transaction","detail":"{read_only:false; response_revision:3103; number_of_response:1; }","duration":"172.918507ms","start":"2026-05-18T15:52:43.500943Z","end":"2026-05-18T15:52:43.673862Z","steps":["trace[1712018630] 'process raft request' (duration: 172.607238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:44.481105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.639264ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-18T15:52:44.481196Z","caller":"traceutil/trace.go:171","msg":"trace[1692566147] linearizableReadLoop","detail":"{readStateIndex:3229; appliedIndex:3228; }","duration":"185.92033ms","start":"2026-05-18T15:52:44.29526Z","end":"2026-05-18T15:52:44.48118Z","steps":["trace[1692566147] 'read index received' (duration: 72.178886ms)","trace[1692566147] 'applied index is now lower than readState.Index' (duration: 113.739944ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:52:44.481564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.299168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-05-18T15:52:44.481619Z","caller":"traceutil/trace.go:171","msg":"trace[153178188] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3109; }","duration":"186.390038ms","start":"2026-05-18T15:52:44.295218Z","end":"2026-05-18T15:52:44.481608Z","steps":["trace[153178188] 'agreement among raft nodes before linearized reading' (duration: 186.067879ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:44.53427Z","caller":"traceutil/trace.go:171","msg":"trace[309535061] transaction","detail":"{read_only:false; response_revision:3110; number_of_response:1; }","duration":"116.890715ms","start":"2026-05-18T15:52:44.417356Z","end":"2026-05-18T15:52:44.534247Z","steps":["trace[309535061] 'process raft request' (duration: 116.493476ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:45.801065Z","caller":"traceutil/trace.go:171","msg":"trace[110197354] transaction","detail":"{read_only:false; response_revision:3113; number_of_response:1; }","duration":"122.093772ms","start":"2026-05-18T15:52:45.678947Z","end":"2026-05-18T15:52:45.801041Z","steps":["trace[110197354] 'process raft request' (duration: 121.946652ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:45.801447Z","caller":"traceutil/trace.go:171","msg":"trace[590245888] transaction","detail":"{read_only:false; response_revision:3114; number_of_response:1; }","duration":"121.456242ms","start":"2026-05-18T15:52:45.679959Z","end":"2026-05-18T15:52:45.801415Z","steps":["trace[590245888] 'process raft request' (duration: 121.044713ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:46.167358Z","caller":"traceutil/trace.go:171","msg":"trace[786746723] transaction","detail":"{read_only:false; response_revision:3116; number_of_response:1; }","duration":"154.694216ms","start":"2026-05-18T15:52:46.012638Z","end":"2026-05-18T15:52:46.167332Z","steps":["trace[786746723] 'process raft request' (duration: 150.867032ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:46.167454Z","caller":"traceutil/trace.go:171","msg":"trace[1032446241] transaction","detail":"{read_only:false; response_revision:3117; number_of_response:1; }","duration":"154.739637ms","start":"2026-05-18T15:52:46.012695Z","end":"2026-05-18T15:52:46.167434Z","steps":["trace[1032446241] 'process raft request' (duration: 154.507927ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:46.167462Z","caller":"traceutil/trace.go:171","msg":"trace[118970048] transaction","detail":"{read_only:false; response_revision:3118; number_of_response:1; }","duration":"154.662897ms","start":"2026-05-18T15:52:46.012766Z","end":"2026-05-18T15:52:46.167429Z","steps":["trace[118970048] 'process raft request' (duration: 154.556337ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:46.167583Z","caller":"traceutil/trace.go:171","msg":"trace[1369868094] transaction","detail":"{read_only:false; response_revision:3119; number_of_response:1; }","duration":"154.777036ms","start":"2026-05-18T15:52:46.012781Z","end":"2026-05-18T15:52:46.167558Z","steps":["trace[1369868094] 'process raft request' (duration: 154.595357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:46.396422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.361168ms","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-18T15:52:46.396673Z","caller":"traceutil/trace.go:171","msg":"trace[2028464455] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3119; }","duration":"107.669468ms","start":"2026-05-18T15:52:46.288975Z","end":"2026-05-18T15:52:46.396645Z","steps":["trace[2028464455] 'range keys from in-memory index tree' (duration: 107.097927ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:47.807245Z","caller":"traceutil/trace.go:171","msg":"trace[822673085] transaction","detail":"{read_only:false; response_revision:3121; number_of_response:1; }","duration":"188.99324ms","start":"2026-05-18T15:52:47.61822Z","end":"2026-05-18T15:52:47.807213Z","steps":["trace[822673085] 'process raft request' (duration: 100.749768ms)","trace[822673085] 'compare' (duration: 88.112423ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:52:48.291235Z","caller":"traceutil/trace.go:171","msg":"trace[645232421] transaction","detail":"{read_only:false; response_revision:3126; number_of_response:1; }","duration":"116.865566ms","start":"2026-05-18T15:52:48.174345Z","end":"2026-05-18T15:52:48.29121Z","steps":["trace[645232421] 'process raft request' (duration: 100.205428ms)","trace[645232421] 'compare' (duration: 16.557528ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:52:48.309321Z","caller":"traceutil/trace.go:171","msg":"trace[156735279] transaction","detail":"{read_only:false; response_revision:3127; number_of_response:1; }","duration":"134.497202ms","start":"2026-05-18T15:52:48.174813Z","end":"2026-05-18T15:52:48.30931Z","steps":["trace[156735279] 'process raft request' (duration: 134.412832ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:48.309649Z","caller":"traceutil/trace.go:171","msg":"trace[1087486871] transaction","detail":"{read_only:false; response_revision:3128; number_of_response:1; }","duration":"134.636054ms","start":"2026-05-18T15:52:48.174985Z","end":"2026-05-18T15:52:48.309621Z","steps":["trace[1087486871] 'process raft request' (duration: 134.297863ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:48.309673Z","caller":"traceutil/trace.go:171","msg":"trace[1988126830] transaction","detail":"{read_only:false; response_revision:3129; number_of_response:1; }","duration":"134.492723ms","start":"2026-05-18T15:52:48.17515Z","end":"2026-05-18T15:52:48.309643Z","steps":["trace[1988126830] 'process raft request' (duration: 134.334222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:48.60104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.169961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-18T15:52:48.601139Z","caller":"traceutil/trace.go:171","msg":"trace[2105166932] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3129; }","duration":"124.338761ms","start":"2026-05-18T15:52:48.476776Z","end":"2026-05-18T15:52:48.601115Z","steps":["trace[2105166932] 'range keys from in-memory index tree' (duration: 123.88364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:48.878433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.390154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:52:48.878684Z","caller":"traceutil/trace.go:171","msg":"trace[1370769459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3130; }","duration":"173.691064ms","start":"2026-05-18T15:52:48.704962Z","end":"2026-05-18T15:52:48.878653Z","steps":["trace[1370769459] 'range keys from in-memory index tree' (duration: 173.232634ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:48.878564Z","caller":"traceutil/trace.go:171","msg":"trace[1846944699] transaction","detail":"{read_only:false; response_revision:3131; number_of_response:1; }","duration":"144.113366ms","start":"2026-05-18T15:52:48.734413Z","end":"2026-05-18T15:52:48.878526Z","steps":["trace[1846944699] 'process raft request' (duration: 117.953284ms)","trace[1846944699] 'compare' (duration: 25.637262ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:52:49.988685Z","caller":"traceutil/trace.go:171","msg":"trace[814257335] transaction","detail":"{read_only:false; response_revision:3137; number_of_response:1; }","duration":"115.467042ms","start":"2026-05-18T15:52:49.873194Z","end":"2026-05-18T15:52:49.988661Z","steps":["trace[814257335] 'process raft request' (duration: 115.373002ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:49.988696Z","caller":"traceutil/trace.go:171","msg":"trace[1305385332] transaction","detail":"{read_only:false; response_revision:3136; number_of_response:1; }","duration":"115.502692ms","start":"2026-05-18T15:52:49.873154Z","end":"2026-05-18T15:52:49.988657Z","steps":["trace[1305385332] 'process raft request' (duration: 72.570643ms)","trace[1305385332] 'compare' (duration: 42.718649ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:55:29.313231Z","caller":"traceutil/trace.go:171","msg":"trace[2063560741] transaction","detail":"{read_only:false; response_revision:4654; number_of_response:1; }","duration":"120.044652ms","start":"2026-05-18T15:55:29.19315Z","end":"2026-05-18T15:55:29.313195Z","steps":["trace[2063560741] 'process raft request' (duration: 86.366541ms)","trace[2063560741] 'compare' (duration: 33.324206ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:55:29.313493Z","caller":"traceutil/trace.go:171","msg":"trace[310091864] transaction","detail":"{read_only:false; response_revision:4655; number_of_response:1; }","duration":"107.509505ms","start":"2026-05-18T15:55:29.205908Z","end":"2026-05-18T15:55:29.313418Z","steps":["trace[310091864] 'process raft request' (duration: 107.096707ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:55:35.249097Z","caller":"traceutil/trace.go:171","msg":"trace[1583924590] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"170.595208ms","start":"2026-05-18T15:55:35.078476Z","end":"2026-05-18T15:55:35.249071Z","steps":["trace[1583924590] 'process raft request' (duration: 170.433221ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:55:35.432934Z","caller":"traceutil/trace.go:171","msg":"trace[1567398129] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"344.082414ms","start":"2026-05-18T15:55:35.088801Z","end":"2026-05-18T15:55:35.432883Z","steps":["trace[1567398129] 'process raft request' (duration: 343.715888ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:55:35.433138Z","caller":"traceutil/trace.go:171","msg":"trace[1895901444] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"344.195669ms","start":"2026-05-18T15:55:35.08893Z","end":"2026-05-18T15:55:35.433126Z","steps":["trace[1895901444] 'process raft request' (duration: 343.932208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:55:35.43321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:55:35.088782Z","time spent":"344.337176ms","remote":"127.0.0.1:60158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1869,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:55:35.432951Z","caller":"traceutil/trace.go:171","msg":"trace[2103141898] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"344.028782ms","start":"2026-05-18T15:55:35.0889Z","end":"2026-05-18T15:55:35.432929Z","steps":["trace[2103141898] 'process raft request' (duration: 343.877436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:55:35.433359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:55:35.088909Z","time spent":"344.318875ms","remote":"127.0.0.1:60158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1455,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:55:35.433449Z","caller":"traceutil/trace.go:171","msg":"trace[773122279] linearizableReadLoop","detail":"{readStateIndex:4903; appliedIndex:4900; }","duration":"344.501263ms","start":"2026-05-18T15:55:35.088928Z","end":"2026-05-18T15:55:35.433429Z","steps":["trace[773122279] 'read index received' (duration: 160.06909ms)","trace[773122279] 'applied index is now lower than readState.Index' (duration: 184.431063ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:55:35.433575Z","caller":"traceutil/trace.go:171","msg":"trace[314116440] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"344.577946ms","start":"2026-05-18T15:55:35.088984Z","end":"2026-05-18T15:55:35.433562Z","steps":["trace[314116440] 'process raft request' (duration: 344.066654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:55:35.433744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:55:35.088977Z","time spent":"344.680041ms","remote":"127.0.0.1:60066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1283,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T15:55:35.433743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:55:35.088882Z","time spent":"344.551246ms","remote":"127.0.0.1:60066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T15:55:35.433761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.810817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-86b7468cf5-46lkh\" ","response":"range_response_count:1 size:11257"} {"level":"info","ts":"2026-05-18T15:55:35.434014Z","caller":"traceutil/trace.go:171","msg":"trace[602487876] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-86b7468cf5-46lkh; range_end:; response_count:1; response_revision:4719; }","duration":"345.087009ms","start":"2026-05-18T15:55:35.088906Z","end":"2026-05-18T15:55:35.433993Z","steps":["trace[602487876] 'agreement among raft nodes before linearized reading' (duration: 344.729424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:55:35.434111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:55:35.088899Z","time spent":"345.192134ms","remote":"127.0.0.1:60078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11280,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-86b7468cf5-46lkh\" "} {"level":"warn","ts":"2026-05-18T15:55:35.442493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.3413ms","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-18T15:55:35.442556Z","caller":"traceutil/trace.go:171","msg":"trace[87206225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4720; }","duration":"162.433864ms","start":"2026-05-18T15:55:35.280108Z","end":"2026-05-18T15:55:35.442542Z","steps":["trace[87206225] 'agreement among raft nodes before linearized reading' (duration: 162.217005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:55:35.442582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.439102ms","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-18T15:55:35.442616Z","caller":"traceutil/trace.go:171","msg":"trace[595293958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4720; }","duration":"168.497134ms","start":"2026-05-18T15:55:35.274105Z","end":"2026-05-18T15:55:35.442602Z","steps":["trace[595293958] 'agreement among raft nodes before linearized reading' (duration: 168.431871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:55:35.442733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.399189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T15:55:35.442747Z","caller":"traceutil/trace.go:171","msg":"trace[1245378129] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"188.901741ms","start":"2026-05-18T15:55:35.253838Z","end":"2026-05-18T15:55:35.44274Z","steps":["trace[1245378129] 'process raft request' (duration: 188.357197ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:55:35.442752Z","caller":"traceutil/trace.go:171","msg":"trace[524730206] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:4720; }","duration":"219.476701ms","start":"2026-05-18T15:55:35.223268Z","end":"2026-05-18T15:55:35.442745Z","steps":["trace[524730206] 'agreement among raft nodes before linearized reading' (duration: 219.43758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:55:35.442883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.892875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:55:35.442903Z","caller":"traceutil/trace.go:171","msg":"trace[2074482936] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4720; }","duration":"314.935407ms","start":"2026-05-18T15:55:35.127962Z","end":"2026-05-18T15:55:35.442897Z","steps":["trace[2074482936] 'agreement among raft nodes before linearized reading' (duration: 314.904186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:55:35.442922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:55:35.12795Z","time spent":"314.965699ms","remote":"127.0.0.1:60318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-18T15:55:35.442927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.181262ms","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-18T15:55:35.442945Z","caller":"traceutil/trace.go:171","msg":"trace[1516297735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4720; }","duration":"162.224514ms","start":"2026-05-18T15:55:35.280716Z","end":"2026-05-18T15:55:35.44294Z","steps":["trace[1516297735] 'agreement among raft nodes before linearized reading' (duration: 162.173712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:55:35.442499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.388509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-18T15:55:35.443017Z","caller":"traceutil/trace.go:171","msg":"trace[358309856] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4720; }","duration":"340.970206ms","start":"2026-05-18T15:55:35.102036Z","end":"2026-05-18T15:55:35.443007Z","steps":["trace[358309856] 'agreement among raft nodes before linearized reading' (duration: 340.285845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:55:35.443037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:55:35.102017Z","time spent":"341.011838ms","remote":"127.0.0.1:60060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-05-18T15:55:35.44306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.207976ms","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-18T15:55:35.443078Z","caller":"traceutil/trace.go:171","msg":"trace[1977315074] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4720; }","duration":"323.268699ms","start":"2026-05-18T15:55:35.119804Z","end":"2026-05-18T15:55:35.443073Z","steps":["trace[1977315074] 'agreement among raft nodes before linearized reading' (duration: 323.200526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:55:35.443097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:55:35.119781Z","time spent":"323.309781ms","remote":"127.0.0.1:60148","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":"info","ts":"2026-05-18T15:55:35.848398Z","caller":"traceutil/trace.go:171","msg":"trace[674748714] linearizableReadLoop","detail":"{readStateIndex:4917; appliedIndex:4916; }","duration":"143.034012ms","start":"2026-05-18T15:55:35.705346Z","end":"2026-05-18T15:55:35.84838Z","steps":["trace[674748714] 'read index received' (duration: 142.866445ms)","trace[674748714] 'applied index is now lower than readState.Index' (duration: 166.617µs)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:55:35.848514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.158107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:55:35.848539Z","caller":"traceutil/trace.go:171","msg":"trace[1863558860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4731; }","duration":"143.230181ms","start":"2026-05-18T15:55:35.705301Z","end":"2026-05-18T15:55:35.848532Z","steps":["trace[1863558860] 'agreement among raft nodes before linearized reading' (duration: 143.153668ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:55:35.848817Z","caller":"traceutil/trace.go:171","msg":"trace[519906919] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"175.510436ms","start":"2026-05-18T15:55:35.673299Z","end":"2026-05-18T15:55:35.848809Z","steps":["trace[519906919] 'process raft request' (duration: 174.9249ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:55:50.86323Z","caller":"traceutil/trace.go:171","msg":"trace[1390074210] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"127.33638ms","start":"2026-05-18T15:55:50.735858Z","end":"2026-05-18T15:55:50.863194Z","steps":["trace[1390074210] 'process raft request' (duration: 67.504838ms)","trace[1390074210] 'compare' (duration: 59.651114ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:56:55.94582Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2875} {"level":"info","ts":"2026-05-18T15:56:56.024718Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2875,"took":"77.317783ms","hash":3834875569,"current-db-size-bytes":53071872,"current-db-size":"53 MB","current-db-size-in-use-bytes":28614656,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-18T15:56:56.024768Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3834875569,"revision":2875,"compact-revision":-1} {"level":"warn","ts":"2026-05-18T15:59:42.719206Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828732988112918,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:59:43.220143Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828732988112918,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:59:43.461315Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.401784691s","expected-duration":"1s"} {"level":"info","ts":"2026-05-18T15:59:43.461547Z","caller":"traceutil/trace.go:171","msg":"trace[653442098] transaction","detail":"{read_only:false; response_revision:6616; number_of_response:1; }","duration":"1.41386583s","start":"2026-05-18T15:59:42.047666Z","end":"2026-05-18T15:59:43.461532Z","steps":["trace[653442098] 'process raft request' (duration: 1.413786036s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:43.461634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:42.047643Z","time spent":"1.413950256s","remote":"127.0.0.1:59962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":724,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-18T15:59:43.751151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.765953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T15:59:43.751469Z","caller":"traceutil/trace.go:171","msg":"trace[989602578] transaction","detail":"{read_only:false; response_revision:6619; number_of_response:1; }","duration":"277.785712ms","start":"2026-05-18T15:59:43.47367Z","end":"2026-05-18T15:59:43.751456Z","steps":["trace[989602578] 'process raft request' (duration: 277.74749ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:59:43.751489Z","caller":"traceutil/trace.go:171","msg":"trace[1933946495] transaction","detail":"{read_only:false; response_revision:6617; number_of_response:1; }","duration":"1.356861245s","start":"2026-05-18T15:59:42.394515Z","end":"2026-05-18T15:59:43.751376Z","steps":["trace[1933946495] 'process raft request' (duration: 1.1387227s)","trace[1933946495] 'compare' (duration: 217.334769ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:59:43.751591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:42.39449Z","time spent":"1.357046297s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:59:43.751835Z","caller":"traceutil/trace.go:171","msg":"trace[1889560191] transaction","detail":"{read_only:false; response_revision:6618; number_of_response:1; }","duration":"509.072642ms","start":"2026-05-18T15:59:43.242751Z","end":"2026-05-18T15:59:43.751824Z","steps":["trace[1889560191] 'process raft request' (duration: 508.550071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:43.751903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:43.242727Z","time spent":"509.142795ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:59:43.75221Z","caller":"traceutil/trace.go:171","msg":"trace[790259972] linearizableReadLoop","detail":"{readStateIndex:6864; appliedIndex:6862; }","duration":"1.533308456s","start":"2026-05-18T15:59:42.218666Z","end":"2026-05-18T15:59:43.751975Z","steps":["trace[790259972] 'read index received' (duration: 1.242799575s)","trace[790259972] 'applied index is now lower than readState.Index' (duration: 290.507731ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:59:43.752507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.533844908s","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-18T15:59:43.752547Z","caller":"traceutil/trace.go:171","msg":"trace[1314087083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6619; }","duration":"1.533922442s","start":"2026-05-18T15:59:42.218615Z","end":"2026-05-18T15:59:43.752537Z","steps":["trace[1314087083] 'agreement among raft nodes before linearized reading' (duration: 1.533795715s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:43.752652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:42.218596Z","time spent":"1.534023408s","remote":"127.0.0.1:60148","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-18T15:59:44.047976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.274038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T15:59:44.048107Z","caller":"traceutil/trace.go:171","msg":"trace[889471143] transaction","detail":"{read_only:false; response_revision:6620; number_of_response:1; }","duration":"458.319189ms","start":"2026-05-18T15:59:43.589768Z","end":"2026-05-18T15:59:44.048087Z","steps":["trace[889471143] 'process raft request' (duration: 267.828738ms)","trace[889471143] 'compare' (duration: 190.143611ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:59:44.048197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:43.58974Z","time spent":"458.425085ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:59:44.103194Z","caller":"traceutil/trace.go:171","msg":"trace[529888022] linearizableReadLoop","detail":"{readStateIndex:6868; appliedIndex:6866; }","duration":"350.910124ms","start":"2026-05-18T15:59:43.752255Z","end":"2026-05-18T15:59:44.103166Z","steps":["trace[529888022] 'read index received' (duration: 105.353565ms)","trace[529888022] 'applied index is now lower than readState.Index' (duration: 245.555769ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:59:44.10328Z","caller":"traceutil/trace.go:171","msg":"trace[731891489] transaction","detail":"{read_only:false; response_revision:6622; number_of_response:1; }","duration":"345.145434ms","start":"2026-05-18T15:59:43.758098Z","end":"2026-05-18T15:59:44.103243Z","steps":["trace[731891489] 'process raft request' (duration: 345.030707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:44.103426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270056634s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/horizon-db-sync-q7v5v\" ","response":"range_response_count:1 size:6548"} {"level":"info","ts":"2026-05-18T15:59:44.103485Z","caller":"traceutil/trace.go:171","msg":"trace[1181746269] transaction","detail":"{read_only:false; response_revision:6621; number_of_response:1; }","duration":"385.406433ms","start":"2026-05-18T15:59:43.718069Z","end":"2026-05-18T15:59:44.103475Z","steps":["trace[1181746269] 'process raft request' (duration: 384.890992ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:59:44.103488Z","caller":"traceutil/trace.go:171","msg":"trace[130360617] range","detail":"{range_begin:/registry/pods/openstack/horizon-db-sync-q7v5v; range_end:; response_count:1; response_revision:6622; }","duration":"1.270147899s","start":"2026-05-18T15:59:42.833326Z","end":"2026-05-18T15:59:44.103474Z","steps":["trace[130360617] 'agreement among raft nodes before linearized reading' (duration: 1.269972507s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:44.103508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.398445549s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-18T15:59:44.103534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:42.833312Z","time spent":"1.270211033s","remote":"127.0.0.1:60078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":6571,"request content":"key:\"/registry/pods/openstack/horizon-db-sync-q7v5v\" "} {"level":"warn","ts":"2026-05-18T15:59:44.103543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:43.718042Z","time spent":"385.471126ms","remote":"127.0.0.1:60148","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-18T15:59:44.103589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.487857825s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T15:59:44.103655Z","caller":"traceutil/trace.go:171","msg":"trace[1067176879] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:6622; }","duration":"1.48795501s","start":"2026-05-18T15:59:42.615687Z","end":"2026-05-18T15:59:44.103643Z","steps":["trace[1067176879] 'agreement among raft nodes before linearized reading' (duration: 1.487870406s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:44.103716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:42.61567Z","time spent":"1.488008144s","remote":"127.0.0.1:33192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":30,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-05-18T15:59:44.103795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.253076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"info","ts":"2026-05-18T15:59:44.103835Z","caller":"traceutil/trace.go:171","msg":"trace[1215631936] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6622; }","duration":"482.324261ms","start":"2026-05-18T15:59:43.6215Z","end":"2026-05-18T15:59:44.103824Z","steps":["trace[1215631936] 'agreement among raft nodes before linearized reading' (duration: 482.158271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:44.103863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:43.621482Z","time spent":"482.375334ms","remote":"127.0.0.1:60134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4880,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"warn","ts":"2026-05-18T15:59:44.104077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.402589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"info","ts":"2026-05-18T15:59:44.104113Z","caller":"traceutil/trace.go:171","msg":"trace[1732337795] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6622; }","duration":"346.463922ms","start":"2026-05-18T15:59:43.757641Z","end":"2026-05-18T15:59:44.104105Z","steps":["trace[1732337795] 'agreement among raft nodes before linearized reading' (duration: 346.366537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:44.104137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:43.757626Z","time spent":"346.505105ms","remote":"127.0.0.1:60134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4880,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"warn","ts":"2026-05-18T15:59:44.104428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.263191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-05-18T15:59:44.104937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256952632s","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-18T15:59:44.104978Z","caller":"traceutil/trace.go:171","msg":"trace[1497128501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6622; }","duration":"1.257014907s","start":"2026-05-18T15:59:42.847953Z","end":"2026-05-18T15:59:44.104968Z","steps":["trace[1497128501] 'agreement among raft nodes before linearized reading' (duration: 1.256908441s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:44.105016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:42.847943Z","time spent":"1.257066519s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-18T15:59:44.105118Z","caller":"traceutil/trace.go:171","msg":"trace[377606460] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:6622; }","duration":"512.382208ms","start":"2026-05-18T15:59:43.592103Z","end":"2026-05-18T15:59:44.104485Z","steps":["trace[377606460] 'agreement among raft nodes before linearized reading' (duration: 512.147504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:44.105199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.249215548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-18T15:59:44.10522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:43.592059Z","time spent":"513.144553ms","remote":"127.0.0.1:60060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-05-18T15:59:44.105233Z","caller":"traceutil/trace.go:171","msg":"trace[35210733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6622; }","duration":"1.249267951s","start":"2026-05-18T15:59:42.855957Z","end":"2026-05-18T15:59:44.105225Z","steps":["trace[35210733] 'agreement among raft nodes before linearized reading' (duration: 1.249187326s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:44.105262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:42.855947Z","time spent":"1.249308874s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-18T15:59:44.105602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.25063523s","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-18T15:59:44.10564Z","caller":"traceutil/trace.go:171","msg":"trace[1679455388] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6622; }","duration":"1.250766009s","start":"2026-05-18T15:59:42.854865Z","end":"2026-05-18T15:59:44.105631Z","steps":["trace[1679455388] 'agreement among raft nodes before linearized reading' (duration: 1.250667013s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:44.105676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:42.854854Z","time spent":"1.250813551s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-18T15:59:44.103543Z","caller":"traceutil/trace.go:171","msg":"trace[787929514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6622; }","duration":"1.398513704s","start":"2026-05-18T15:59:42.705017Z","end":"2026-05-18T15:59:44.103531Z","steps":["trace[787929514] 'agreement among raft nodes before linearized reading' (duration: 1.398466321s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:44.105884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.559498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"warn","ts":"2026-05-18T15:59:44.10344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:43.758072Z","time spent":"345.293384ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T15:59:44.105868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:42.704997Z","time spent":"1.400853961s","remote":"127.0.0.1:59906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-18T15:59:44.105928Z","caller":"traceutil/trace.go:171","msg":"trace[1281392926] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6622; }","duration":"304.843745ms","start":"2026-05-18T15:59:43.801076Z","end":"2026-05-18T15:59:44.10592Z","steps":["trace[1281392926] 'agreement among raft nodes before linearized reading' (duration: 303.425522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:44.105986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:43.801056Z","time spent":"304.921109ms","remote":"127.0.0.1:60134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4880,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"warn","ts":"2026-05-18T15:59:44.103437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322864988s","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-18T15:59:44.106081Z","caller":"traceutil/trace.go:171","msg":"trace[1967176535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6622; }","duration":"1.325536764s","start":"2026-05-18T15:59:42.780518Z","end":"2026-05-18T15:59:44.106055Z","steps":["trace[1967176535] 'agreement among raft nodes before linearized reading' (duration: 1.322783242s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:44.106128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:42.780498Z","time spent":"1.325618398s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-18T15:59:44.107146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.742456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:59:44.107225Z","caller":"traceutil/trace.go:171","msg":"trace[2141828986] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:6622; }","duration":"842.71221ms","start":"2026-05-18T15:59:43.264489Z","end":"2026-05-18T15:59:44.107202Z","steps":["trace[2141828986] 'agreement among raft nodes before linearized reading' (duration: 838.710154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:44.107285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:43.264447Z","time spent":"842.820396ms","remote":"127.0.0.1:36714","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/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-18T15:59:44.549569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.94771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T15:59:44.549726Z","caller":"traceutil/trace.go:171","msg":"trace[752775273] transaction","detail":"{read_only:false; response_revision:6623; number_of_response:1; }","duration":"437.103378ms","start":"2026-05-18T15:59:44.112597Z","end":"2026-05-18T15:59:44.549701Z","steps":["trace[752775273] 'process raft request' (duration: 76.571363ms)","trace[752775273] 'compare' (duration: 359.178075ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:59:44.549811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:44.112563Z","time spent":"437.204824ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:59:44.611915Z","caller":"traceutil/trace.go:171","msg":"trace[469008229] transaction","detail":"{read_only:false; response_revision:6624; number_of_response:1; }","duration":"498.821838ms","start":"2026-05-18T15:59:44.113061Z","end":"2026-05-18T15:59:44.611883Z","steps":["trace[469008229] 'process raft request' (duration: 498.597165ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:59:44.611934Z","caller":"traceutil/trace.go:171","msg":"trace[733557801] linearizableReadLoop","detail":"{readStateIndex:6872; appliedIndex:6869; }","duration":"497.216184ms","start":"2026-05-18T15:59:44.114694Z","end":"2026-05-18T15:59:44.611911Z","steps":["trace[733557801] 'read index received' (duration: 74.63472ms)","trace[733557801] 'applied index is now lower than readState.Index' (duration: 422.580594ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:59:44.612075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:44.113036Z","time spent":"498.959616ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:59:44.612043Z","caller":"traceutil/trace.go:171","msg":"trace[94121951] transaction","detail":"{read_only:false; response_revision:6625; number_of_response:1; }","duration":"497.472939ms","start":"2026-05-18T15:59:44.114558Z","end":"2026-05-18T15:59:44.612031Z","steps":["trace[94121951] 'process raft request' (duration: 497.232415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:44.612414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:44.114549Z","time spent":"497.706833ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:59:44.612687Z","caller":"traceutil/trace.go:171","msg":"trace[1166297557] transaction","detail":"{read_only:false; response_revision:6626; number_of_response:1; }","duration":"497.861081ms","start":"2026-05-18T15:59:44.114811Z","end":"2026-05-18T15:59:44.612672Z","steps":["trace[1166297557] 'process raft request' (duration: 497.020252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:44.612833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:44.114806Z","time spent":"497.918134ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:59:44.612918Z","caller":"traceutil/trace.go:171","msg":"trace[2058985748] transaction","detail":"{read_only:false; response_revision:6627; number_of_response:1; }","duration":"495.559017ms","start":"2026-05-18T15:59:44.117345Z","end":"2026-05-18T15:59:44.612904Z","steps":["trace[2058985748] 'process raft request' (duration: 494.518906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:44.613039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:44.117337Z","time spent":"495.661652ms","remote":"127.0.0.1:60078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6486,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T15:59:44.613215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.513979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:59:44.613265Z","caller":"traceutil/trace.go:171","msg":"trace[571561758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6627; }","duration":"498.580644ms","start":"2026-05-18T15:59:44.11467Z","end":"2026-05-18T15:59:44.61325Z","steps":["trace[571561758] 'agreement among raft nodes before linearized reading' (duration: 498.507969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:44.613302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:44.114663Z","time spent":"498.632207ms","remote":"127.0.0.1:59906","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-18T15:59:45.252889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.72908ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-18T15:59:45.252977Z","caller":"traceutil/trace.go:171","msg":"trace[403447894] linearizableReadLoop","detail":"{readStateIndex:6875; appliedIndex:6874; }","duration":"640.964992ms","start":"2026-05-18T15:59:44.611997Z","end":"2026-05-18T15:59:45.252962Z","steps":["trace[403447894] 'read index received' (duration: 85.158198ms)","trace[403447894] 'applied index is now lower than readState.Index' (duration: 555.805784ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:59:45.25326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.040739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:59:45.253309Z","caller":"traceutil/trace.go:171","msg":"trace[1594067306] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:6627; }","duration":"741.153495ms","start":"2026-05-18T15:59:44.512143Z","end":"2026-05-18T15:59:45.253296Z","steps":["trace[1594067306] 'agreement among raft nodes before linearized reading' (duration: 741.048279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:45.253316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.772166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-18T15:59:45.253344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:44.512122Z","time spent":"741.210159ms","remote":"127.0.0.1:36700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-05-18T15:59:45.25335Z","caller":"traceutil/trace.go:171","msg":"trace[303444616] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:6627; }","duration":"602.842029ms","start":"2026-05-18T15:59:44.650498Z","end":"2026-05-18T15:59:45.25334Z","steps":["trace[303444616] 'agreement among raft nodes before linearized reading' (duration: 602.784056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:45.253434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:44.650481Z","time spent":"602.941205ms","remote":"127.0.0.1:36742","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/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-18T15:59:45.25358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042412674s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-18T15:59:45.253599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.715306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:59:45.253617Z","caller":"traceutil/trace.go:171","msg":"trace[1531985515] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:6627; }","duration":"1.042548491s","start":"2026-05-18T15:59:44.21106Z","end":"2026-05-18T15:59:45.253608Z","steps":["trace[1531985515] 'agreement among raft nodes before linearized reading' (duration: 1.042132117s)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:59:45.253631Z","caller":"traceutil/trace.go:171","msg":"trace[614934221] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:6627; }","duration":"414.78791ms","start":"2026-05-18T15:59:44.838834Z","end":"2026-05-18T15:59:45.253622Z","steps":["trace[614934221] 'agreement among raft nodes before linearized reading' (duration: 414.729367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:45.25367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:44.838812Z","time spent":"414.846213ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-18T15:59:45.253691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.172134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-18T15:59:45.253645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.160916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-05-18T15:59:45.253777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:44.211041Z","time spent":"1.042725971s","remote":"127.0.0.1:60380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":637,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-05-18T15:59:45.253812Z","caller":"traceutil/trace.go:171","msg":"trace[191844494] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6627; }","duration":"114.39328ms","start":"2026-05-18T15:59:45.139399Z","end":"2026-05-18T15:59:45.253792Z","steps":["trace[191844494] 'agreement among raft nodes before linearized reading' (duration: 113.945994ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:59:45.253723Z","caller":"traceutil/trace.go:171","msg":"trace[2076231876] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:6627; }","duration":"540.206816ms","start":"2026-05-18T15:59:44.713507Z","end":"2026-05-18T15:59:45.253713Z","steps":["trace[2076231876] 'agreement among raft nodes before linearized reading' (duration: 540.153103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:45.253901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:44.713437Z","time spent":"540.450391ms","remote":"127.0.0.1:40144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-05-18T15:59:45.927555Z","caller":"traceutil/trace.go:171","msg":"trace[1188515031] linearizableReadLoop","detail":"{readStateIndex:6876; appliedIndex:6875; }","duration":"220.630411ms","start":"2026-05-18T15:59:45.7069Z","end":"2026-05-18T15:59:45.927531Z","steps":["trace[1188515031] 'read index received' (duration: 220.572457ms)","trace[1188515031] 'applied index is now lower than readState.Index' (duration: 56.764µs)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:59:45.927747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.837993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:59:45.927918Z","caller":"traceutil/trace.go:171","msg":"trace[990386830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6628; }","duration":"221.065825ms","start":"2026-05-18T15:59:45.706839Z","end":"2026-05-18T15:59:45.927905Z","steps":["trace[990386830] 'agreement among raft nodes before linearized reading' (duration: 220.855673ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:59:45.928103Z","caller":"traceutil/trace.go:171","msg":"trace[2050545887] transaction","detail":"{read_only:false; response_revision:6628; number_of_response:1; }","duration":"304.216968ms","start":"2026-05-18T15:59:45.623853Z","end":"2026-05-18T15:59:45.92807Z","steps":["trace[2050545887] 'process raft request' (duration: 303.521697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:45.928738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:45.623829Z","time spent":"304.366376ms","remote":"127.0.0.1:60134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4842,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T15:59:46.429825Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828732988112952,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:59:46.695674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.603445ms","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-18T15:59:46.6958Z","caller":"traceutil/trace.go:171","msg":"trace[473274637] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:6628; }","duration":"462.738703ms","start":"2026-05-18T15:59:46.233044Z","end":"2026-05-18T15:59:46.695782Z","steps":["trace[473274637] 'range keys from in-memory index tree' (duration: 462.587944ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:59:46.826699Z","caller":"traceutil/trace.go:171","msg":"trace[2029298667] transaction","detail":"{read_only:false; response_revision:6629; number_of_response:1; }","duration":"1.069802685s","start":"2026-05-18T15:59:45.756867Z","end":"2026-05-18T15:59:46.82667Z","steps":["trace[2029298667] 'process raft request' (duration: 1.069576742s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:46.826933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:45.756845Z","time spent":"1.069940133s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:59:46.826957Z","caller":"traceutil/trace.go:171","msg":"trace[1702619666] transaction","detail":"{read_only:false; response_revision:6630; number_of_response:1; }","duration":"1.069195539s","start":"2026-05-18T15:59:45.757733Z","end":"2026-05-18T15:59:46.826929Z","steps":["trace[1702619666] 'process raft request' (duration: 1.068891161s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:46.827297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:45.757707Z","time spent":"1.069417802s","remote":"127.0.0.1:60148","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-18T15:59:47.147909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.109491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T15:59:47.148078Z","caller":"traceutil/trace.go:171","msg":"trace[73982997] linearizableReadLoop","detail":"{readStateIndex:6880; appliedIndex:6876; }","duration":"1.218698563s","start":"2026-05-18T15:59:45.929363Z","end":"2026-05-18T15:59:47.148062Z","steps":["trace[73982997] 'read index received' (duration: 897.231912ms)","trace[73982997] 'applied index is now lower than readState.Index' (duration: 321.465481ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:59:47.148173Z","caller":"traceutil/trace.go:171","msg":"trace[297473520] transaction","detail":"{read_only:false; response_revision:6632; number_of_response:1; }","duration":"1.029886292s","start":"2026-05-18T15:59:46.118277Z","end":"2026-05-18T15:59:47.148163Z","steps":["trace[297473520] 'process raft request' (duration: 1.029727722s)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:59:47.148227Z","caller":"traceutil/trace.go:171","msg":"trace[1848149657] transaction","detail":"{read_only:false; response_revision:6631; number_of_response:1; }","duration":"1.094022007s","start":"2026-05-18T15:59:46.054004Z","end":"2026-05-18T15:59:47.148026Z","steps":["trace[1848149657] 'process raft request' (duration: 904.718404ms)","trace[1848149657] 'compare' (duration: 188.991594ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:59:47.148254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:46.118262Z","time spent":"1.029942075s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T15:59:47.148434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.219066554s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:59:47.148472Z","caller":"traceutil/trace.go:171","msg":"trace[1415930278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6632; }","duration":"1.219131098s","start":"2026-05-18T15:59:45.92933Z","end":"2026-05-18T15:59:47.148461Z","steps":["trace[1415930278] 'agreement among raft nodes before linearized reading' (duration: 1.219070805s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:47.148506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:45.929315Z","time spent":"1.219185041s","remote":"127.0.0.1:59896","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-18T15:59:47.148945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038383082s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"info","ts":"2026-05-18T15:59:47.149036Z","caller":"traceutil/trace.go:171","msg":"trace[412599336] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6632; }","duration":"1.03849383s","start":"2026-05-18T15:59:46.110525Z","end":"2026-05-18T15:59:47.149018Z","steps":["trace[412599336] 'agreement among raft nodes before linearized reading' (duration: 1.038234535s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:47.149057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.243034ms","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-18T15:59:47.149128Z","caller":"traceutil/trace.go:171","msg":"trace[557856898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6632; }","duration":"595.34203ms","start":"2026-05-18T15:59:46.553767Z","end":"2026-05-18T15:59:47.149109Z","steps":["trace[557856898] 'agreement among raft nodes before linearized reading' (duration: 595.16525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:47.149176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030874361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"warn","ts":"2026-05-18T15:59:47.149178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:46.553751Z","time spent":"595.414373ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-18T15:59:47.149158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.853702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-18T15:59:47.149211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.644529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-18T15:59:47.149273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034010645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-18T15:59:47.149084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:46.110459Z","time spent":"1.038614056s","remote":"127.0.0.1:60134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4880,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"info","ts":"2026-05-18T15:59:47.149309Z","caller":"traceutil/trace.go:171","msg":"trace[1209128743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6632; }","duration":"1.03409293s","start":"2026-05-18T15:59:46.115209Z","end":"2026-05-18T15:59:47.149302Z","steps":["trace[1209128743] 'agreement among raft nodes before linearized reading' (duration: 1.034014486s)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:59:47.149296Z","caller":"traceutil/trace.go:171","msg":"trace[659419759] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6632; }","duration":"532.808148ms","start":"2026-05-18T15:59:46.616439Z","end":"2026-05-18T15:59:47.149247Z","steps":["trace[659419759] 'agreement among raft nodes before linearized reading' (duration: 532.547933ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:59:47.149266Z","caller":"traceutil/trace.go:171","msg":"trace[121809901] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6632; }","duration":"531.999832ms","start":"2026-05-18T15:59:46.617241Z","end":"2026-05-18T15:59:47.149241Z","steps":["trace[121809901] 'agreement among raft nodes before linearized reading' (duration: 531.724376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:47.149439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:46.617234Z","time spent":"532.189743ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-18T15:59:47.149395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:46.61642Z","time spent":"532.958287ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-18T15:59:47.149337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:46.115184Z","time spent":"1.034146693s","remote":"127.0.0.1:60148","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-18T15:59:47.149206Z","caller":"traceutil/trace.go:171","msg":"trace[1471423883] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6632; }","duration":"1.030919333s","start":"2026-05-18T15:59:46.118278Z","end":"2026-05-18T15:59:47.149197Z","steps":["trace[1471423883] 'agreement among raft nodes before linearized reading' (duration: 1.030826008s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:47.149642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:46.118271Z","time spent":"1.031354868s","remote":"127.0.0.1:60134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4880,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"warn","ts":"2026-05-18T15:59:47.149797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032116673s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"info","ts":"2026-05-18T15:59:47.150043Z","caller":"traceutil/trace.go:171","msg":"trace[1490293007] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6632; }","duration":"1.03240721s","start":"2026-05-18T15:59:46.117627Z","end":"2026-05-18T15:59:47.150035Z","steps":["trace[1490293007] 'agreement among raft nodes before linearized reading' (duration: 1.031538779s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:47.150068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:46.117612Z","time spent":"1.032448784s","remote":"127.0.0.1:60134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4880,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"warn","ts":"2026-05-18T15:59:47.15008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.510561ms","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-18T15:59:47.150877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:46.053979Z","time spent":"1.096606169s","remote":"127.0.0.1:60148","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-18T15:59:47.151484Z","caller":"traceutil/trace.go:171","msg":"trace[2115250905] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6632; }","duration":"533.920225ms","start":"2026-05-18T15:59:46.617506Z","end":"2026-05-18T15:59:47.151426Z","steps":["trace[2115250905] 'agreement among raft nodes before linearized reading' (duration: 531.544555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:47.151842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:46.617483Z","time spent":"534.332799ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-18T15:59:47.597701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.221918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T15:59:47.597776Z","caller":"traceutil/trace.go:171","msg":"trace[1074872380] transaction","detail":"{read_only:false; response_revision:6633; number_of_response:1; }","duration":"443.203711ms","start":"2026-05-18T15:59:47.154558Z","end":"2026-05-18T15:59:47.597762Z","steps":["trace[1074872380] 'process raft request' (duration: 110.838084ms)","trace[1074872380] 'compare' (duration: 331.534079ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:59:47.59782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:47.15454Z","time spent":"443.256444ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:59:47.651064Z","caller":"traceutil/trace.go:171","msg":"trace[528068216] linearizableReadLoop","detail":"{readStateIndex:6885; appliedIndex:6880; }","duration":"492.236368ms","start":"2026-05-18T15:59:47.158797Z","end":"2026-05-18T15:59:47.651033Z","steps":["trace[528068216] 'read index received' (duration: 106.67493ms)","trace[528068216] 'applied index is now lower than readState.Index' (duration: 385.560478ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:59:47.651075Z","caller":"traceutil/trace.go:171","msg":"trace[470420434] transaction","detail":"{read_only:false; response_revision:6634; number_of_response:1; }","duration":"495.868262ms","start":"2026-05-18T15:59:47.155176Z","end":"2026-05-18T15:59:47.651044Z","steps":["trace[470420434] 'process raft request' (duration: 495.633738ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:59:47.651127Z","caller":"traceutil/trace.go:171","msg":"trace[1147536115] transaction","detail":"{read_only:false; response_revision:6636; number_of_response:1; }","duration":"493.994211ms","start":"2026-05-18T15:59:47.157103Z","end":"2026-05-18T15:59:47.651097Z","steps":["trace[1147536115] 'process raft request' (duration: 493.872954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:47.651242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.427599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:59:47.651272Z","caller":"traceutil/trace.go:171","msg":"trace[1791780070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6637; }","duration":"492.522615ms","start":"2026-05-18T15:59:47.158744Z","end":"2026-05-18T15:59:47.651266Z","steps":["trace[1791780070] 'agreement among raft nodes before linearized reading' (duration: 492.44725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:47.651281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:47.155153Z","time spent":"496.052152ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T15:59:47.651338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.827219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:59:47.651363Z","caller":"traceutil/trace.go:171","msg":"trace[1331642654] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:6637; }","duration":"222.887312ms","start":"2026-05-18T15:59:47.428469Z","end":"2026-05-18T15:59:47.651356Z","steps":["trace[1331642654] 'agreement among raft nodes before linearized reading' (duration: 222.84669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:47.651341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:47.157096Z","time spent":"494.131139ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:59:47.651178Z","caller":"traceutil/trace.go:171","msg":"trace[814048845] transaction","detail":"{read_only:false; response_revision:6637; number_of_response:1; }","duration":"493.995701ms","start":"2026-05-18T15:59:47.157174Z","end":"2026-05-18T15:59:47.651169Z","steps":["trace[814048845] 'process raft request' (duration: 493.828311ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:59:47.651491Z","caller":"traceutil/trace.go:171","msg":"trace[566296939] transaction","detail":"{read_only:false; response_revision:6635; number_of_response:1; }","duration":"495.129879ms","start":"2026-05-18T15:59:47.156353Z","end":"2026-05-18T15:59:47.651482Z","steps":["trace[566296939] 'process raft request' (duration: 494.595317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:59:47.651509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:47.157157Z","time spent":"494.31876ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T15:59:47.651557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:47.156342Z","time spent":"495.189162ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T15:59:47.651297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:59:47.158728Z","time spent":"492.562367ms","remote":"127.0.0.1:59906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-18T15:59:54.125964Z","caller":"traceutil/trace.go:171","msg":"trace[1075329682] transaction","detail":"{read_only:false; response_revision:6676; number_of_response:1; }","duration":"121.732835ms","start":"2026-05-18T15:59:54.0042Z","end":"2026-05-18T15:59:54.125933Z","steps":["trace[1075329682] 'process raft request' (duration: 121.523133ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:59:54.292863Z","caller":"traceutil/trace.go:171","msg":"trace[499143871] transaction","detail":"{read_only:false; response_revision:6679; number_of_response:1; }","duration":"118.613384ms","start":"2026-05-18T15:59:54.174221Z","end":"2026-05-18T15:59:54.292834Z","steps":["trace[499143871] 'process raft request' (duration: 93.049457ms)","trace[499143871] 'compare' (duration: 25.453361ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T16:01:55.954307Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5460} {"level":"info","ts":"2026-05-18T16:01:56.061072Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5460,"took":"104.951749ms","hash":4082276514,"current-db-size-bytes":53071872,"current-db-size":"53 MB","current-db-size-in-use-bytes":18145280,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-18T16:01:56.061143Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4082276514,"revision":5460,"compact-revision":2875}