{"level":"warn","ts":"2026-04-20T08:11:48.417388Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T08:11:48.417487Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.250: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.250:2380","--initial-cluster=instance=https://199.19.213.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.250: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-04-20T08:11:48.417581Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T08:11:48.417598Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.250:2380"]} {"level":"info","ts":"2026-04-20T08:11:48.417624Z","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-04-20T08:11:48.418638Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.250:2379"]} {"level":"info","ts":"2026-04-20T08:11:48.418735Z","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.250:2380"],"listen-peer-urls":["https://199.19.213.250:2380"],"advertise-client-urls":["https://199.19.213.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.250: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-04-20T08:11:48.422472Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.443916ms"} {"level":"info","ts":"2026-04-20T08:11:48.42873Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1918dd9317ae83a2","cluster-id":"1b9b645507bfe47a"} {"level":"info","ts":"2026-04-20T08:11:48.428808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T08:11:48.428832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 became follower at term 0"} {"level":"info","ts":"2026-04-20T08:11:48.428842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1918dd9317ae83a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T08:11:48.428859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 became follower at term 1"} {"level":"info","ts":"2026-04-20T08:11:48.42891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 switched to configuration voters=(1808438874216498082)"} {"level":"warn","ts":"2026-04-20T08:11:48.435848Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T08:11:48.439794Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T08:11:48.441387Z","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-04-20T08:11:48.444912Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1918dd9317ae83a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T08:11:48.445743Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1918dd9317ae83a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T08:11:48.44571Z","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-04-20T08:11:48.446145Z","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-04-20T08:11:48.446201Z","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-04-20T08:11:48.44847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 switched to configuration voters=(1808438874216498082)"} {"level":"info","ts":"2026-04-20T08:11:48.448714Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1b9b645507bfe47a","local-member-id":"1918dd9317ae83a2","added-peer-id":"1918dd9317ae83a2","added-peer-peer-urls":["https://199.19.213.250:2380"]} {"level":"info","ts":"2026-04-20T08:11:48.450182Z","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-04-20T08:11:48.450319Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.250:2380"} {"level":"info","ts":"2026-04-20T08:11:48.450384Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.250:2380"} {"level":"info","ts":"2026-04-20T08:11:48.450508Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1918dd9317ae83a2","initial-advertise-peer-urls":["https://199.19.213.250:2380"],"listen-peer-urls":["https://199.19.213.250:2380"],"advertise-client-urls":["https://199.19.213.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T08:11:48.450543Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T08:11:49.229739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T08:11:49.229823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T08:11:49.22985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 received MsgPreVoteResp from 1918dd9317ae83a2 at term 1"} {"level":"info","ts":"2026-04-20T08:11:49.229865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 became candidate at term 2"} {"level":"info","ts":"2026-04-20T08:11:49.229874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 received MsgVoteResp from 1918dd9317ae83a2 at term 2"} {"level":"info","ts":"2026-04-20T08:11:49.229885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 became leader at term 2"} {"level":"info","ts":"2026-04-20T08:11:49.229895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1918dd9317ae83a2 elected leader 1918dd9317ae83a2 at term 2"} {"level":"info","ts":"2026-04-20T08:11:49.231154Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1918dd9317ae83a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.250:2379]}","request-path":"/0/members/1918dd9317ae83a2/attributes","cluster-id":"1b9b645507bfe47a","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T08:11:49.231194Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:11:49.231214Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T08:11:49.231265Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T08:11:49.231563Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T08:11:49.231723Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T08:11:49.23353Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1b9b645507bfe47a","local-member-id":"1918dd9317ae83a2","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:11:49.233687Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:11:49.233726Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:11:49.233886Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T08:11:49.235787Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.250:2379"} {"level":"warn","ts":"2026-04-20T08:12:20.556065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.397252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:12:20.556185Z","caller":"traceutil/trace.go:171","msg":"trace[1527373722] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"191.684766ms","start":"2026-04-20T08:12:20.364486Z","end":"2026-04-20T08:12:20.556171Z","steps":["trace[1527373722] 'compare' (duration: 187.095985ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:12:20.568148Z","caller":"traceutil/trace.go:171","msg":"trace[1141979939] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"171.9413ms","start":"2026-04-20T08:12:20.396182Z","end":"2026-04-20T08:12:20.568123Z","steps":["trace[1141979939] 'process raft request' (duration: 171.811257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:12:27.44227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.505972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:12:27.442428Z","caller":"traceutil/trace.go:171","msg":"trace[1833071676] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"359.290557ms","start":"2026-04-20T08:12:27.083114Z","end":"2026-04-20T08:12:27.442404Z","steps":["trace[1833071676] 'read index received' (duration: 68.511µs)","trace[1833071676] 'applied index is now lower than readState.Index' (duration: 359.220746ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:12:27.442479Z","caller":"traceutil/trace.go:171","msg":"trace[2130267427] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"455.969491ms","start":"2026-04-20T08:12:26.986459Z","end":"2026-04-20T08:12:27.442428Z","steps":["trace[2130267427] 'process raft request' (duration: 28.213483ms)","trace[2130267427] 'compare' (duration: 427.284998ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:12:27.442644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:12:26.986424Z","time spent":"456.147005ms","remote":"127.0.0.1:45300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T08:12:27.442911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.773318ms","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-04-20T08:12:27.443069Z","caller":"traceutil/trace.go:171","msg":"trace[537363645] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"359.980493ms","start":"2026-04-20T08:12:27.083053Z","end":"2026-04-20T08:12:27.443033Z","steps":["trace[537363645] 'agreement among raft nodes before linearized reading' (duration: 359.437381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:12:27.443126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:12:27.083032Z","time spent":"360.083076ms","remote":"127.0.0.1:45384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T08:12:42.986054Z","caller":"traceutil/trace.go:171","msg":"trace[151654111] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"249.100727ms","start":"2026-04-20T08:12:42.736925Z","end":"2026-04-20T08:12:42.986026Z","steps":["trace[151654111] 'process raft request' (duration: 248.925063ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:32.86999Z","caller":"traceutil/trace.go:171","msg":"trace[1160467283] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"134.182267ms","start":"2026-04-20T08:13:32.735784Z","end":"2026-04-20T08:13:32.869967Z","steps":["trace[1160467283] 'process raft request' (duration: 133.955263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:55.977087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.459012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d34a938d-a857-40a8-a015-24dec5742b1f\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-04-20T08:13:55.977162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.922789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:13:55.977219Z","caller":"traceutil/trace.go:171","msg":"trace[1256360538] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d34a938d-a857-40a8-a015-24dec5742b1f; range_end:; response_count:1; response_revision:1793; }","duration":"145.672586ms","start":"2026-04-20T08:13:55.831519Z","end":"2026-04-20T08:13:55.977192Z","steps":["trace[1256360538] 'range keys from in-memory index tree' (duration: 145.225626ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:55.977265Z","caller":"traceutil/trace.go:171","msg":"trace[76854653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1793; }","duration":"211.082453ms","start":"2026-04-20T08:13:55.766153Z","end":"2026-04-20T08:13:55.977235Z","steps":["trace[76854653] 'range keys from in-memory index tree' (duration: 210.832768ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:56.098341Z","caller":"traceutil/trace.go:171","msg":"trace[1544036990] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"113.495409ms","start":"2026-04-20T08:13:55.984816Z","end":"2026-04-20T08:13:56.098311Z","steps":["trace[1544036990] 'process raft request' (duration: 113.370577ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:56.098316Z","caller":"traceutil/trace.go:171","msg":"trace[2054605415] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"116.899802ms","start":"2026-04-20T08:13:55.981365Z","end":"2026-04-20T08:13:56.098265Z","steps":["trace[2054605415] 'process raft request' (duration: 111.426924ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:40.742109Z","caller":"traceutil/trace.go:171","msg":"trace[567082341] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"113.820822ms","start":"2026-04-20T08:14:40.628257Z","end":"2026-04-20T08:14:40.742078Z","steps":["trace[567082341] 'process raft request' (duration: 113.639208ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:40.991312Z","caller":"traceutil/trace.go:171","msg":"trace[517129207] linearizableReadLoop","detail":"{readStateIndex:2179; appliedIndex:2178; }","duration":"190.495233ms","start":"2026-04-20T08:14:40.80079Z","end":"2026-04-20T08:14:40.991285Z","steps":["trace[517129207] 'read index received' (duration: 190.35687ms)","trace[517129207] 'applied index is now lower than readState.Index' (duration: 137.173µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:14:40.991378Z","caller":"traceutil/trace.go:171","msg":"trace[1254563088] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"211.143308ms","start":"2026-04-20T08:14:40.780197Z","end":"2026-04-20T08:14:40.99134Z","steps":["trace[1254563088] 'process raft request' (duration: 210.949273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:14:40.991548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.749908ms","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-04-20T08:14:40.991628Z","caller":"traceutil/trace.go:171","msg":"trace[1528733816] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2096; }","duration":"190.873281ms","start":"2026-04-20T08:14:40.800738Z","end":"2026-04-20T08:14:40.991611Z","steps":["trace[1528733816] 'agreement among raft nodes before linearized reading' (duration: 190.684367ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:03.541304Z","caller":"traceutil/trace.go:171","msg":"trace[379314886] transaction","detail":"{read_only:false; response_revision:2739; number_of_response:1; }","duration":"104.163934ms","start":"2026-04-20T08:16:03.437112Z","end":"2026-04-20T08:16:03.541275Z","steps":["trace[379314886] 'process raft request' (duration: 94.772321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:46.249595Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9485317118432339593,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:16:46.750461Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9485317118432339593,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:16:47.072493Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.392612851s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T08:16:47.154943Z","caller":"traceutil/trace.go:171","msg":"trace[71901412] linearizableReadLoop","detail":"{readStateIndex:3083; appliedIndex:3080; }","duration":"1.405407016s","start":"2026-04-20T08:16:45.749507Z","end":"2026-04-20T08:16:47.154914Z","steps":["trace[71901412] 'read index received' (duration: 1.323289568s)","trace[71901412] 'applied index is now lower than readState.Index' (duration: 82.116538ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:16:47.155022Z","caller":"traceutil/trace.go:171","msg":"trace[1330489352] transaction","detail":"{read_only:false; response_revision:2966; number_of_response:1; }","duration":"1.47521279s","start":"2026-04-20T08:16:45.679762Z","end":"2026-04-20T08:16:47.154974Z","steps":["trace[1330489352] 'process raft request' (duration: 1.393044481s)","trace[1330489352] 'compare' (duration: 81.541796ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:16:47.15504Z","caller":"traceutil/trace.go:171","msg":"trace[942688697] transaction","detail":"{read_only:false; response_revision:2968; number_of_response:1; }","duration":"718.987804ms","start":"2026-04-20T08:16:46.436023Z","end":"2026-04-20T08:16:47.155011Z","steps":["trace[942688697] 'process raft request' (duration: 718.854071ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:47.155043Z","caller":"traceutil/trace.go:171","msg":"trace[1815513329] transaction","detail":"{read_only:false; response_revision:2967; number_of_response:1; }","duration":"903.225061ms","start":"2026-04-20T08:16:46.251774Z","end":"2026-04-20T08:16:47.154999Z","steps":["trace[1815513329] 'process raft request' (duration: 903.042158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.155133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.389716779s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:16:47.155186Z","caller":"traceutil/trace.go:171","msg":"trace[2073873556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2968; }","duration":"1.38979326s","start":"2026-04-20T08:16:45.765379Z","end":"2026-04-20T08:16:47.155172Z","steps":["trace[2073873556] 'agreement among raft nodes before linearized reading' (duration: 1.389713628s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.155228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:45.765357Z","time spent":"1.389862072s","remote":"127.0.0.1:45088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T08:16:47.155277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041199193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T08:16:47.15529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070166587s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T08:16:47.155339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.144532ms","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-04-20T08:16:47.155354Z","caller":"traceutil/trace.go:171","msg":"trace[593719731] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2968; }","duration":"1.041290226s","start":"2026-04-20T08:16:46.114046Z","end":"2026-04-20T08:16:47.155336Z","steps":["trace[593719731] 'agreement among raft nodes before linearized reading' (duration: 1.041154592s)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:47.155378Z","caller":"traceutil/trace.go:171","msg":"trace[1654157983] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2968; }","duration":"852.230804ms","start":"2026-04-20T08:16:46.303135Z","end":"2026-04-20T08:16:47.155366Z","steps":["trace[1654157983] 'agreement among raft nodes before linearized reading' (duration: 852.06675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.155397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:45.679735Z","time spent":"1.475345452s","remote":"127.0.0.1:45384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:16:47.155431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:46.303109Z","time spent":"852.315036ms","remote":"127.0.0.1:45384","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-04-20T08:16:47.15537Z","caller":"traceutil/trace.go:171","msg":"trace[154797319] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2968; }","duration":"1.070290301s","start":"2026-04-20T08:16:46.085056Z","end":"2026-04-20T08:16:47.155347Z","steps":["trace[154797319] 'agreement among raft nodes before linearized reading' (duration: 1.070156627s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.155499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:46.251756Z","time spent":"903.363614ms","remote":"127.0.0.1:45384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:16:47.155392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:46.114035Z","time spent":"1.041340556s","remote":"127.0.0.1:39706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:16:47.155513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.128071ms","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-04-20T08:16:47.155643Z","caller":"traceutil/trace.go:171","msg":"trace[500329612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2968; }","duration":"155.296435ms","start":"2026-04-20T08:16:47.000307Z","end":"2026-04-20T08:16:47.155603Z","steps":["trace[500329612] 'agreement among raft nodes before linearized reading' (duration: 154.881925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.155557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:46.085036Z","time spent":"1.070499425s","remote":"127.0.0.1:45340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:16:47.155621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.618691ms","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":"warn","ts":"2026-04-20T08:16:47.155792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:46.436Z","time spent":"719.115256ms","remote":"127.0.0.1:45384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:16:47.155732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045192249s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-04-20T08:16:47.155878Z","caller":"traceutil/trace.go:171","msg":"trace[1640898046] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2968; }","duration":"988.908657ms","start":"2026-04-20T08:16:46.166911Z","end":"2026-04-20T08:16:47.15582Z","steps":["trace[1640898046] 'agreement among raft nodes before linearized reading' (duration: 988.334974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.155983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:46.166895Z","time spent":"989.073981ms","remote":"127.0.0.1:45384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":580,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-04-20T08:16:47.155979Z","caller":"traceutil/trace.go:171","msg":"trace[1055461601] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2968; }","duration":"1.045457535s","start":"2026-04-20T08:16:46.110445Z","end":"2026-04-20T08:16:47.155903Z","steps":["trace[1055461601] 'agreement among raft nodes before linearized reading' (duration: 1.044946554s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.15613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.349463262s","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-04-20T08:16:47.155949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.406154793s","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-04-20T08:16:47.156169Z","caller":"traceutil/trace.go:171","msg":"trace[1723061850] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2968; }","duration":"1.349542704s","start":"2026-04-20T08:16:45.806617Z","end":"2026-04-20T08:16:47.156159Z","steps":["trace[1723061850] 'agreement among raft nodes before linearized reading' (duration: 1.349431612s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.156193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:45.806595Z","time spent":"1.349593585s","remote":"127.0.0.1:45310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1391,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-04-20T08:16:47.156144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:46.110432Z","time spent":"1.045692989s","remote":"127.0.0.1:45384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":574,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-20T08:16:47.156231Z","caller":"traceutil/trace.go:171","msg":"trace[1054711467] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2968; }","duration":"1.406721985s","start":"2026-04-20T08:16:45.749464Z","end":"2026-04-20T08:16:47.156186Z","steps":["trace[1054711467] 'agreement among raft nodes before linearized reading' (duration: 1.40561712s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.156455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:45.749447Z","time spent":"1.406998351s","remote":"127.0.0.1:45384","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-04-20T08:16:49.426656Z","caller":"traceutil/trace.go:171","msg":"trace[1732045876] transaction","detail":"{read_only:false; response_revision:2984; number_of_response:1; }","duration":"205.065226ms","start":"2026-04-20T08:16:49.221558Z","end":"2026-04-20T08:16:49.426623Z","steps":["trace[1732045876] 'process raft request' (duration: 205.000684ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:49.426677Z","caller":"traceutil/trace.go:171","msg":"trace[699288108] transaction","detail":"{read_only:false; response_revision:2982; number_of_response:1; }","duration":"205.551656ms","start":"2026-04-20T08:16:49.221087Z","end":"2026-04-20T08:16:49.426638Z","steps":["trace[699288108] 'process raft request' (duration: 134.812983ms)","trace[699288108] 'compare' (duration: 70.435927ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:16:49.42674Z","caller":"traceutil/trace.go:171","msg":"trace[626829979] transaction","detail":"{read_only:false; response_revision:2983; number_of_response:1; }","duration":"205.344022ms","start":"2026-04-20T08:16:49.221349Z","end":"2026-04-20T08:16:49.426693Z","steps":["trace[626829979] 'process raft request' (duration: 205.160428ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:49.426989Z","caller":"traceutil/trace.go:171","msg":"trace[409908921] transaction","detail":"{read_only:false; response_revision:2985; number_of_response:1; }","duration":"205.091227ms","start":"2026-04-20T08:16:49.221855Z","end":"2026-04-20T08:16:49.426947Z","steps":["trace[409908921] 'process raft request' (duration: 204.731939ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:49.427018Z","caller":"traceutil/trace.go:171","msg":"trace[2005195846] transaction","detail":"{read_only:false; response_revision:2986; number_of_response:1; }","duration":"175.602421ms","start":"2026-04-20T08:16:49.251389Z","end":"2026-04-20T08:16:49.426991Z","steps":["trace[2005195846] 'process raft request' (duration: 175.482298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:53.768592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.64822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T08:16:53.768712Z","caller":"traceutil/trace.go:171","msg":"trace[1308112200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3010; }","duration":"102.849254ms","start":"2026-04-20T08:16:53.665836Z","end":"2026-04-20T08:16:53.768686Z","steps":["trace[1308112200] 'range keys from in-memory index tree' (duration: 102.356584ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:53.795979Z","caller":"traceutil/trace.go:171","msg":"trace[500556091] transaction","detail":"{read_only:false; response_revision:3011; number_of_response:1; }","duration":"118.984532ms","start":"2026-04-20T08:16:53.676955Z","end":"2026-04-20T08:16:53.79594Z","steps":["trace[500556091] 'process raft request' (duration: 118.792998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:22.011287Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9485317118432340138,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:22.511962Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9485317118432340138,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:23.012733Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9485317118432340138,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:23.513104Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9485317118432340138,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:23.766097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00040411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T08:17:23.766175Z","caller":"traceutil/trace.go:171","msg":"trace[1851165742] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000520932s","start":"2026-04-20T08:17:21.765638Z","end":"2026-04-20T08:17:23.766159Z","steps":["trace[1851165742] 'agreement among raft nodes before linearized reading' (duration: 2.00043124s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:23.766218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.765625Z","time spent":"2.000584824s","remote":"127.0.0.1:45088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T08:17:24.014215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9485317118432340138,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:24.515364Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9485317118432340138,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:25.016267Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9485317118432340138,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:25.516896Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9485317118432340138,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:25.681201Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.606176728s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T08:17:25.681914Z","caller":"traceutil/trace.go:171","msg":"trace[1573705273] transaction","detail":"{read_only:false; response_revision:3149; number_of_response:1; }","duration":"4.606960405s","start":"2026-04-20T08:17:21.074918Z","end":"2026-04-20T08:17:25.681879Z","steps":["trace[1573705273] 'process raft request' (duration: 4.606635668s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.6821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.074902Z","time spent":"4.607099158s","remote":"127.0.0.1:45384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:17:25.691118Z","caller":"traceutil/trace.go:171","msg":"trace[962414431] transaction","detail":"{read_only:false; response_revision:3150; number_of_response:1; }","duration":"4.320966617s","start":"2026-04-20T08:17:21.370129Z","end":"2026-04-20T08:17:25.691095Z","steps":["trace[962414431] 'process raft request' (duration: 4.320572767s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.691247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.370105Z","time spent":"4.321070098s","remote":"127.0.0.1:45384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:17:25.691419Z","caller":"traceutil/trace.go:171","msg":"trace[627406827] transaction","detail":"{read_only:false; response_revision:3151; number_of_response:1; }","duration":"4.259465202s","start":"2026-04-20T08:17:21.431944Z","end":"2026-04-20T08:17:25.69141Z","steps":["trace[627406827] 'process raft request' (duration: 4.259120595s)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:17:25.691426Z","caller":"traceutil/trace.go:171","msg":"trace[473684109] transaction","detail":"{read_only:false; response_revision:3152; number_of_response:1; }","duration":"4.140814736s","start":"2026-04-20T08:17:21.550578Z","end":"2026-04-20T08:17:25.691393Z","steps":["trace[473684109] 'process raft request' (duration: 4.140694034s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.691607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.550563Z","time spent":"4.140946979s","remote":"127.0.0.1:45384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:17:25.691613Z","caller":"traceutil/trace.go:171","msg":"trace[1747041883] transaction","detail":"{read_only:false; response_revision:3153; number_of_response:1; }","duration":"3.781347795s","start":"2026-04-20T08:17:21.910245Z","end":"2026-04-20T08:17:25.691592Z","steps":["trace[1747041883] 'process raft request' (duration: 3.781126731s)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:17:25.69162Z","caller":"traceutil/trace.go:171","msg":"trace[930111762] linearizableReadLoop","detail":"{readStateIndex:3276; appliedIndex:3271; }","duration":"4.181249027s","start":"2026-04-20T08:17:21.51034Z","end":"2026-04-20T08:17:25.691589Z","steps":["trace[930111762] 'read index received' (duration: 4.171224711s)","trace[930111762] 'applied index is now lower than readState.Index' (duration: 10.022876ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:17:25.691723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.181370231s","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-04-20T08:17:25.691722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.910233Z","time spent":"3.781427897s","remote":"127.0.0.1:45384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:17:25.691753Z","caller":"traceutil/trace.go:171","msg":"trace[298355901] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3153; }","duration":"4.181450062s","start":"2026-04-20T08:17:21.510296Z","end":"2026-04-20T08:17:25.691746Z","steps":["trace[298355901] 'agreement among raft nodes before linearized reading' (duration: 4.18138579s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.691777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.510282Z","time spent":"4.181487583s","remote":"127.0.0.1:45626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:17:25.691461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.431922Z","time spent":"4.259519173s","remote":"127.0.0.1:45384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:17:25.691813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.410036388s","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":"info","ts":"2026-04-20T08:17:25.69186Z","caller":"traceutil/trace.go:171","msg":"trace[892631753] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:3153; }","duration":"3.410139151s","start":"2026-04-20T08:17:22.281712Z","end":"2026-04-20T08:17:25.691851Z","steps":["trace[892631753] 'agreement among raft nodes before linearized reading' (duration: 3.410051389s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.691896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:22.281694Z","time spent":"3.410192182s","remote":"127.0.0.1:39598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:17:25.691974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.389451593s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-20T08:17:25.692029Z","caller":"traceutil/trace.go:171","msg":"trace[1314768026] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3153; }","duration":"1.389533485s","start":"2026-04-20T08:17:24.302469Z","end":"2026-04-20T08:17:25.692002Z","steps":["trace[1314768026] 'agreement among raft nodes before linearized reading' (duration: 1.389394312s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.692061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:24.30245Z","time spent":"1.389603437s","remote":"127.0.0.1:45272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1875,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-20T08:17:25.692085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.890257921s","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-04-20T08:17:25.692111Z","caller":"traceutil/trace.go:171","msg":"trace[224745648] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3153; }","duration":"3.890336302s","start":"2026-04-20T08:17:21.801766Z","end":"2026-04-20T08:17:25.692102Z","steps":["trace[224745648] 'agreement among raft nodes before linearized reading' (duration: 3.89022972s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.692145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.801746Z","time spent":"3.890391894s","remote":"127.0.0.1:45310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1391,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-04-20T08:17:25.692234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.173979741s","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-04-20T08:17:25.692282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.301013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T08:17:25.692304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.591135668s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a802a1d5e090eb\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-20T08:17:25.69233Z","caller":"traceutil/trace.go:171","msg":"trace[722466721] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a802a1d5e090eb; range_end:; response_count:1; response_revision:3153; }","duration":"3.59118649s","start":"2026-04-20T08:17:22.101137Z","end":"2026-04-20T08:17:25.692323Z","steps":["trace[722466721] 'agreement among raft nodes before linearized reading' (duration: 3.591124099s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.692355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:22.101121Z","time spent":"3.59122961s","remote":"127.0.0.1:45170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":803,"request content":"key:\"/registry/events/auth-system/keycloak-0.18a802a1d5e090eb\" "} {"level":"warn","ts":"2026-04-20T08:17:25.692412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.268480519s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-04-20T08:17:25.692424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.925521868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:17:25.692441Z","caller":"traceutil/trace.go:171","msg":"trace[1464853936] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3153; }","duration":"1.26855046s","start":"2026-04-20T08:17:24.423883Z","end":"2026-04-20T08:17:25.692433Z","steps":["trace[1464853936] 'agreement among raft nodes before linearized reading' (duration: 1.268461468s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.692468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:24.42386Z","time spent":"1.268602841s","remote":"127.0.0.1:45300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-20T08:17:25.692326Z","caller":"traceutil/trace.go:171","msg":"trace[1869161667] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:3153; }","duration":"323.349934ms","start":"2026-04-20T08:17:25.368967Z","end":"2026-04-20T08:17:25.692317Z","steps":["trace[1869161667] 'agreement among raft nodes before linearized reading' (duration: 323.273832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.692522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.820856056s","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":"warn","ts":"2026-04-20T08:17:25.692483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.998856469s","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-04-20T08:17:25.692545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:25.368887Z","time spent":"323.646899ms","remote":"127.0.0.1:45402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":4,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-20T08:17:25.69259Z","caller":"traceutil/trace.go:171","msg":"trace[591161205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3153; }","duration":"3.998992061s","start":"2026-04-20T08:17:21.693578Z","end":"2026-04-20T08:17:25.69257Z","steps":["trace[591161205] 'agreement among raft nodes before linearized reading' (duration: 3.998692185s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.692469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.763092272s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T08:17:25.692641Z","caller":"traceutil/trace.go:171","msg":"trace[31551793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3153; }","duration":"3.763284215s","start":"2026-04-20T08:17:21.929347Z","end":"2026-04-20T08:17:25.692632Z","steps":["trace[31551793] 'agreement among raft nodes before linearized reading' (duration: 3.763082511s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.692641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.693572Z","time spent":"3.999057663s","remote":"127.0.0.1:45384","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-04-20T08:17:25.692662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.042615752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-20T08:17:25.692668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.929335Z","time spent":"3.763325187s","remote":"127.0.0.1:45384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T08:17:25.692684Z","caller":"traceutil/trace.go:171","msg":"trace[222107390] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3153; }","duration":"4.042663922s","start":"2026-04-20T08:17:21.650015Z","end":"2026-04-20T08:17:25.692679Z","steps":["trace[222107390] 'agreement among raft nodes before linearized reading' (duration: 4.042608341s)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:17:25.692288Z","caller":"traceutil/trace.go:171","msg":"trace[1487414719] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3153; }","duration":"4.174231226s","start":"2026-04-20T08:17:21.518041Z","end":"2026-04-20T08:17:25.692272Z","steps":["trace[1487414719] 'agreement among raft nodes before linearized reading' (duration: 4.173713435s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.692652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.349761151s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.250\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T08:17:25.692466Z","caller":"traceutil/trace.go:171","msg":"trace[1140808641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3153; }","duration":"1.925844765s","start":"2026-04-20T08:17:23.76661Z","end":"2026-04-20T08:17:25.692455Z","steps":["trace[1140808641] 'agreement among raft nodes before linearized reading' (duration: 1.925528428s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.692759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.650003Z","time spent":"4.042749965s","remote":"127.0.0.1:45384","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-04-20T08:17:25.692778Z","caller":"traceutil/trace.go:171","msg":"trace[568132374] range","detail":"{range_begin:/registry/masterleases/199.19.213.250; range_end:; response_count:1; response_revision:3153; }","duration":"3.349940325s","start":"2026-04-20T08:17:22.342813Z","end":"2026-04-20T08:17:25.692753Z","steps":["trace[568132374] 'agreement among raft nodes before linearized reading' (duration: 3.349539717s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.692802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:23.766586Z","time spent":"1.926204323s","remote":"127.0.0.1:45096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T08:17:25.692544Z","caller":"traceutil/trace.go:171","msg":"trace[972693093] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:3153; }","duration":"3.820908397s","start":"2026-04-20T08:17:21.87163Z","end":"2026-04-20T08:17:25.692539Z","steps":["trace[972693093] 'agreement among raft nodes before linearized reading' (duration: 3.820851346s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.692832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:22.342804Z","time spent":"3.350018766s","remote":"127.0.0.1:45128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.250\" "} {"level":"warn","ts":"2026-04-20T08:17:25.692843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.983669537s","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":"warn","ts":"2026-04-20T08:17:25.692727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.518031Z","time spent":"4.174686015s","remote":"127.0.0.1:45384","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-04-20T08:17:25.692866Z","caller":"traceutil/trace.go:171","msg":"trace[1456557228] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:3153; }","duration":"2.983724678s","start":"2026-04-20T08:17:22.709133Z","end":"2026-04-20T08:17:25.692858Z","steps":["trace[1456557228] 'agreement among raft nodes before linearized reading' (duration: 2.983683637s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.692883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:22.709117Z","time spent":"2.983762129s","remote":"127.0.0.1:45564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:17:25.692887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.871615Z","time spent":"3.821228014s","remote":"127.0.0.1:45196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":26,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:17:25.69275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.035970889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"warn","ts":"2026-04-20T08:17:25.693316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.99565122s","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-04-20T08:17:25.693404Z","caller":"traceutil/trace.go:171","msg":"trace[2071378247] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3153; }","duration":"3.995757313s","start":"2026-04-20T08:17:21.697636Z","end":"2026-04-20T08:17:25.693393Z","steps":["trace[2071378247] 'agreement among raft nodes before linearized reading' (duration: 3.995135799s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.693442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.697624Z","time spent":"3.995809523s","remote":"127.0.0.1:45384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T08:17:25.693325Z","caller":"traceutil/trace.go:171","msg":"trace[1613148712] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3153; }","duration":"4.03649828s","start":"2026-04-20T08:17:21.656521Z","end":"2026-04-20T08:17:25.693019Z","steps":["trace[1613148712] 'agreement among raft nodes before linearized reading' (duration: 4.035791055s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.693641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.65651Z","time spent":"4.037118934s","remote":"127.0.0.1:45384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":575,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-20T08:20:20.404808Z","caller":"traceutil/trace.go:171","msg":"trace[1265408927] linearizableReadLoop","detail":"{readStateIndex:5199; appliedIndex:5198; }","duration":"147.605848ms","start":"2026-04-20T08:20:20.257176Z","end":"2026-04-20T08:20:20.404782Z","steps":["trace[1265408927] 'read index received' (duration: 49.19503ms)","trace[1265408927] 'applied index is now lower than readState.Index' (duration: 98.409588ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:20:20.405063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.891303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d0875177-d797-4a1d-aa78-1ece007f8de5\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-20T08:20:20.405096Z","caller":"traceutil/trace.go:171","msg":"trace[2022928997] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d0875177-d797-4a1d-aa78-1ece007f8de5; range_end:; response_count:1; response_revision:5006; }","duration":"147.989615ms","start":"2026-04-20T08:20:20.257095Z","end":"2026-04-20T08:20:20.405085Z","steps":["trace[2022928997] 'agreement among raft nodes before linearized reading' (duration: 147.805501ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:20:20.405218Z","caller":"traceutil/trace.go:171","msg":"trace[1796401763] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"292.751262ms","start":"2026-04-20T08:20:20.11242Z","end":"2026-04-20T08:20:20.405171Z","steps":["trace[1796401763] 'process raft request' (duration: 193.975456ms)","trace[1796401763] 'compare' (duration: 98.136382ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:20:20.723406Z","caller":"traceutil/trace.go:171","msg":"trace[1530061598] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"118.334138ms","start":"2026-04-20T08:20:20.605026Z","end":"2026-04-20T08:20:20.72336Z","steps":["trace[1530061598] 'process raft request' (duration: 118.03266ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:20:20.723379Z","caller":"traceutil/trace.go:171","msg":"trace[1237781351] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"130.382256ms","start":"2026-04-20T08:20:20.592909Z","end":"2026-04-20T08:20:20.723291Z","steps":["trace[1237781351] 'process raft request' (duration: 129.942247ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:20:32.090889Z","caller":"traceutil/trace.go:171","msg":"trace[2026710825] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"142.186529ms","start":"2026-04-20T08:20:31.948673Z","end":"2026-04-20T08:20:32.09086Z","steps":["trace[2026710825] 'process raft request' (duration: 141.991615ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:20:32.368141Z","caller":"traceutil/trace.go:171","msg":"trace[1560495327] transaction","detail":"{read_only:false; response_revision:5243; number_of_response:1; }","duration":"255.364506ms","start":"2026-04-20T08:20:32.112761Z","end":"2026-04-20T08:20:32.368125Z","steps":["trace[1560495327] 'process raft request' (duration: 255.171932ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:20:40.195836Z","caller":"traceutil/trace.go:171","msg":"trace[902732866] transaction","detail":"{read_only:false; response_revision:5425; number_of_response:1; }","duration":"152.739928ms","start":"2026-04-20T08:20:40.043046Z","end":"2026-04-20T08:20:40.195786Z","steps":["trace[902732866] 'process raft request' (duration: 152.37171ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:21:31.247213Z","caller":"traceutil/trace.go:171","msg":"trace[1508506919] transaction","detail":"{read_only:false; response_revision:5910; number_of_response:1; }","duration":"134.010385ms","start":"2026-04-20T08:21:31.113149Z","end":"2026-04-20T08:21:31.24716Z","steps":["trace[1508506919] 'process raft request' (duration: 133.814641ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:21:31.423173Z","caller":"traceutil/trace.go:171","msg":"trace[1995344769] transaction","detail":"{read_only:false; response_revision:5912; number_of_response:1; }","duration":"126.198668ms","start":"2026-04-20T08:21:31.296921Z","end":"2026-04-20T08:21:31.42312Z","steps":["trace[1995344769] 'process raft request' (duration: 49.318259ms)","trace[1995344769] 'compare' (duration: 76.532262ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:21:31.423186Z","caller":"traceutil/trace.go:171","msg":"trace[2132477795] transaction","detail":"{read_only:false; response_revision:5913; number_of_response:1; }","duration":"126.132436ms","start":"2026-04-20T08:21:31.297007Z","end":"2026-04-20T08:21:31.423139Z","steps":["trace[2132477795] 'process raft request' (duration: 125.984433ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:21:31.423281Z","caller":"traceutil/trace.go:171","msg":"trace[1518362569] linearizableReadLoop","detail":"{readStateIndex:6131; appliedIndex:6129; }","duration":"123.213023ms","start":"2026-04-20T08:21:31.300015Z","end":"2026-04-20T08:21:31.423228Z","steps":["trace[1518362569] 'read index received' (duration: 46.207252ms)","trace[1518362569] 'applied index is now lower than readState.Index' (duration: 77.003811ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:21:31.423526Z","caller":"traceutil/trace.go:171","msg":"trace[349490275] transaction","detail":"{read_only:false; response_revision:5914; number_of_response:1; }","duration":"123.150482ms","start":"2026-04-20T08:21:31.300317Z","end":"2026-04-20T08:21:31.423467Z","steps":["trace[349490275] 'process raft request' (duration: 122.768884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:21:31.423919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.885618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:11 size:101672"} {"level":"info","ts":"2026-04-20T08:21:31.423987Z","caller":"traceutil/trace.go:171","msg":"trace[2140387997] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:11; response_revision:5914; }","duration":"124.018491ms","start":"2026-04-20T08:21:31.29995Z","end":"2026-04-20T08:21:31.423969Z","steps":["trace[2140387997] 'agreement among raft nodes before linearized reading' (duration: 123.597122ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:21:49.491962Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2986} {"level":"info","ts":"2026-04-20T08:21:49.744385Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2986,"took":"250.497054ms","hash":1028494755,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":33599488,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-20T08:21:49.744478Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1028494755,"revision":2986,"compact-revision":-1} {"level":"warn","ts":"2026-04-20T08:26:38.195656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.283304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:26:38.195872Z","caller":"traceutil/trace.go:171","msg":"trace[1137562132] transaction","detail":"{read_only:false; response_revision:8199; number_of_response:1; }","duration":"178.36802ms","start":"2026-04-20T08:26:38.017485Z","end":"2026-04-20T08:26:38.195853Z","steps":["trace[1137562132] 'process raft request' (duration: 178.300408ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:26:38.195977Z","caller":"traceutil/trace.go:171","msg":"trace[1544464135] transaction","detail":"{read_only:false; response_revision:8198; number_of_response:1; }","duration":"232.166562ms","start":"2026-04-20T08:26:37.963754Z","end":"2026-04-20T08:26:38.195921Z","steps":["trace[1544464135] 'process raft request' (duration: 126.52204ms)","trace[1544464135] 'compare' (duration: 105.093751ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:26:49.872705Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6066} {"level":"info","ts":"2026-04-20T08:26:49.872876Z","caller":"traceutil/trace.go:171","msg":"trace[1018451141] linearizableReadLoop","detail":"{readStateIndex:8554; appliedIndex:8553; }","duration":"203.284934ms","start":"2026-04-20T08:26:49.669557Z","end":"2026-04-20T08:26:49.872842Z","steps":["trace[1018451141] 'read index received' (duration: 169.493199ms)","trace[1018451141] 'applied index is now lower than readState.Index' (duration: 33.789255ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:26:49.872961Z","caller":"traceutil/trace.go:171","msg":"trace[1707981964] compact","detail":"{revision:6066; response_revision:8259; }","duration":"350.652081ms","start":"2026-04-20T08:26:49.522267Z","end":"2026-04-20T08:26:49.872919Z","steps":["trace[1707981964] 'process raft request' (duration: 316.713423ms)","trace[1707981964] 'check and update compact revision' (duration: 33.700532ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:26:49.873085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:26:49.522231Z","time spent":"350.849295ms","remote":"127.0.0.1:45102","response type":"/etcdserverpb.KV/Compact","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-20T08:26:49.873082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.492778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:26:49.873155Z","caller":"traceutil/trace.go:171","msg":"trace[1567095172] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:8259; }","duration":"203.642902ms","start":"2026-04-20T08:26:49.669486Z","end":"2026-04-20T08:26:49.873129Z","steps":["trace[1567095172] 'agreement among raft nodes before linearized reading' (duration: 203.482669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:26:49.873281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.870879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:26:49.873349Z","caller":"traceutil/trace.go:171","msg":"trace[688010774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8259; }","duration":"107.009321ms","start":"2026-04-20T08:26:49.766321Z","end":"2026-04-20T08:26:49.87333Z","steps":["trace[688010774] 'agreement among raft nodes before linearized reading' (duration: 106.733295ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:26:50.208482Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6066,"took":"331.574461ms","hash":4056307896,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":23195648,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-20T08:26:50.208561Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4056307896,"revision":6066,"compact-revision":2986} {"level":"warn","ts":"2026-04-20T08:26:50.616314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.580433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:26:50.616416Z","caller":"traceutil/trace.go:171","msg":"trace[1378561581] linearizableReadLoop","detail":"{readStateIndex:8558; appliedIndex:8557; }","duration":"306.055134ms","start":"2026-04-20T08:26:50.310346Z","end":"2026-04-20T08:26:50.616402Z","steps":["trace[1378561581] 'read index received' (duration: 136.155465ms)","trace[1378561581] 'applied index is now lower than readState.Index' (duration: 169.898529ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:26:50.616679Z","caller":"traceutil/trace.go:171","msg":"trace[1821187909] transaction","detail":"{read_only:false; response_revision:8263; number_of_response:1; }","duration":"372.13399ms","start":"2026-04-20T08:26:50.244537Z","end":"2026-04-20T08:26:50.616671Z","steps":["trace[1821187909] 'process raft request' (duration: 202.120508ms)","trace[1821187909] 'compare' (duration: 169.248826ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:26:50.616714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:26:50.244524Z","time spent":"372.17134ms","remote":"127.0.0.1:45384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:26:50.616837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.491114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:26:50.616856Z","caller":"traceutil/trace.go:171","msg":"trace[874653386] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:8263; }","duration":"306.560846ms","start":"2026-04-20T08:26:50.310289Z","end":"2026-04-20T08:26:50.61685Z","steps":["trace[874653386] 'agreement among raft nodes before linearized reading' (duration: 306.519675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:26:50.616871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:26:50.310269Z","time spent":"306.599286ms","remote":"127.0.0.1:39746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-04-20T08:27:18.912908Z","caller":"traceutil/trace.go:171","msg":"trace[343538057] linearizableReadLoop","detail":"{readStateIndex:8771; appliedIndex:8770; }","duration":"146.858405ms","start":"2026-04-20T08:27:18.766014Z","end":"2026-04-20T08:27:18.912872Z","steps":["trace[343538057] 'read index received' (duration: 146.663161ms)","trace[343538057] 'applied index is now lower than readState.Index' (duration: 193.944µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:27:18.9131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.05592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:27:18.913144Z","caller":"traceutil/trace.go:171","msg":"trace[2016477830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8467; }","duration":"147.125902ms","start":"2026-04-20T08:27:18.766006Z","end":"2026-04-20T08:27:18.913132Z","steps":["trace[2016477830] 'agreement among raft nodes before linearized reading' (duration: 147.004969ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:27:18.913536Z","caller":"traceutil/trace.go:171","msg":"trace[1771165313] transaction","detail":"{read_only:false; response_revision:8467; number_of_response:1; }","duration":"164.480743ms","start":"2026-04-20T08:27:18.749042Z","end":"2026-04-20T08:27:18.913523Z","steps":["trace[1771165313] 'process raft request' (duration: 163.592004ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:27:18.928269Z","caller":"traceutil/trace.go:171","msg":"trace[36805754] transaction","detail":"{read_only:false; response_revision:8468; number_of_response:1; }","duration":"134.913719ms","start":"2026-04-20T08:27:18.793342Z","end":"2026-04-20T08:27:18.928256Z","steps":["trace[36805754] 'process raft request' (duration: 134.851658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:27:31.12307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.762679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:27:31.123336Z","caller":"traceutil/trace.go:171","msg":"trace[1374861733] transaction","detail":"{read_only:false; response_revision:8571; number_of_response:1; }","duration":"281.357926ms","start":"2026-04-20T08:27:30.841939Z","end":"2026-04-20T08:27:31.123297Z","steps":["trace[1374861733] 'process raft request' (duration: 39.24595ms)","trace[1374861733] 'compare' (duration: 241.218307ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:27:31.123963Z","caller":"traceutil/trace.go:171","msg":"trace[1616260182] transaction","detail":"{read_only:false; response_revision:8572; number_of_response:1; }","duration":"165.77843ms","start":"2026-04-20T08:27:30.95817Z","end":"2026-04-20T08:27:31.123949Z","steps":["trace[1616260182] 'process raft request' (duration: 165.064555ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:27:31.730151Z","caller":"traceutil/trace.go:171","msg":"trace[1593531604] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"119.027829ms","start":"2026-04-20T08:27:31.611091Z","end":"2026-04-20T08:27:31.730119Z","steps":["trace[1593531604] 'process raft request' (duration: 102.535316ms)","trace[1593531604] 'compare' (duration: 16.198337ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:27:31.974687Z","caller":"traceutil/trace.go:171","msg":"trace[1001462633] linearizableReadLoop","detail":"{readStateIndex:8891; appliedIndex:8890; }","duration":"197.391298ms","start":"2026-04-20T08:27:31.77727Z","end":"2026-04-20T08:27:31.974661Z","steps":["trace[1001462633] 'read index received' (duration: 173.120038ms)","trace[1001462633] 'applied index is now lower than readState.Index' (duration: 24.27018ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:27:31.974807Z","caller":"traceutil/trace.go:171","msg":"trace[1703934852] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"209.096578ms","start":"2026-04-20T08:27:31.765653Z","end":"2026-04-20T08:27:31.97475Z","steps":["trace[1703934852] 'process raft request' (duration: 184.700385ms)","trace[1703934852] 'compare' (duration: 24.195559ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:27:31.975072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.770906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vlgh5\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-04-20T08:27:31.97512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.724911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-04-20T08:27:31.975168Z","caller":"traceutil/trace.go:171","msg":"trace[353932229] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vlgh5; range_end:; response_count:1; response_revision:8581; }","duration":"197.928329ms","start":"2026-04-20T08:27:31.777219Z","end":"2026-04-20T08:27:31.975147Z","steps":["trace[353932229] 'agreement among raft nodes before linearized reading' (duration: 197.603462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:27:31.97516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.86527ms","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-04-20T08:27:31.975262Z","caller":"traceutil/trace.go:171","msg":"trace[2100123656] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8581; }","duration":"192.016123ms","start":"2026-04-20T08:27:31.783217Z","end":"2026-04-20T08:27:31.975233Z","steps":["trace[2100123656] 'agreement among raft nodes before linearized reading' (duration: 191.657056ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:27:31.975196Z","caller":"traceutil/trace.go:171","msg":"trace[1845931710] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8581; }","duration":"194.822732ms","start":"2026-04-20T08:27:31.780332Z","end":"2026-04-20T08:27:31.975155Z","steps":["trace[1845931710] 'agreement among raft nodes before linearized reading' (duration: 194.537526ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:27:32.159486Z","caller":"traceutil/trace.go:171","msg":"trace[906874897] transaction","detail":"{read_only:false; response_revision:8584; number_of_response:1; }","duration":"108.063425ms","start":"2026-04-20T08:27:32.051384Z","end":"2026-04-20T08:27:32.159448Z","steps":["trace[906874897] 'process raft request' (duration: 94.710009ms)","trace[906874897] 'compare' (duration: 13.189031ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:27:32.655005Z","caller":"traceutil/trace.go:171","msg":"trace[355201784] transaction","detail":"{read_only:false; response_revision:8588; number_of_response:1; }","duration":"141.494371ms","start":"2026-04-20T08:27:32.513479Z","end":"2026-04-20T08:27:32.654974Z","steps":["trace[355201784] 'process raft request' (duration: 141.279966ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:28:19.500208Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000249c.wal"} {"level":"info","ts":"2026-04-20T08:29:30.66053Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1918dd9317ae83a2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T08:29:30.666048Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T08:29:30.666143Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T08:31:05.012439Z","caller":"traceutil/trace.go:171","msg":"trace[1373039577] linearizableReadLoop","detail":"{readStateIndex:10742; appliedIndex:10741; }","duration":"244.450189ms","start":"2026-04-20T08:31:04.767968Z","end":"2026-04-20T08:31:05.012418Z","steps":["trace[1373039577] 'read index received' (duration: 237.9219ms)","trace[1373039577] 'applied index is now lower than readState.Index' (duration: 6.527119ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:31:05.012784Z","caller":"traceutil/trace.go:171","msg":"trace[1771386669] transaction","detail":"{read_only:false; response_revision:10377; number_of_response:1; }","duration":"253.568405ms","start":"2026-04-20T08:31:04.7592Z","end":"2026-04-20T08:31:05.012769Z","steps":["trace[1771386669] 'process raft request' (duration: 246.676738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:31:05.012985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.994891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:31:05.013049Z","caller":"traceutil/trace.go:171","msg":"trace[59986358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10377; }","duration":"245.104113ms","start":"2026-04-20T08:31:04.767931Z","end":"2026-04-20T08:31:05.013035Z","steps":["trace[59986358] 'agreement among raft nodes before linearized reading' (duration: 244.995741ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:31:05.049526Z","caller":"traceutil/trace.go:171","msg":"trace[1149858278] transaction","detail":"{read_only:false; response_revision:10378; number_of_response:1; }","duration":"256.203542ms","start":"2026-04-20T08:31:04.79329Z","end":"2026-04-20T08:31:05.049493Z","steps":["trace[1149858278] 'process raft request' (duration: 256.036088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:31:05.049665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.977974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:31:05.049783Z","caller":"traceutil/trace.go:171","msg":"trace[1359972756] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:10378; }","duration":"247.164039ms","start":"2026-04-20T08:31:04.802594Z","end":"2026-04-20T08:31:05.049758Z","steps":["trace[1359972756] 'agreement among raft nodes before linearized reading' (duration: 246.919434ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:31:05.202041Z","caller":"traceutil/trace.go:171","msg":"trace[46022516] linearizableReadLoop","detail":"{readStateIndex:10744; appliedIndex:10743; }","duration":"128.921914ms","start":"2026-04-20T08:31:05.073094Z","end":"2026-04-20T08:31:05.202015Z","steps":["trace[46022516] 'read index received' (duration: 68.217052ms)","trace[46022516] 'applied index is now lower than readState.Index' (duration: 60.703582ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:31:05.202267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.161909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-20T08:31:05.202324Z","caller":"traceutil/trace.go:171","msg":"trace[132679088] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:10380; }","duration":"129.260531ms","start":"2026-04-20T08:31:05.073046Z","end":"2026-04-20T08:31:05.202307Z","steps":["trace[132679088] 'agreement among raft nodes before linearized reading' (duration: 129.072237ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:31:05.202442Z","caller":"traceutil/trace.go:171","msg":"trace[155336629] transaction","detail":"{read_only:false; response_revision:10379; number_of_response:1; }","duration":"148.84592ms","start":"2026-04-20T08:31:05.05357Z","end":"2026-04-20T08:31:05.202416Z","steps":["trace[155336629] 'process raft request' (duration: 87.810412ms)","trace[155336629] 'compare' (duration: 60.409315ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:31:05.318697Z","caller":"traceutil/trace.go:171","msg":"trace[697202863] transaction","detail":"{read_only:false; response_revision:10382; number_of_response:1; }","duration":"112.736367ms","start":"2026-04-20T08:31:05.205927Z","end":"2026-04-20T08:31:05.318663Z","steps":["trace[697202863] 'process raft request' (duration: 102.201381ms)","trace[697202863] 'compare' (duration: 10.354132ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:31:49.880551Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8259} {"level":"info","ts":"2026-04-20T08:31:49.961841Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8259,"took":"79.123946ms","hash":3649105580,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":24891392,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-20T08:31:49.961916Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3649105580,"revision":8259,"compact-revision":6066} {"level":"info","ts":"2026-04-20T08:36:49.887757Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10659} {"level":"info","ts":"2026-04-20T08:36:49.964408Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10659,"took":"74.752629ms","hash":3353491865,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":20418560,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-20T08:36:49.964507Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3353491865,"revision":10659,"compact-revision":8259}