{"level":"warn","ts":"2026-08-04T15:17:13.703604Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-04T15:17:13.703748Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.170:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.170:2380","--initial-cluster=instance=https://199.19.213.170:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.170:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.170: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-08-04T15:17:13.703848Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-04T15:17:13.703862Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.170:2380"]} {"level":"info","ts":"2026-08-04T15:17:13.703887Z","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-08-04T15:17:13.704654Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.170:2379"]} {"level":"info","ts":"2026-08-04T15:17:13.704758Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.170:2380"],"listen-peer-urls":["https://199.19.213.170:2380"],"advertise-client-urls":["https://199.19.213.170:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.170:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.170: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-08-04T15:17:13.709931Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.920558ms"} {"level":"info","ts":"2026-08-04T15:17:13.719254Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"80be33c6b0d5a492","cluster-id":"abad33c2eec87dc"} {"level":"info","ts":"2026-08-04T15:17:13.719339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 switched to configuration voters=()"} {"level":"info","ts":"2026-08-04T15:17:13.719361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became follower at term 0"} {"level":"info","ts":"2026-08-04T15:17:13.719372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 80be33c6b0d5a492 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-04T15:17:13.719381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became follower at term 1"} {"level":"info","ts":"2026-08-04T15:17:13.719421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 switched to configuration voters=(9276909210893132946)"} {"level":"warn","ts":"2026-08-04T15:17:13.730957Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-04T15:17:13.735059Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-04T15:17:13.737581Z","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-08-04T15:17:13.741875Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"80be33c6b0d5a492","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-04T15:17:13.742271Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"80be33c6b0d5a492","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-04T15:17:13.742338Z","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-08-04T15:17:13.7426Z","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-08-04T15:17:13.742619Z","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-08-04T15:17:13.745177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 switched to configuration voters=(9276909210893132946)"} {"level":"info","ts":"2026-08-04T15:17:13.745446Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"abad33c2eec87dc","local-member-id":"80be33c6b0d5a492","added-peer-id":"80be33c6b0d5a492","added-peer-peer-urls":["https://199.19.213.170:2380"]} {"level":"info","ts":"2026-08-04T15:17:13.752794Z","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-08-04T15:17:13.752939Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.170:2380"} {"level":"info","ts":"2026-08-04T15:17:13.753009Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.170:2380"} {"level":"info","ts":"2026-08-04T15:17:13.753236Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"80be33c6b0d5a492","initial-advertise-peer-urls":["https://199.19.213.170:2380"],"listen-peer-urls":["https://199.19.213.170:2380"],"advertise-client-urls":["https://199.19.213.170:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.170:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-04T15:17:13.753293Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-04T15:17:14.620389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 is starting a new election at term 1"} {"level":"info","ts":"2026-08-04T15:17:14.620463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-04T15:17:14.620495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 received MsgPreVoteResp from 80be33c6b0d5a492 at term 1"} {"level":"info","ts":"2026-08-04T15:17:14.620521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became candidate at term 2"} {"level":"info","ts":"2026-08-04T15:17:14.620535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 received MsgVoteResp from 80be33c6b0d5a492 at term 2"} {"level":"info","ts":"2026-08-04T15:17:14.620548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became leader at term 2"} {"level":"info","ts":"2026-08-04T15:17:14.620564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 80be33c6b0d5a492 elected leader 80be33c6b0d5a492 at term 2"} {"level":"info","ts":"2026-08-04T15:17:14.622694Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"80be33c6b0d5a492","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.170:2379]}","request-path":"/0/members/80be33c6b0d5a492/attributes","cluster-id":"abad33c2eec87dc","publish-timeout":"7s"} {"level":"info","ts":"2026-08-04T15:17:14.622864Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T15:17:14.622939Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T15:17:14.62305Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:17:14.624374Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"abad33c2eec87dc","local-member-id":"80be33c6b0d5a492","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:17:14.6245Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:17:14.624547Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:17:14.625505Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-04T15:17:14.634304Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-04T15:17:14.63439Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-04T15:17:14.63481Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.170:2379"} {"level":"info","ts":"2026-08-04T15:17:30.26204Z","caller":"traceutil/trace.go:171","msg":"trace[327975881] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"118.165185ms","start":"2026-08-04T15:17:30.143779Z","end":"2026-08-04T15:17:30.261945Z","steps":["trace[327975881] 'process raft request' (duration: 115.925107ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:17:57.224754Z","caller":"traceutil/trace.go:171","msg":"trace[2030753695] transaction","detail":"{read_only:false; number_of_response:1; response_revision:592; }","duration":"198.075426ms","start":"2026-08-04T15:17:57.026643Z","end":"2026-08-04T15:17:57.224718Z","steps":["trace[2030753695] 'process raft request' (duration: 192.297051ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:17:57.227001Z","caller":"traceutil/trace.go:171","msg":"trace[2009557625] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"160.142612ms","start":"2026-08-04T15:17:57.066833Z","end":"2026-08-04T15:17:57.226976Z","steps":["trace[2009557625] 'process raft request' (duration: 159.73496ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:17:57.22705Z","caller":"traceutil/trace.go:171","msg":"trace[26656498] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"149.394498ms","start":"2026-08-04T15:17:57.077619Z","end":"2026-08-04T15:17:57.227013Z","steps":["trace[26656498] 'process raft request' (duration: 149.256814ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:18:03.247128Z","caller":"traceutil/trace.go:171","msg":"trace[1459313924] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"114.968558ms","start":"2026-08-04T15:18:03.132116Z","end":"2026-08-04T15:18:03.247084Z","steps":["trace[1459313924] 'process raft request' (duration: 109.365809ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:18:04.031525Z","caller":"traceutil/trace.go:171","msg":"trace[1801712164] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"309.635333ms","start":"2026-08-04T15:18:03.72186Z","end":"2026-08-04T15:18:04.031495Z","steps":["trace[1801712164] 'process raft request' (duration: 309.401606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:18:04.032203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:18:03.721808Z","time spent":"309.812147ms","remote":"127.0.0.1:33592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:18:04.16289Z","caller":"traceutil/trace.go:171","msg":"trace[1464377293] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"414.827307ms","start":"2026-08-04T15:18:03.748035Z","end":"2026-08-04T15:18:04.162862Z","steps":["trace[1464377293] 'process raft request' (duration: 412.63702ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:18:04.163047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:18:03.747997Z","time spent":"414.96277ms","remote":"127.0.0.1:48620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:18:20.619887Z","caller":"traceutil/trace.go:171","msg":"trace[1899302830] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:847; }","duration":"116.954527ms","start":"2026-08-04T15:18:20.502901Z","end":"2026-08-04T15:18:20.619855Z","steps":["trace[1899302830] 'read index received' (duration: 116.767272ms)","trace[1899302830] 'applied index is now lower than readState.Index' (duration: 185.925µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:18:20.619962Z","caller":"traceutil/trace.go:171","msg":"trace[1842386026] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"119.076679ms","start":"2026-08-04T15:18:20.500833Z","end":"2026-08-04T15:18:20.61991Z","steps":["trace[1842386026] 'process raft request' (duration: 118.839653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:18:20.620122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.188784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-04T15:18:20.620328Z","caller":"traceutil/trace.go:171","msg":"trace[490862894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:818; }","duration":"117.423301ms","start":"2026-08-04T15:18:20.502878Z","end":"2026-08-04T15:18:20.620301Z","steps":["trace[490862894] 'agreement among raft nodes before linearized reading' (duration: 117.119342ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:18:20.622428Z","caller":"traceutil/trace.go:171","msg":"trace[2126208533] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"118.034218ms","start":"2026-08-04T15:18:20.504364Z","end":"2026-08-04T15:18:20.622398Z","steps":["trace[2126208533] 'process raft request' (duration: 117.784001ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:19:31.187543Z","caller":"traceutil/trace.go:171","msg":"trace[257716060] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"101.825107ms","start":"2026-08-04T15:19:31.085685Z","end":"2026-08-04T15:19:31.18751Z","steps":["trace[257716060] 'process raft request' (duration: 101.739594ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:19:31.187595Z","caller":"traceutil/trace.go:171","msg":"trace[1754453851] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"101.890788ms","start":"2026-08-04T15:19:31.085653Z","end":"2026-08-04T15:19:31.187544Z","steps":["trace[1754453851] 'process raft request' (duration: 55.556739ms)","trace[1754453851] 'compare' (duration: 46.078072ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:19:31.187752Z","caller":"traceutil/trace.go:171","msg":"trace[773496818] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"101.007224ms","start":"2026-08-04T15:19:31.086708Z","end":"2026-08-04T15:19:31.187715Z","steps":["trace[773496818] 'process raft request' (duration: 100.764197ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:19:31.414675Z","caller":"traceutil/trace.go:171","msg":"trace[1420659957] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"200.518205ms","start":"2026-08-04T15:19:31.214109Z","end":"2026-08-04T15:19:31.414627Z","steps":["trace[1420659957] 'process raft request' (duration: 168.319549ms)","trace[1420659957] 'compare' (duration: 31.807285ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:20:21.887475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.429529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T15:20:21.887592Z","caller":"traceutil/trace.go:171","msg":"trace[697696133] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2193; }","duration":"119.607994ms","start":"2026-08-04T15:20:21.767962Z","end":"2026-08-04T15:20:21.88757Z","steps":["trace[697696133] 'count revisions from in-memory index tree' (duration: 119.319655ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:21:40.105228Z","caller":"traceutil/trace.go:171","msg":"trace[10485479] linearizableReadLoop","detail":"{readStateIndex:2763; appliedIndex:2762; }","duration":"188.979855ms","start":"2026-08-04T15:21:39.916225Z","end":"2026-08-04T15:21:40.105205Z","steps":["trace[10485479] 'read index received' (duration: 188.80767ms)","trace[10485479] 'applied index is now lower than readState.Index' (duration: 170.835µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:21:40.105368Z","caller":"traceutil/trace.go:171","msg":"trace[1279889222] transaction","detail":"{read_only:false; response_revision:2665; number_of_response:1; }","duration":"219.397545ms","start":"2026-08-04T15:21:39.885921Z","end":"2026-08-04T15:21:40.105318Z","steps":["trace[1279889222] 'process raft request' (duration: 219.106397ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:40.105482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.953219ms","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-08-04T15:21:40.105521Z","caller":"traceutil/trace.go:171","msg":"trace[1865411852] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2665; }","duration":"256.00958ms","start":"2026-08-04T15:21:39.8495Z","end":"2026-08-04T15:21:40.10551Z","steps":["trace[1865411852] 'agreement among raft nodes before linearized reading' (duration: 255.856086ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:40.105511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.747786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-08-04T15:21:40.105574Z","caller":"traceutil/trace.go:171","msg":"trace[1490835310] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2665; }","duration":"201.86938ms","start":"2026-08-04T15:21:39.90369Z","end":"2026-08-04T15:21:40.105559Z","steps":["trace[1490835310] 'agreement among raft nodes before linearized reading' (duration: 201.608242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:40.105728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.470067ms","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-08-04T15:21:40.105804Z","caller":"traceutil/trace.go:171","msg":"trace[952382313] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2665; }","duration":"260.59773ms","start":"2026-08-04T15:21:39.845193Z","end":"2026-08-04T15:21:40.105791Z","steps":["trace[952382313] 'agreement among raft nodes before linearized reading' (duration: 260.460696ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:21:40.22991Z","caller":"traceutil/trace.go:171","msg":"trace[1627476046] transaction","detail":"{read_only:false; response_revision:2666; number_of_response:1; }","duration":"308.632188ms","start":"2026-08-04T15:21:39.921248Z","end":"2026-08-04T15:21:40.22988Z","steps":["trace[1627476046] 'process raft request' (duration: 265.181229ms)","trace[1627476046] 'compare' (duration: 43.284875ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:21:40.229957Z","caller":"traceutil/trace.go:171","msg":"trace[951091097] transaction","detail":"{read_only:false; response_revision:2668; number_of_response:1; }","duration":"119.217472ms","start":"2026-08-04T15:21:40.11072Z","end":"2026-08-04T15:21:40.229937Z","steps":["trace[951091097] 'process raft request' (duration: 119.178731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:40.230063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:39.921229Z","time spent":"308.751392ms","remote":"127.0.0.1:33668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:21:40.230178Z","caller":"traceutil/trace.go:171","msg":"trace[331309538] linearizableReadLoop","detail":"{readStateIndex:2764; appliedIndex:2763; }","duration":"124.844661ms","start":"2026-08-04T15:21:40.105325Z","end":"2026-08-04T15:21:40.230169Z","steps":["trace[331309538] 'read index received' (duration: 81.121444ms)","trace[331309538] 'applied index is now lower than readState.Index' (duration: 43.722487ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:21:40.230439Z","caller":"traceutil/trace.go:171","msg":"trace[1927021862] transaction","detail":"{read_only:false; response_revision:2667; number_of_response:1; }","duration":"119.959663ms","start":"2026-08-04T15:21:40.110456Z","end":"2026-08-04T15:21:40.230415Z","steps":["trace[1927021862] 'process raft request' (duration: 119.408338ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:40.230562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.378573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-08-04T15:21:40.230597Z","caller":"traceutil/trace.go:171","msg":"trace[1730721799] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2668; }","duration":"121.478615ms","start":"2026-08-04T15:21:40.10911Z","end":"2026-08-04T15:21:40.230588Z","steps":["trace[1730721799] 'agreement among raft nodes before linearized reading' (duration: 121.329781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:40.230599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.611888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-08-04T15:21:40.230708Z","caller":"traceutil/trace.go:171","msg":"trace[574576468] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2668; }","duration":"145.739072ms","start":"2026-08-04T15:21:40.084946Z","end":"2026-08-04T15:21:40.230685Z","steps":["trace[574576468] 'agreement among raft nodes before linearized reading' (duration: 145.389752ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:21.214285Z","caller":"traceutil/trace.go:171","msg":"trace[2129568607] transaction","detail":"{read_only:false; response_revision:3701; number_of_response:1; }","duration":"123.73322ms","start":"2026-08-04T15:24:21.09049Z","end":"2026-08-04T15:24:21.214223Z","steps":["trace[2129568607] 'process raft request' (duration: 123.349008ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:26.704565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.070471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-04T15:24:26.704904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.687661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T15:24:26.705079Z","caller":"traceutil/trace.go:171","msg":"trace[656018386] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:3729; }","duration":"129.970119ms","start":"2026-08-04T15:24:26.575021Z","end":"2026-08-04T15:24:26.704991Z","steps":["trace[656018386] 'count revisions from in-memory index tree' (duration: 129.582808ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:26.705199Z","caller":"traceutil/trace.go:171","msg":"trace[479749572] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3729; }","duration":"105.267488ms","start":"2026-08-04T15:24:26.599394Z","end":"2026-08-04T15:24:26.704661Z","steps":["trace[479749572] 'count revisions from in-memory index tree' (duration: 104.877496ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:49.841022Z","caller":"traceutil/trace.go:171","msg":"trace[1709006872] linearizableReadLoop","detail":"{readStateIndex:5074; appliedIndex:5073; }","duration":"107.099028ms","start":"2026-08-04T15:25:49.733905Z","end":"2026-08-04T15:25:49.841004Z","steps":["trace[1709006872] 'read index received' (duration: 96.341781ms)","trace[1709006872] 'applied index is now lower than readState.Index' (duration: 10.756157ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:25:49.84108Z","caller":"traceutil/trace.go:171","msg":"trace[454153626] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"120.736957ms","start":"2026-08-04T15:25:49.720296Z","end":"2026-08-04T15:25:49.841033Z","steps":["trace[454153626] 'process raft request' (duration: 109.875986ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:49.84124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.579584ms","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-08-04T15:25:49.841279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.729658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-04T15:25:49.841314Z","caller":"traceutil/trace.go:171","msg":"trace[217910762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4896; }","duration":"140.724847ms","start":"2026-08-04T15:25:49.700576Z","end":"2026-08-04T15:25:49.841301Z","steps":["trace[217910762] 'agreement among raft nodes before linearized reading' (duration: 140.46104ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:49.841318Z","caller":"traceutil/trace.go:171","msg":"trace[217078395] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4896; }","duration":"140.796929ms","start":"2026-08-04T15:25:49.700507Z","end":"2026-08-04T15:25:49.841304Z","steps":["trace[217078395] 'agreement among raft nodes before linearized reading' (duration: 140.588163ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:49.845482Z","caller":"traceutil/trace.go:171","msg":"trace[399057053] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"105.878022ms","start":"2026-08-04T15:25:49.739582Z","end":"2026-08-04T15:25:49.84546Z","steps":["trace[399057053] 'process raft request' (duration: 105.714528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:26:12.162649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.665034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:26:12.162935Z","caller":"traceutil/trace.go:171","msg":"trace[1147149898] transaction","detail":"{read_only:false; response_revision:5321; number_of_response:1; }","duration":"121.502788ms","start":"2026-08-04T15:26:12.041414Z","end":"2026-08-04T15:26:12.162917Z","steps":["trace[1147149898] 'process raft request' (duration: 121.463967ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:12.163097Z","caller":"traceutil/trace.go:171","msg":"trace[1119137420] transaction","detail":"{read_only:false; response_revision:5319; number_of_response:1; }","duration":"196.218692ms","start":"2026-08-04T15:26:11.96687Z","end":"2026-08-04T15:26:12.163088Z","steps":["trace[1119137420] 'process raft request' (duration: 195.897002ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:12.163139Z","caller":"traceutil/trace.go:171","msg":"trace[93507351] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"200.067773ms","start":"2026-08-04T15:26:11.963036Z","end":"2026-08-04T15:26:12.163104Z","steps":["trace[93507351] 'process raft request' (duration: 43.728559ms)","trace[93507351] 'compare' (duration: 155.53452ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:26:12.163259Z","caller":"traceutil/trace.go:171","msg":"trace[837647649] transaction","detail":"{read_only:false; response_revision:5320; number_of_response:1; }","duration":"186.269409ms","start":"2026-08-04T15:26:11.976979Z","end":"2026-08-04T15:26:12.163249Z","steps":["trace[837647649] 'process raft request' (duration: 185.853576ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:12.163373Z","caller":"traceutil/trace.go:171","msg":"trace[322759195] linearizableReadLoop","detail":"{readStateIndex:5508; appliedIndex:5507; }","duration":"199.97771ms","start":"2026-08-04T15:26:11.963246Z","end":"2026-08-04T15:26:12.163223Z","steps":["trace[322759195] 'read index received' (duration: 43.700178ms)","trace[322759195] 'applied index is now lower than readState.Index' (duration: 156.273182ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:26:12.163499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.250828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-04T15:26:12.163669Z","caller":"traceutil/trace.go:171","msg":"trace[687067851] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5321; }","duration":"200.464533ms","start":"2026-08-04T15:26:11.963192Z","end":"2026-08-04T15:26:12.163656Z","steps":["trace[687067851] 'agreement among raft nodes before linearized reading' (duration: 200.195346ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:26:12.59473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.60374ms","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-08-04T15:26:12.594919Z","caller":"traceutil/trace.go:171","msg":"trace[1084966418] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5321; }","duration":"192.801775ms","start":"2026-08-04T15:26:12.402095Z","end":"2026-08-04T15:26:12.594896Z","steps":["trace[1084966418] 'range keys from in-memory index tree' (duration: 192.591579ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:26:12.595374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.954504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:26:12.595636Z","caller":"traceutil/trace.go:171","msg":"trace[515142511] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"427.322601ms","start":"2026-08-04T15:26:12.168297Z","end":"2026-08-04T15:26:12.595619Z","steps":["trace[515142511] 'process raft request' (duration: 127.015837ms)","trace[515142511] 'compare' (duration: 299.554513ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:26:12.595705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:26:12.168276Z","time spent":"427.395542ms","remote":"127.0.0.1:33668","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-08-04T15:26:12.595722Z","caller":"traceutil/trace.go:171","msg":"trace[1562972888] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"426.676451ms","start":"2026-08-04T15:26:12.169005Z","end":"2026-08-04T15:26:12.595681Z","steps":["trace[1562972888] 'process raft request' (duration: 426.483846ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:12.595979Z","caller":"traceutil/trace.go:171","msg":"trace[752501505] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"426.449005ms","start":"2026-08-04T15:26:12.169522Z","end":"2026-08-04T15:26:12.595971Z","steps":["trace[752501505] 'process raft request' (duration: 426.062174ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:26:12.595988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:26:12.168998Z","time spent":"426.898927ms","remote":"127.0.0.1:48594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":919,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-04T15:26:12.596034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:26:12.169513Z","time spent":"426.493697ms","remote":"127.0.0.1:33668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:26:12.595824Z","caller":"traceutil/trace.go:171","msg":"trace[144039446] linearizableReadLoop","detail":"{readStateIndex:5514; appliedIndex:5511; }","duration":"425.568ms","start":"2026-08-04T15:26:12.170235Z","end":"2026-08-04T15:26:12.595803Z","steps":["trace[144039446] 'read index received' (duration: 125.122352ms)","trace[144039446] 'applied index is now lower than readState.Index' (duration: 300.444148ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:26:12.59605Z","caller":"traceutil/trace.go:171","msg":"trace[480204523] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"417.445238ms","start":"2026-08-04T15:26:12.17859Z","end":"2026-08-04T15:26:12.596035Z","steps":["trace[480204523] 'process raft request' (duration: 417.128069ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:26:12.596138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:26:12.178553Z","time spent":"417.540661ms","remote":"127.0.0.1:52028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2577,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:26:12.595997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.747685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-08-04T15:26:12.59629Z","caller":"traceutil/trace.go:171","msg":"trace[1438248468] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5326; }","duration":"426.061304ms","start":"2026-08-04T15:26:12.170219Z","end":"2026-08-04T15:26:12.596281Z","steps":["trace[1438248468] 'agreement among raft nodes before linearized reading' (duration: 425.643773ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:26:12.596321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:26:12.170212Z","time spent":"426.100235ms","remote":"127.0.0.1:48620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3981,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" "} {"level":"warn","ts":"2026-08-04T15:26:12.596292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.189023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:26:12.596373Z","caller":"traceutil/trace.go:171","msg":"trace[28119086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5326; }","duration":"319.339887ms","start":"2026-08-04T15:26:12.277012Z","end":"2026-08-04T15:26:12.596352Z","steps":["trace[28119086] 'agreement among raft nodes before linearized reading' (duration: 319.202793ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:26:12.59643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:26:12.276986Z","time spent":"319.43574ms","remote":"127.0.0.1:48488","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-08-04T15:26:12.59655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.842373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f62340ee-f59a-43c9-a616-fb46d52dd703\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-04T15:26:12.596583Z","caller":"traceutil/trace.go:171","msg":"trace[790629181] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f62340ee-f59a-43c9-a616-fb46d52dd703; range_end:; response_count:1; response_revision:5326; }","duration":"118.893955ms","start":"2026-08-04T15:26:12.47768Z","end":"2026-08-04T15:26:12.596574Z","steps":["trace[790629181] 'agreement among raft nodes before linearized reading' (duration: 118.813582ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:26:12.59674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.234655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T15:26:12.596773Z","caller":"traceutil/trace.go:171","msg":"trace[1313049228] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:5326; }","duration":"201.341129ms","start":"2026-08-04T15:26:12.395422Z","end":"2026-08-04T15:26:12.596763Z","steps":["trace[1313049228] 'agreement among raft nodes before linearized reading' (duration: 201.208585ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:26:12.597586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.632935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2380610"} {"level":"info","ts":"2026-08-04T15:26:12.597623Z","caller":"traceutil/trace.go:171","msg":"trace[115566537] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5326; }","duration":"161.710727ms","start":"2026-08-04T15:26:12.435904Z","end":"2026-08-04T15:26:12.597615Z","steps":["trace[115566537] 'agreement among raft nodes before linearized reading' (duration: 160.615415ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:12.597951Z","caller":"traceutil/trace.go:171","msg":"trace[893193313] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"426.758315ms","start":"2026-08-04T15:26:12.171141Z","end":"2026-08-04T15:26:12.597899Z","steps":["trace[893193313] 'process raft request' (duration: 424.512051ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:26:12.598335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:26:12.171132Z","time spent":"427.099144ms","remote":"127.0.0.1:33668","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-08-04T15:27:14.159193Z","caller":"traceutil/trace.go:171","msg":"trace[202753527] linearizableReadLoop","detail":"{readStateIndex:6126; appliedIndex:6125; }","duration":"114.81796ms","start":"2026-08-04T15:27:14.044351Z","end":"2026-08-04T15:27:14.159169Z","steps":["trace[202753527] 'read index received' (duration: 114.670746ms)","trace[202753527] 'applied index is now lower than readState.Index' (duration: 145.784µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:27:14.159434Z","caller":"traceutil/trace.go:171","msg":"trace[839622579] transaction","detail":"{read_only:false; response_revision:5920; number_of_response:1; }","duration":"126.01086ms","start":"2026-08-04T15:27:14.033407Z","end":"2026-08-04T15:27:14.159418Z","steps":["trace[839622579] 'process raft request' (duration: 125.597408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:27:14.159895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.52436ms","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-08-04T15:27:14.159987Z","caller":"traceutil/trace.go:171","msg":"trace[140077922] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5920; }","duration":"115.684165ms","start":"2026-08-04T15:27:14.044289Z","end":"2026-08-04T15:27:14.159973Z","steps":["trace[140077922] 'agreement among raft nodes before linearized reading' (duration: 115.546191ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:27:14.654241Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2851} {"level":"info","ts":"2026-08-04T15:27:14.722528Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2851,"took":"66.412477ms","hash":433378934,"current-db-size-bytes":57724928,"current-db-size":"58 MB","current-db-size-in-use-bytes":32899072,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-04T15:27:14.722616Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":433378934,"revision":2851,"compact-revision":-1} {"level":"info","ts":"2026-08-04T15:32:14.671905Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5925} {"level":"info","ts":"2026-08-04T15:32:14.761785Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5925,"took":"87.199481ms","hash":1559785521,"current-db-size-bytes":57724928,"current-db-size":"58 MB","current-db-size-in-use-bytes":25186304,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-04T15:32:14.761858Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1559785521,"revision":5925,"compact-revision":2851} {"level":"info","ts":"2026-08-04T15:32:34.442202Z","caller":"traceutil/trace.go:171","msg":"trace[1768955283] linearizableReadLoop","detail":"{readStateIndex:8528; appliedIndex:8527; }","duration":"247.046187ms","start":"2026-08-04T15:32:34.195124Z","end":"2026-08-04T15:32:34.442171Z","steps":["trace[1768955283] 'read index received' (duration: 246.860842ms)","trace[1768955283] 'applied index is now lower than readState.Index' (duration: 183.505µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:32:34.442639Z","caller":"traceutil/trace.go:171","msg":"trace[736317936] transaction","detail":"{read_only:false; response_revision:8244; number_of_response:1; }","duration":"251.996309ms","start":"2026-08-04T15:32:34.190627Z","end":"2026-08-04T15:32:34.442624Z","steps":["trace[736317936] 'process raft request' (duration: 251.344501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:32:34.44308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.946712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-04T15:32:34.44307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.980952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:32:34.443129Z","caller":"traceutil/trace.go:171","msg":"trace[510568819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8244; }","duration":"248.054886ms","start":"2026-08-04T15:32:34.195063Z","end":"2026-08-04T15:32:34.443118Z","steps":["trace[510568819] 'agreement among raft nodes before linearized reading' (duration: 247.880951ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:32:34.443156Z","caller":"traceutil/trace.go:171","msg":"trace[827503687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8244; }","duration":"166.131076ms","start":"2026-08-04T15:32:34.277008Z","end":"2026-08-04T15:32:34.443139Z","steps":["trace[827503687] 'agreement among raft nodes before linearized reading' (duration: 165.983221ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:33:15.378734Z","caller":"traceutil/trace.go:171","msg":"trace[1590993621] transaction","detail":"{read_only:false; response_revision:8556; number_of_response:1; }","duration":"189.547707ms","start":"2026-08-04T15:33:15.189161Z","end":"2026-08-04T15:33:15.378708Z","steps":["trace[1590993621] 'process raft request' (duration: 189.323621ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:33:15.43203Z","caller":"traceutil/trace.go:171","msg":"trace[1826034104] linearizableReadLoop","detail":"{readStateIndex:8854; appliedIndex:8851; }","duration":"147.657411ms","start":"2026-08-04T15:33:15.284356Z","end":"2026-08-04T15:33:15.432014Z","steps":["trace[1826034104] 'read index received' (duration: 94.116305ms)","trace[1826034104] 'applied index is now lower than readState.Index' (duration: 53.539696ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:33:15.432138Z","caller":"traceutil/trace.go:171","msg":"trace[185902087] transaction","detail":"{read_only:false; response_revision:8558; number_of_response:1; }","duration":"234.790749ms","start":"2026-08-04T15:33:15.197302Z","end":"2026-08-04T15:33:15.432093Z","steps":["trace[185902087] 'process raft request' (duration: 234.643235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:33:15.432275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.913578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-04T15:33:15.432333Z","caller":"traceutil/trace.go:171","msg":"trace[119839123] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8558; }","duration":"148.069723ms","start":"2026-08-04T15:33:15.284247Z","end":"2026-08-04T15:33:15.432317Z","steps":["trace[119839123] 'agreement among raft nodes before linearized reading' (duration: 147.885827ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:33:15.432374Z","caller":"traceutil/trace.go:171","msg":"trace[1499672235] transaction","detail":"{read_only:false; response_revision:8557; number_of_response:1; }","duration":"238.205576ms","start":"2026-08-04T15:33:15.194146Z","end":"2026-08-04T15:33:15.432352Z","steps":["trace[1499672235] 'process raft request' (duration: 237.681091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:33:15.432428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.612561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-08-04T15:33:15.435107Z","caller":"traceutil/trace.go:171","msg":"trace[390752995] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8558; }","duration":"150.320067ms","start":"2026-08-04T15:33:15.284759Z","end":"2026-08-04T15:33:15.435079Z","steps":["trace[390752995] 'agreement among raft nodes before linearized reading' (duration: 147.480116ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:33:15.432501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.820086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T15:33:15.435284Z","caller":"traceutil/trace.go:171","msg":"trace[1815073438] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:8558; }","duration":"126.616956ms","start":"2026-08-04T15:33:15.308642Z","end":"2026-08-04T15:33:15.435259Z","steps":["trace[1815073438] 'agreement among raft nodes before linearized reading' (duration: 123.817227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:33:15.432552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.36256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-04T15:33:15.435459Z","caller":"traceutil/trace.go:171","msg":"trace[446920784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8558; }","duration":"146.321733ms","start":"2026-08-04T15:33:15.289124Z","end":"2026-08-04T15:33:15.435446Z","steps":["trace[446920784] 'agreement among raft nodes before linearized reading' (duration: 143.363749ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:33:15.548522Z","caller":"traceutil/trace.go:171","msg":"trace[624332681] transaction","detail":"{read_only:false; response_revision:8561; number_of_response:1; }","duration":"107.579047ms","start":"2026-08-04T15:33:15.440921Z","end":"2026-08-04T15:33:15.5485Z","steps":["trace[624332681] 'process raft request' (duration: 107.527186ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:33:15.54898Z","caller":"traceutil/trace.go:171","msg":"trace[817339981] transaction","detail":"{read_only:false; response_revision:8560; number_of_response:1; }","duration":"109.261954ms","start":"2026-08-04T15:33:15.439703Z","end":"2026-08-04T15:33:15.548965Z","steps":["trace[817339981] 'process raft request' (duration: 108.598266ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:33:16.611922Z","caller":"traceutil/trace.go:171","msg":"trace[860941863] linearizableReadLoop","detail":"{readStateIndex:8862; appliedIndex:8861; }","duration":"229.323134ms","start":"2026-08-04T15:33:16.382557Z","end":"2026-08-04T15:33:16.61188Z","steps":["trace[860941863] 'read index received' (duration: 221.828813ms)","trace[860941863] 'applied index is now lower than readState.Index' (duration: 7.492851ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:33:16.612188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.612172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-04T15:33:16.612237Z","caller":"traceutil/trace.go:171","msg":"trace[1447252325] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8566; }","duration":"229.718975ms","start":"2026-08-04T15:33:16.382506Z","end":"2026-08-04T15:33:16.612225Z","steps":["trace[1447252325] 'agreement among raft nodes before linearized reading' (duration: 229.490499ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:33:16.612485Z","caller":"traceutil/trace.go:171","msg":"trace[1811298360] transaction","detail":"{read_only:false; response_revision:8566; number_of_response:1; }","duration":"292.390459ms","start":"2026-08-04T15:33:16.320064Z","end":"2026-08-04T15:33:16.612454Z","steps":["trace[1811298360] 'process raft request' (duration: 284.407814ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:33:17.294707Z","caller":"traceutil/trace.go:171","msg":"trace[1647986757] transaction","detail":"{read_only:false; response_revision:8571; number_of_response:1; }","duration":"197.972757ms","start":"2026-08-04T15:33:17.096701Z","end":"2026-08-04T15:33:17.294674Z","steps":["trace[1647986757] 'process raft request' (duration: 197.695809ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:33:17.307328Z","caller":"traceutil/trace.go:171","msg":"trace[253867140] transaction","detail":"{read_only:false; response_revision:8572; number_of_response:1; }","duration":"185.639757ms","start":"2026-08-04T15:33:17.121657Z","end":"2026-08-04T15:33:17.307297Z","steps":["trace[253867140] 'process raft request' (duration: 185.493543ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:34:01.467447Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002472.wal"} {"level":"info","ts":"2026-08-04T15:35:15.633496Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"80be33c6b0d5a492","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-04T15:35:15.638615Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-04T15:35:15.638716Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-04T15:37:14.679113Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8010} {"level":"info","ts":"2026-08-04T15:37:14.744039Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8010,"took":"62.267743ms","hash":2785649449,"current-db-size-bytes":57724928,"current-db-size":"58 MB","current-db-size-in-use-bytes":28356608,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-04T15:37:14.7441Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2785649449,"revision":8010,"compact-revision":5925}