{"level":"warn","ts":"2026-04-20T10:18:36.018402Z","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-20T10:18:36.018505Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-20T10:18:36.018584Z","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-20T10:18:36.018624Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-04-20T10:18:36.018645Z","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-20T10:18:36.019356Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-04-20T10:18:36.019443Z","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.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-20T10:18:36.023075Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.428054ms"} {"level":"info","ts":"2026-04-20T10:18:36.028848Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-04-20T10:18:36.030544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T10:18:36.030564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-04-20T10:18:36.030585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T10:18:36.030594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-04-20T10:18:36.030633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-04-20T10:18:36.040701Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T10:18:36.042288Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T10:18:36.043823Z","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-20T10:18:36.046149Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T10:18:36.046513Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T10:18:36.046701Z","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-20T10:18:36.046801Z","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-20T10:18:36.046832Z","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-20T10:18:36.048392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-04-20T10:18:36.048847Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-04-20T10:18:36.050705Z","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-20T10:18:36.050834Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-04-20T10:18:36.050863Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-04-20T10:18:36.054049Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T10:18:36.05412Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T10:18:36.430845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T10:18:36.43098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T10:18:36.431057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-04-20T10:18:36.431108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-04-20T10:18:36.43116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-04-20T10:18:36.43121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-04-20T10:18:36.431275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-04-20T10:18:36.435264Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:18:36.43735Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T10:18:36.437404Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:18:36.438031Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:18:36.438341Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:18:36.438062Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:18:36.438749Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:18:36.44109Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T10:18:36.441688Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T10:18:36.441836Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T10:18:36.442996Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"warn","ts":"2026-04-20T10:18:52.907628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.02076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4379"} {"level":"info","ts":"2026-04-20T10:18:52.907799Z","caller":"traceutil/trace.go:171","msg":"trace[1741271356] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:374; }","duration":"109.275936ms","start":"2026-04-20T10:18:52.798497Z","end":"2026-04-20T10:18:52.907773Z","steps":["trace[1741271356] 'range keys from in-memory index tree' (duration: 108.798476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:18:52.908478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.908442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-04-20T10:18:52.908611Z","caller":"traceutil/trace.go:171","msg":"trace[1643639466] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:374; }","duration":"207.588995ms","start":"2026-04-20T10:18:52.700994Z","end":"2026-04-20T10:18:52.908583Z","steps":["trace[1643639466] 'range keys from in-memory index tree' (duration: 206.699657ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:03.960134Z","caller":"traceutil/trace.go:171","msg":"trace[482657595] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"252.785ms","start":"2026-04-20T10:19:03.707312Z","end":"2026-04-20T10:19:03.960097Z","steps":["trace[482657595] 'process raft request' (duration: 252.613276ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:09.157126Z","caller":"traceutil/trace.go:171","msg":"trace[580266538] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"108.528974ms","start":"2026-04-20T10:19:09.048566Z","end":"2026-04-20T10:19:09.157095Z","steps":["trace[580266538] 'process raft request' (duration: 108.348471ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:09.235595Z","caller":"traceutil/trace.go:171","msg":"trace[1325655936] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"154.100047ms","start":"2026-04-20T10:19:09.081448Z","end":"2026-04-20T10:19:09.235548Z","steps":["trace[1325655936] 'process raft request' (duration: 153.431543ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:09.790822Z","caller":"traceutil/trace.go:171","msg":"trace[875479782] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"156.213738ms","start":"2026-04-20T10:19:09.634539Z","end":"2026-04-20T10:19:09.790752Z","steps":["trace[875479782] 'process raft request' (duration: 151.764711ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:09.791242Z","caller":"traceutil/trace.go:171","msg":"trace[625009567] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"136.499754ms","start":"2026-04-20T10:19:09.65472Z","end":"2026-04-20T10:19:09.791219Z","steps":["trace[625009567] 'process raft request' (duration: 136.370291ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:17.669455Z","caller":"traceutil/trace.go:171","msg":"trace[1965809073] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"282.967758ms","start":"2026-04-20T10:19:17.386452Z","end":"2026-04-20T10:19:17.669419Z","steps":["trace[1965809073] 'process raft request' (duration: 240.877385ms)","trace[1965809073] 'compare' (duration: 41.93205ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:19:18.086995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.013843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:18.087094Z","caller":"traceutil/trace.go:171","msg":"trace[591502100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"161.158766ms","start":"2026-04-20T10:19:17.925911Z","end":"2026-04-20T10:19:18.08707Z","steps":["trace[591502100] 'range keys from in-memory index tree' (duration: 160.922331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:18.836995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:18.208318Z","time spent":"628.668764ms","remote":"127.0.0.1:35710","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-20T10:19:18.838462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.609692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:19:18.838744Z","caller":"traceutil/trace.go:171","msg":"trace[1107252151] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"678.987058ms","start":"2026-04-20T10:19:18.159713Z","end":"2026-04-20T10:19:18.8387Z","steps":["trace[1107252151] 'process raft request' (duration: 44.334953ms)","trace[1107252151] 'compare' (duration: 632.921416ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:19:18.83892Z","caller":"traceutil/trace.go:171","msg":"trace[816817449] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"332.389264ms","start":"2026-04-20T10:19:18.50651Z","end":"2026-04-20T10:19:18.838899Z","steps":["trace[816817449] 'read index received' (duration: 331.26422ms)","trace[816817449] 'applied index is now lower than readState.Index' (duration: 1.123764ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:19:18.838993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:18.159682Z","time spent":"679.159282ms","remote":"127.0.0.1:59706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:19:18.839107Z","caller":"traceutil/trace.go:171","msg":"trace[880163215] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"620.751319ms","start":"2026-04-20T10:19:18.218305Z","end":"2026-04-20T10:19:18.839057Z","steps":["trace[880163215] 'process raft request' (duration: 620.392502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:18.839247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:18.218285Z","time spent":"620.887223ms","remote":"127.0.0.1:59674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:19:18.839323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.794202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-20T10:19:18.839354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.268667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-20T10:19:18.839375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.43731ms","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-20T10:19:18.839422Z","caller":"traceutil/trace.go:171","msg":"trace[1300461184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:646; }","duration":"333.497978ms","start":"2026-04-20T10:19:18.505904Z","end":"2026-04-20T10:19:18.839401Z","steps":["trace[1300461184] 'agreement among raft nodes before linearized reading' (duration: 333.165201ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:18.839456Z","caller":"traceutil/trace.go:171","msg":"trace[1699089782] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:646; }","duration":"140.415581ms","start":"2026-04-20T10:19:18.699027Z","end":"2026-04-20T10:19:18.839443Z","steps":["trace[1699089782] 'agreement among raft nodes before linearized reading' (duration: 140.054123ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:18.839462Z","caller":"traceutil/trace.go:171","msg":"trace[2122461187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:646; }","duration":"124.545483ms","start":"2026-04-20T10:19:18.714891Z","end":"2026-04-20T10:19:18.839437Z","steps":["trace[2122461187] 'agreement among raft nodes before linearized reading' (duration: 124.313258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:18.839479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:18.505874Z","time spent":"333.593599ms","remote":"127.0.0.1:59706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T10:20:49.446999Z","caller":"traceutil/trace.go:171","msg":"trace[944788770] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"130.535494ms","start":"2026-04-20T10:20:49.316418Z","end":"2026-04-20T10:20:49.446953Z","steps":["trace[944788770] 'process raft request' (duration: 130.163146ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:49.709132Z","caller":"traceutil/trace.go:171","msg":"trace[924843653] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"256.969249ms","start":"2026-04-20T10:20:49.452133Z","end":"2026-04-20T10:20:49.709103Z","steps":["trace[924843653] 'process raft request' (duration: 256.752175ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:49.70948Z","caller":"traceutil/trace.go:171","msg":"trace[1839586891] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"256.406047ms","start":"2026-04-20T10:20:49.453005Z","end":"2026-04-20T10:20:49.709411Z","steps":["trace[1839586891] 'process raft request' (duration: 256.045729ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:49.709624Z","caller":"traceutil/trace.go:171","msg":"trace[16143809] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"256.420077ms","start":"2026-04-20T10:20:49.453171Z","end":"2026-04-20T10:20:49.709592Z","steps":["trace[16143809] 'process raft request' (duration: 256.206922ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:49.709664Z","caller":"traceutil/trace.go:171","msg":"trace[54886991] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"255.969137ms","start":"2026-04-20T10:20:49.453673Z","end":"2026-04-20T10:20:49.709642Z","steps":["trace[54886991] 'process raft request' (duration: 255.821344ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:33.707081Z","caller":"traceutil/trace.go:171","msg":"trace[1058164403] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"116.529307ms","start":"2026-04-20T10:21:33.590514Z","end":"2026-04-20T10:21:33.707043Z","steps":["trace[1058164403] 'process raft request' (duration: 116.23912ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:39.09988Z","caller":"traceutil/trace.go:171","msg":"trace[2115520243] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"115.644417ms","start":"2026-04-20T10:21:38.984202Z","end":"2026-04-20T10:21:39.099847Z","steps":["trace[2115520243] 'process raft request' (duration: 115.459653ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:39.720123Z","caller":"traceutil/trace.go:171","msg":"trace[2083390677] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"233.031722ms","start":"2026-04-20T10:21:39.487057Z","end":"2026-04-20T10:21:39.720089Z","steps":["trace[2083390677] 'process raft request' (duration: 232.835477ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:39.722417Z","caller":"traceutil/trace.go:171","msg":"trace[1569780006] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"185.94984ms","start":"2026-04-20T10:21:39.536432Z","end":"2026-04-20T10:21:39.722382Z","steps":["trace[1569780006] 'process raft request' (duration: 185.791847ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:23:02.350376Z","caller":"traceutil/trace.go:171","msg":"trace[708212381] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"164.144269ms","start":"2026-04-20T10:23:02.186205Z","end":"2026-04-20T10:23:02.350349Z","steps":["trace[708212381] 'process raft request' (duration: 163.931115ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:23:02.350617Z","caller":"traceutil/trace.go:171","msg":"trace[369078705] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"164.367864ms","start":"2026-04-20T10:23:02.186204Z","end":"2026-04-20T10:23:02.350572Z","steps":["trace[369078705] 'process raft request' (duration: 164.095438ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:23:02.837276Z","caller":"traceutil/trace.go:171","msg":"trace[1776697756] linearizableReadLoop","detail":"{readStateIndex:2897; appliedIndex:2894; }","duration":"125.947622ms","start":"2026-04-20T10:23:02.711301Z","end":"2026-04-20T10:23:02.837249Z","steps":["trace[1776697756] 'read index received' (duration: 35.390245ms)","trace[1776697756] 'applied index is now lower than readState.Index' (duration: 90.556177ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:23:02.837301Z","caller":"traceutil/trace.go:171","msg":"trace[332421789] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"303.253164ms","start":"2026-04-20T10:23:02.53401Z","end":"2026-04-20T10:23:02.837263Z","steps":["trace[332421789] 'process raft request' (duration: 212.733907ms)","trace[332421789] 'compare' (duration: 90.117398ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:23:02.837459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:23:02.533983Z","time spent":"303.389356ms","remote":"127.0.0.1:59706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:23:02.837507Z","caller":"traceutil/trace.go:171","msg":"trace[1484901749] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"303.358086ms","start":"2026-04-20T10:23:02.5341Z","end":"2026-04-20T10:23:02.837459Z","steps":["trace[1484901749] 'process raft request' (duration: 303.036719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:23:02.837578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.27035ms","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-20T10:23:02.837621Z","caller":"traceutil/trace.go:171","msg":"trace[224945316] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2791; }","duration":"126.316801ms","start":"2026-04-20T10:23:02.71129Z","end":"2026-04-20T10:23:02.837607Z","steps":["trace[224945316] 'agreement among raft nodes before linearized reading' (duration: 126.063735ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:23:02.837726Z","caller":"traceutil/trace.go:171","msg":"trace[752319078] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"303.460949ms","start":"2026-04-20T10:23:02.534187Z","end":"2026-04-20T10:23:02.837648Z","steps":["trace[752319078] 'process raft request' (duration: 303.015938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:23:02.837765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:23:02.534092Z","time spent":"303.52785ms","remote":"127.0.0.1:59706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:23:02.837838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:23:02.534157Z","time spent":"303.624073ms","remote":"127.0.0.1:59706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:25:49.415378Z","caller":"traceutil/trace.go:171","msg":"trace[1057806614] transaction","detail":"{read_only:false; response_revision:3862; number_of_response:1; }","duration":"208.771061ms","start":"2026-04-20T10:25:49.206575Z","end":"2026-04-20T10:25:49.415346Z","steps":["trace[1057806614] 'process raft request' (duration: 208.520395ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:25:49.682168Z","caller":"traceutil/trace.go:171","msg":"trace[1401601551] linearizableReadLoop","detail":"{readStateIndex:4009; appliedIndex:4008; }","duration":"230.619902ms","start":"2026-04-20T10:25:49.451499Z","end":"2026-04-20T10:25:49.682119Z","steps":["trace[1401601551] 'read index received' (duration: 228.654449ms)","trace[1401601551] 'applied index is now lower than readState.Index' (duration: 1.963573ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:25:49.682216Z","caller":"traceutil/trace.go:171","msg":"trace[1420512789] transaction","detail":"{read_only:false; response_revision:3863; number_of_response:1; }","duration":"261.823396ms","start":"2026-04-20T10:25:49.420357Z","end":"2026-04-20T10:25:49.682181Z","steps":["trace[1420512789] 'process raft request' (duration: 259.782061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:49.68258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.062231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jcgfd\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-20T10:25:49.682651Z","caller":"traceutil/trace.go:171","msg":"trace[1089979998] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jcgfd; range_end:; response_count:1; response_revision:3863; }","duration":"231.176424ms","start":"2026-04-20T10:25:49.451454Z","end":"2026-04-20T10:25:49.682631Z","steps":["trace[1089979998] 'agreement among raft nodes before linearized reading' (duration: 230.859987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:49.683082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.528532ms","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-20T10:25:49.683126Z","caller":"traceutil/trace.go:171","msg":"trace[2057675944] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3864; }","duration":"195.618604ms","start":"2026-04-20T10:25:49.487498Z","end":"2026-04-20T10:25:49.683117Z","steps":["trace[2057675944] 'agreement among raft nodes before linearized reading' (duration: 195.486081ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:25:49.683292Z","caller":"traceutil/trace.go:171","msg":"trace[1466164220] transaction","detail":"{read_only:false; response_revision:3864; number_of_response:1; }","duration":"126.194949ms","start":"2026-04-20T10:25:49.557089Z","end":"2026-04-20T10:25:49.683284Z","steps":["trace[1466164220] 'process raft request' (duration: 125.807421ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:27:11.746638Z","caller":"traceutil/trace.go:171","msg":"trace[689924122] linearizableReadLoop","detail":"{readStateIndex:5000; appliedIndex:4999; }","duration":"143.549154ms","start":"2026-04-20T10:27:11.60306Z","end":"2026-04-20T10:27:11.746609Z","steps":["trace[689924122] 'read index received' (duration: 142.691195ms)","trace[689924122] 'applied index is now lower than readState.Index' (duration: 857.299µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:27:11.746733Z","caller":"traceutil/trace.go:171","msg":"trace[416973988] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"148.298121ms","start":"2026-04-20T10:27:11.59839Z","end":"2026-04-20T10:27:11.746688Z","steps":["trace[416973988] 'process raft request' (duration: 147.32598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:27:11.74696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.866911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-04-20T10:27:11.747004Z","caller":"traceutil/trace.go:171","msg":"trace[1414698639] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4817; }","duration":"143.984344ms","start":"2026-04-20T10:27:11.603011Z","end":"2026-04-20T10:27:11.746995Z","steps":["trace[1414698639] 'agreement among raft nodes before linearized reading' (duration: 143.761109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:27:11.752864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.4374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4737"} {"level":"info","ts":"2026-04-20T10:27:11.752955Z","caller":"traceutil/trace.go:171","msg":"trace[626561016] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4819; }","duration":"142.594573ms","start":"2026-04-20T10:27:11.61034Z","end":"2026-04-20T10:27:11.752935Z","steps":["trace[626561016] 'agreement among raft nodes before linearized reading' (duration: 142.341828ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:27:11.753397Z","caller":"traceutil/trace.go:171","msg":"trace[1462001236] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"143.005043ms","start":"2026-04-20T10:27:11.61037Z","end":"2026-04-20T10:27:11.753375Z","steps":["trace[1462001236] 'process raft request' (duration: 142.110272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:27:11.754698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.715839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:22 size:517416"} {"level":"info","ts":"2026-04-20T10:27:11.754723Z","caller":"traceutil/trace.go:171","msg":"trace[966005382] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:22; response_revision:4819; }","duration":"114.774691ms","start":"2026-04-20T10:27:11.639942Z","end":"2026-04-20T10:27:11.754717Z","steps":["trace[966005382] 'agreement among raft nodes before linearized reading' (duration: 113.694187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:27:14.551759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.772867ms","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":"info","ts":"2026-04-20T10:27:14.551904Z","caller":"traceutil/trace.go:171","msg":"trace[1357117507] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:4864; }","duration":"139.071613ms","start":"2026-04-20T10:27:14.412804Z","end":"2026-04-20T10:27:14.551876Z","steps":["trace[1357117507] 'count revisions from in-memory index tree' (duration: 138.45656ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:27:18.067253Z","caller":"traceutil/trace.go:171","msg":"trace[930891432] linearizableReadLoop","detail":"{readStateIndex:5196; appliedIndex:5195; }","duration":"118.939283ms","start":"2026-04-20T10:27:17.948266Z","end":"2026-04-20T10:27:18.067205Z","steps":["trace[930891432] 'read index received' (duration: 118.652277ms)","trace[930891432] 'applied index is now lower than readState.Index' (duration: 285.236µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:27:18.067311Z","caller":"traceutil/trace.go:171","msg":"trace[537472467] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"139.721579ms","start":"2026-04-20T10:27:17.927537Z","end":"2026-04-20T10:27:18.067259Z","steps":["trace[537472467] 'process raft request' (duration: 139.417662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:27:18.067587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.285051ms","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-20T10:27:18.067709Z","caller":"traceutil/trace.go:171","msg":"trace[1038146370] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5010; }","duration":"119.489006ms","start":"2026-04-20T10:27:17.948194Z","end":"2026-04-20T10:27:18.067683Z","steps":["trace[1038146370] 'agreement among raft nodes before linearized reading' (duration: 119.147977ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:27:18.104597Z","caller":"traceutil/trace.go:171","msg":"trace[1960079502] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"103.62424ms","start":"2026-04-20T10:27:18.000942Z","end":"2026-04-20T10:27:18.104567Z","steps":["trace[1960079502] 'process raft request' (duration: 103.335174ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:27:18.582519Z","caller":"traceutil/trace.go:171","msg":"trace[1766130927] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"173.127217ms","start":"2026-04-20T10:27:18.409365Z","end":"2026-04-20T10:27:18.582493Z","steps":["trace[1766130927] 'process raft request' (duration: 172.973964ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:27:20.800523Z","caller":"traceutil/trace.go:171","msg":"trace[873565202] transaction","detail":"{read_only:false; response_revision:5058; number_of_response:1; }","duration":"104.813636ms","start":"2026-04-20T10:27:20.695647Z","end":"2026-04-20T10:27:20.800461Z","steps":["trace[873565202] 'process raft request' (duration: 104.142511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:27:30.685617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.368963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:27:30.686001Z","caller":"traceutil/trace.go:171","msg":"trace[1244449557] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"299.040907ms","start":"2026-04-20T10:27:30.386942Z","end":"2026-04-20T10:27:30.685982Z","steps":["trace[1244449557] 'process raft request' (duration: 16.230524ms)","trace[1244449557] 'compare' (duration: 282.108718ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:27:30.686011Z","caller":"traceutil/trace.go:171","msg":"trace[348353477] linearizableReadLoop","detail":"{readStateIndex:5490; appliedIndex:5488; }","duration":"182.137008ms","start":"2026-04-20T10:27:30.503859Z","end":"2026-04-20T10:27:30.685996Z","steps":["trace[348353477] 'read index received' (duration: 140.23518ms)","trace[348353477] 'applied index is now lower than readState.Index' (duration: 41.900508ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:27:30.686077Z","caller":"traceutil/trace.go:171","msg":"trace[271432443] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"298.448163ms","start":"2026-04-20T10:27:30.387585Z","end":"2026-04-20T10:27:30.686033Z","steps":["trace[271432443] 'process raft request' (duration: 298.143776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:27:30.686191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.321073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-24qg4\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-04-20T10:27:30.686228Z","caller":"traceutil/trace.go:171","msg":"trace[648733923] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-24qg4; range_end:; response_count:1; response_revision:5293; }","duration":"182.394394ms","start":"2026-04-20T10:27:30.50382Z","end":"2026-04-20T10:27:30.686214Z","steps":["trace[648733923] 'agreement among raft nodes before linearized reading' (duration: 182.229361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:27:39.908497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.437806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:27:39.908633Z","caller":"traceutil/trace.go:171","msg":"trace[1193763724] linearizableReadLoop","detail":"{readStateIndex:5664; appliedIndex:5663; }","duration":"112.762925ms","start":"2026-04-20T10:27:39.795846Z","end":"2026-04-20T10:27:39.908609Z","steps":["trace[1193763724] 'read index received' (duration: 44.801µs)","trace[1193763724] 'applied index is now lower than readState.Index' (duration: 112.716113ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:27:39.908706Z","caller":"traceutil/trace.go:171","msg":"trace[758413873] transaction","detail":"{read_only:false; response_revision:5466; number_of_response:1; }","duration":"192.397679ms","start":"2026-04-20T10:27:39.716271Z","end":"2026-04-20T10:27:39.908668Z","steps":["trace[758413873] 'process raft request' (duration: 55.654577ms)","trace[758413873] 'compare' (duration: 136.19548ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:27:39.908821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.967549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T10:27:39.908879Z","caller":"traceutil/trace.go:171","msg":"trace[470046652] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:5466; }","duration":"113.067771ms","start":"2026-04-20T10:27:39.795796Z","end":"2026-04-20T10:27:39.908864Z","steps":["trace[470046652] 'agreement among raft nodes before linearized reading' (duration: 112.877537ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:27:40.083348Z","caller":"traceutil/trace.go:171","msg":"trace[168257112] transaction","detail":"{read_only:false; response_revision:5467; number_of_response:1; }","duration":"169.342062ms","start":"2026-04-20T10:27:39.91398Z","end":"2026-04-20T10:27:40.083322Z","steps":["trace[168257112] 'process raft request' (duration: 149.61491ms)","trace[168257112] 'compare' (duration: 19.486196ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:27:40.083782Z","caller":"traceutil/trace.go:171","msg":"trace[1466135447] linearizableReadLoop","detail":"{readStateIndex:5668; appliedIndex:5665; }","duration":"130.965223ms","start":"2026-04-20T10:27:39.95279Z","end":"2026-04-20T10:27:40.083755Z","steps":["trace[1466135447] 'read index received' (duration: 110.821731ms)","trace[1466135447] 'applied index is now lower than readState.Index' (duration: 20.141852ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:27:40.083869Z","caller":"traceutil/trace.go:171","msg":"trace[735713237] transaction","detail":"{read_only:false; response_revision:5468; number_of_response:1; }","duration":"168.693978ms","start":"2026-04-20T10:27:39.915144Z","end":"2026-04-20T10:27:40.083838Z","steps":["trace[735713237] 'process raft request' (duration: 168.111124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:27:40.083921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.250649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:27:40.083964Z","caller":"traceutil/trace.go:171","msg":"trace[274370025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5469; }","duration":"160.333381ms","start":"2026-04-20T10:27:39.923612Z","end":"2026-04-20T10:27:40.083945Z","steps":["trace[274370025] 'agreement among raft nodes before linearized reading' (duration: 160.244369ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:27:40.084066Z","caller":"traceutil/trace.go:171","msg":"trace[1475819411] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"165.565228ms","start":"2026-04-20T10:27:39.918486Z","end":"2026-04-20T10:27:40.084051Z","steps":["trace[1475819411] 'process raft request' (duration: 165.104217ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:27:40.086913Z","caller":"traceutil/trace.go:171","msg":"trace[659510243] transaction","detail":"{read_only:false; response_revision:5470; number_of_response:1; }","duration":"132.994598ms","start":"2026-04-20T10:27:39.953897Z","end":"2026-04-20T10:27:40.086891Z","steps":["trace[659510243] 'process raft request' (duration: 132.778574ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:27:40.086968Z","caller":"traceutil/trace.go:171","msg":"trace[1605437473] transaction","detail":"{read_only:false; response_revision:5471; number_of_response:1; }","duration":"125.782887ms","start":"2026-04-20T10:27:39.961139Z","end":"2026-04-20T10:27:40.086922Z","steps":["trace[1605437473] 'process raft request' (duration: 125.639704ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:28:36.470338Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2972} {"level":"info","ts":"2026-04-20T10:28:36.546593Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2972,"took":"74.4588ms","hash":2455582519,"current-db-size-bytes":56610816,"current-db-size":"57 MB","current-db-size-in-use-bytes":33357824,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-20T10:28:36.546685Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2455582519,"revision":2972,"compact-revision":-1} {"level":"info","ts":"2026-04-20T10:33:36.477521Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6020} {"level":"info","ts":"2026-04-20T10:33:36.582698Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6020,"took":"102.372186ms","hash":955552429,"current-db-size-bytes":56610816,"current-db-size":"57 MB","current-db-size-in-use-bytes":21762048,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-20T10:33:36.582791Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":955552429,"revision":6020,"compact-revision":2972} {"level":"info","ts":"2026-04-20T10:33:50.536705Z","caller":"traceutil/trace.go:171","msg":"trace[2112453521] linearizableReadLoop","detail":"{readStateIndex:8621; appliedIndex:8620; }","duration":"159.813097ms","start":"2026-04-20T10:33:50.37686Z","end":"2026-04-20T10:33:50.536673Z","steps":["trace[2112453521] 'read index received' (duration: 159.614262ms)","trace[2112453521] 'applied index is now lower than readState.Index' (duration: 197.415µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:33:50.536811Z","caller":"traceutil/trace.go:171","msg":"trace[433174015] transaction","detail":"{read_only:false; response_revision:8327; number_of_response:1; }","duration":"179.454022ms","start":"2026-04-20T10:33:50.357295Z","end":"2026-04-20T10:33:50.536749Z","steps":["trace[433174015] 'process raft request' (duration: 179.177395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:33:50.537061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.182716ms","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-20T10:33:50.537175Z","caller":"traceutil/trace.go:171","msg":"trace[341914] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8327; }","duration":"160.369541ms","start":"2026-04-20T10:33:50.376785Z","end":"2026-04-20T10:33:50.537154Z","steps":["trace[341914] 'agreement among raft nodes before linearized reading' (duration: 160.083503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:01.031724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.433164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:34:01.031825Z","caller":"traceutil/trace.go:171","msg":"trace[792461638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8388; }","duration":"104.617899ms","start":"2026-04-20T10:34:00.927183Z","end":"2026-04-20T10:34:01.031801Z","steps":["trace[792461638] 'range keys from in-memory index tree' (duration: 104.305042ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:02.878185Z","caller":"traceutil/trace.go:171","msg":"trace[243498039] linearizableReadLoop","detail":"{readStateIndex:8686; appliedIndex:8685; }","duration":"239.330397ms","start":"2026-04-20T10:34:02.638823Z","end":"2026-04-20T10:34:02.878154Z","steps":["trace[243498039] 'read index received' (duration: 239.142503ms)","trace[243498039] 'applied index is now lower than readState.Index' (duration: 186.844µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:34:02.878313Z","caller":"traceutil/trace.go:171","msg":"trace[1629016321] transaction","detail":"{read_only:false; response_revision:8390; number_of_response:1; }","duration":"271.304311ms","start":"2026-04-20T10:34:02.606975Z","end":"2026-04-20T10:34:02.87828Z","steps":["trace[1629016321] 'process raft request' (duration: 270.984824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:02.878462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.614924ms","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-20T10:34:02.878516Z","caller":"traceutil/trace.go:171","msg":"trace[1033555769] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8390; }","duration":"239.741337ms","start":"2026-04-20T10:34:02.638761Z","end":"2026-04-20T10:34:02.878502Z","steps":["trace[1033555769] 'agreement among raft nodes before linearized reading' (duration: 239.538762ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:02.899304Z","caller":"traceutil/trace.go:171","msg":"trace[1353596417] transaction","detail":"{read_only:false; response_revision:8391; number_of_response:1; }","duration":"159.457069ms","start":"2026-04-20T10:34:02.739803Z","end":"2026-04-20T10:34:02.89926Z","steps":["trace[1353596417] 'process raft request' (duration: 159.156602ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:02.899312Z","caller":"traceutil/trace.go:171","msg":"trace[202438891] transaction","detail":"{read_only:false; response_revision:8392; number_of_response:1; }","duration":"103.422711ms","start":"2026-04-20T10:34:02.795855Z","end":"2026-04-20T10:34:02.899278Z","steps":["trace[202438891] 'process raft request' (duration: 103.326088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:02.899614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.841381ms","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-20T10:34:02.899692Z","caller":"traceutil/trace.go:171","msg":"trace[829189223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8393; }","duration":"255.977464ms","start":"2026-04-20T10:34:02.643695Z","end":"2026-04-20T10:34:02.899672Z","steps":["trace[829189223] 'agreement among raft nodes before linearized reading' (duration: 255.761369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:02.899732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.103083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T10:34:02.899764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.50598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jcgfd\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-20T10:34:02.899797Z","caller":"traceutil/trace.go:171","msg":"trace[330538373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8393; }","duration":"251.206646ms","start":"2026-04-20T10:34:02.64857Z","end":"2026-04-20T10:34:02.899777Z","steps":["trace[330538373] 'agreement among raft nodes before linearized reading' (duration: 251.070013ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:02.899815Z","caller":"traceutil/trace.go:171","msg":"trace[940184253] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jcgfd; range_end:; response_count:1; response_revision:8393; }","duration":"201.638743ms","start":"2026-04-20T10:34:02.698164Z","end":"2026-04-20T10:34:02.899803Z","steps":["trace[940184253] 'agreement among raft nodes before linearized reading' (duration: 201.431439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:02.8998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.277558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-20T10:34:02.899851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.520952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-20T10:34:02.899953Z","caller":"traceutil/trace.go:171","msg":"trace[1832767089] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8393; }","duration":"171.636555ms","start":"2026-04-20T10:34:02.728291Z","end":"2026-04-20T10:34:02.899928Z","steps":["trace[1832767089] 'agreement among raft nodes before linearized reading' (duration: 171.324878ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:02.899878Z","caller":"traceutil/trace.go:171","msg":"trace[412170164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8393; }","duration":"251.369489ms","start":"2026-04-20T10:34:02.648485Z","end":"2026-04-20T10:34:02.899854Z","steps":["trace[412170164] 'agreement among raft nodes before linearized reading' (duration: 251.164655ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:03.02329Z","caller":"traceutil/trace.go:171","msg":"trace[1774714723] transaction","detail":"{read_only:false; response_revision:8395; number_of_response:1; }","duration":"118.197786ms","start":"2026-04-20T10:34:02.905052Z","end":"2026-04-20T10:34:03.02325Z","steps":["trace[1774714723] 'process raft request' (duration: 112.441406ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:03.023325Z","caller":"traceutil/trace.go:171","msg":"trace[31036681] transaction","detail":"{read_only:false; response_revision:8396; number_of_response:1; }","duration":"118.201327ms","start":"2026-04-20T10:34:02.905107Z","end":"2026-04-20T10:34:03.023308Z","steps":["trace[31036681] 'process raft request' (duration: 118.045543ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:03.023712Z","caller":"traceutil/trace.go:171","msg":"trace[1825062666] linearizableReadLoop","detail":"{readStateIndex:8694; appliedIndex:8689; }","duration":"117.775295ms","start":"2026-04-20T10:34:02.905917Z","end":"2026-04-20T10:34:03.023693Z","steps":["trace[1825062666] 'read index received' (duration: 16.859761ms)","trace[1825062666] 'applied index is now lower than readState.Index' (duration: 100.914144ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:34:03.023746Z","caller":"traceutil/trace.go:171","msg":"trace[459205874] transaction","detail":"{read_only:false; response_revision:8397; number_of_response:1; }","duration":"118.553773ms","start":"2026-04-20T10:34:02.905163Z","end":"2026-04-20T10:34:03.023717Z","steps":["trace[459205874] 'process raft request' (duration: 118.035121ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:03.023808Z","caller":"traceutil/trace.go:171","msg":"trace[297551281] transaction","detail":"{read_only:false; response_revision:8398; number_of_response:1; }","duration":"118.522512ms","start":"2026-04-20T10:34:02.905257Z","end":"2026-04-20T10:34:03.02378Z","steps":["trace[297551281] 'process raft request' (duration: 118.346528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:03.024201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.266757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223806"} {"level":"info","ts":"2026-04-20T10:34:03.024292Z","caller":"traceutil/trace.go:171","msg":"trace[678777878] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8398; }","duration":"118.401639ms","start":"2026-04-20T10:34:02.905877Z","end":"2026-04-20T10:34:03.024279Z","steps":["trace[678777878] 'agreement among raft nodes before linearized reading' (duration: 117.921508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:35.320295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.900043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:34:35.320437Z","caller":"traceutil/trace.go:171","msg":"trace[794244836] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8620; }","duration":"178.078266ms","start":"2026-04-20T10:34:35.142332Z","end":"2026-04-20T10:34:35.32041Z","steps":["trace[794244836] 'range keys from in-memory index tree' (duration: 177.825531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:35.320319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.622698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-20T10:34:35.320571Z","caller":"traceutil/trace.go:171","msg":"trace[1349854703] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8620; }","duration":"189.941585ms","start":"2026-04-20T10:34:35.130582Z","end":"2026-04-20T10:34:35.320523Z","steps":["trace[1349854703] 'range keys from in-memory index tree' (duration: 189.347512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:35.551868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.588505ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T10:34:35.5522Z","caller":"traceutil/trace.go:171","msg":"trace[1646875744] transaction","detail":"{read_only:false; response_revision:8621; number_of_response:1; }","duration":"226.639876ms","start":"2026-04-20T10:34:35.325546Z","end":"2026-04-20T10:34:35.552186Z","steps":["trace[1646875744] 'process raft request' (duration: 226.476232ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:35.552201Z","caller":"traceutil/trace.go:171","msg":"trace[1739660175] linearizableReadLoop","detail":"{readStateIndex:8925; appliedIndex:8924; }","duration":"317.24316ms","start":"2026-04-20T10:34:35.234915Z","end":"2026-04-20T10:34:35.552158Z","steps":["trace[1739660175] 'read index received' (duration: 19.260407ms)","trace[1739660175] 'applied index is now lower than readState.Index' (duration: 297.980153ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:34:35.552489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.401349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-20T10:34:35.552461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.530937ms","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-20T10:34:35.552532Z","caller":"traceutil/trace.go:171","msg":"trace[1105614255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8621; }","duration":"315.47856ms","start":"2026-04-20T10:34:35.237041Z","end":"2026-04-20T10:34:35.55252Z","steps":["trace[1105614255] 'agreement among raft nodes before linearized reading' (duration: 315.292105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:35.552548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.621539ms","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-20T10:34:35.55255Z","caller":"traceutil/trace.go:171","msg":"trace[1262607524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8621; }","duration":"317.664741ms","start":"2026-04-20T10:34:35.234864Z","end":"2026-04-20T10:34:35.552529Z","steps":["trace[1262607524] 'agreement among raft nodes before linearized reading' (duration: 317.364703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:35.55257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:35.237032Z","time spent":"315.527062ms","remote":"127.0.0.1:59706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T10:34:35.552584Z","caller":"traceutil/trace.go:171","msg":"trace[842570371] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8621; }","duration":"313.709421ms","start":"2026-04-20T10:34:35.238859Z","end":"2026-04-20T10:34:35.552568Z","steps":["trace[842570371] 'agreement among raft nodes before linearized reading' (duration: 313.611278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:35.552586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:35.234834Z","time spent":"317.745323ms","remote":"127.0.0.1:59706","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-20T10:34:35.552613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:35.238832Z","time spent":"313.772961ms","remote":"127.0.0.1:59706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T10:34:35.552627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.575359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:34:35.552656Z","caller":"traceutil/trace.go:171","msg":"trace[1135088099] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8621; }","duration":"227.64208ms","start":"2026-04-20T10:34:35.325006Z","end":"2026-04-20T10:34:35.552648Z","steps":["trace[1135088099] 'agreement among raft nodes before linearized reading' (duration: 227.588229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:35.552779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.664835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jcgfd\" ","response":"range_response_count:1 size:6530"} {"level":"warn","ts":"2026-04-20T10:34:35.552803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.795306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T10:34:35.552837Z","caller":"traceutil/trace.go:171","msg":"trace[812653659] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jcgfd; range_end:; response_count:1; response_revision:8621; }","duration":"315.726506ms","start":"2026-04-20T10:34:35.237095Z","end":"2026-04-20T10:34:35.552822Z","steps":["trace[812653659] 'agreement among raft nodes before linearized reading' (duration: 315.533082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:35.552875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:35.236996Z","time spent":"315.871549ms","remote":"127.0.0.1:59630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6552,"request content":"key:\"/registry/pods/openstack/keepalived-jcgfd\" "} {"level":"info","ts":"2026-04-20T10:34:35.55288Z","caller":"traceutil/trace.go:171","msg":"trace[2044216535] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8621; }","duration":"314.889218ms","start":"2026-04-20T10:34:35.237971Z","end":"2026-04-20T10:34:35.55286Z","steps":["trace[2044216535] 'agreement among raft nodes before linearized reading' (duration: 314.632592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:35.552913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:35.23794Z","time spent":"314.967019ms","remote":"127.0.0.1:59706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T10:34:46.16842Z","caller":"traceutil/trace.go:171","msg":"trace[2084266079] transaction","detail":"{read_only:false; response_revision:8723; number_of_response:1; }","duration":"169.975243ms","start":"2026-04-20T10:34:45.998395Z","end":"2026-04-20T10:34:46.168371Z","steps":["trace[2084266079] 'process raft request' (duration: 169.680047ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:47.263702Z","caller":"traceutil/trace.go:171","msg":"trace[285624787] transaction","detail":"{read_only:false; response_revision:8727; number_of_response:1; }","duration":"182.283702ms","start":"2026-04-20T10:34:47.08138Z","end":"2026-04-20T10:34:47.263664Z","steps":["trace[285624787] 'process raft request' (duration: 181.943905ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:47.274096Z","caller":"traceutil/trace.go:171","msg":"trace[1806883423] transaction","detail":"{read_only:false; response_revision:8728; number_of_response:1; }","duration":"185.682179ms","start":"2026-04-20T10:34:47.088382Z","end":"2026-04-20T10:34:47.274064Z","steps":["trace[1806883423] 'process raft request' (duration: 185.479715ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:47.274293Z","caller":"traceutil/trace.go:171","msg":"trace[1292290969] linearizableReadLoop","detail":"{readStateIndex:9040; appliedIndex:9037; }","duration":"154.508022ms","start":"2026-04-20T10:34:47.119767Z","end":"2026-04-20T10:34:47.274275Z","steps":["trace[1292290969] 'read index received' (duration: 143.797959ms)","trace[1292290969] 'applied index is now lower than readState.Index' (duration: 10.709013ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:34:47.27444Z","caller":"traceutil/trace.go:171","msg":"trace[515215843] transaction","detail":"{read_only:false; response_revision:8729; number_of_response:1; }","duration":"186.013716ms","start":"2026-04-20T10:34:47.088384Z","end":"2026-04-20T10:34:47.274397Z","steps":["trace[515215843] 'process raft request' (duration: 185.632158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:47.274484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.980483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:34:47.274775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.984912ms","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-20T10:34:47.274955Z","caller":"traceutil/trace.go:171","msg":"trace[493234861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8729; }","duration":"155.214907ms","start":"2026-04-20T10:34:47.119717Z","end":"2026-04-20T10:34:47.274932Z","steps":["trace[493234861] 'agreement among raft nodes before linearized reading' (duration: 154.680226ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:47.274672Z","caller":"traceutil/trace.go:171","msg":"trace[443871997] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:8729; }","duration":"106.194798ms","start":"2026-04-20T10:34:47.168431Z","end":"2026-04-20T10:34:47.274625Z","steps":["trace[443871997] 'agreement among raft nodes before linearized reading' (duration: 105.980803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:47.553046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.078152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:34:47.553185Z","caller":"traceutil/trace.go:171","msg":"trace[1222487545] linearizableReadLoop","detail":"{readStateIndex:9041; appliedIndex:9040; }","duration":"210.358218ms","start":"2026-04-20T10:34:47.342806Z","end":"2026-04-20T10:34:47.553164Z","steps":["trace[1222487545] 'read index received' (duration: 36.951478ms)","trace[1222487545] 'applied index is now lower than readState.Index' (duration: 173.40533ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:34:47.553275Z","caller":"traceutil/trace.go:171","msg":"trace[891584797] transaction","detail":"{read_only:false; response_revision:8730; number_of_response:1; }","duration":"273.235443ms","start":"2026-04-20T10:34:47.279977Z","end":"2026-04-20T10:34:47.553212Z","steps":["trace[891584797] 'process raft request' (duration: 99.881424ms)","trace[891584797] 'compare' (duration: 172.929039ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:34:47.553352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.561053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-7795f45c9f-mw48w.18a80a29ba964bf3\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-04-20T10:34:47.553388Z","caller":"traceutil/trace.go:171","msg":"trace[54555817] range","detail":"{range_begin:/registry/events/openstack/barbican-api-7795f45c9f-mw48w.18a80a29ba964bf3; range_end:; response_count:1; response_revision:8730; }","duration":"210.653594ms","start":"2026-04-20T10:34:47.34272Z","end":"2026-04-20T10:34:47.553374Z","steps":["trace[54555817] 'agreement among raft nodes before linearized reading' (duration: 210.497001ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:47.738377Z","caller":"traceutil/trace.go:171","msg":"trace[553263076] linearizableReadLoop","detail":"{readStateIndex:9043; appliedIndex:9042; }","duration":"109.557824ms","start":"2026-04-20T10:34:47.628786Z","end":"2026-04-20T10:34:47.738343Z","steps":["trace[553263076] 'read index received' (duration: 33.57556ms)","trace[553263076] 'applied index is now lower than readState.Index' (duration: 75.980854ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:34:47.73846Z","caller":"traceutil/trace.go:171","msg":"trace[2143023690] transaction","detail":"{read_only:false; response_revision:8732; number_of_response:1; }","duration":"120.063221ms","start":"2026-04-20T10:34:47.618335Z","end":"2026-04-20T10:34:47.738398Z","steps":["trace[2143023690] 'process raft request' (duration: 44.042617ms)","trace[2143023690] 'compare' (duration: 75.844799ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:34:47.738681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.872951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-20T10:34:47.738738Z","caller":"traceutil/trace.go:171","msg":"trace[962692351] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8732; }","duration":"109.970043ms","start":"2026-04-20T10:34:47.62875Z","end":"2026-04-20T10:34:47.73872Z","steps":["trace[962692351] 'agreement among raft nodes before linearized reading' (duration: 109.763878ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:58.984066Z","caller":"traceutil/trace.go:171","msg":"trace[787079355] transaction","detail":"{read_only:false; response_revision:8825; number_of_response:1; }","duration":"114.899784ms","start":"2026-04-20T10:34:58.869128Z","end":"2026-04-20T10:34:58.984028Z","steps":["trace[787079355] 'process raft request' (duration: 114.463395ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:35:03.064938Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023f0.wal"} {"level":"info","ts":"2026-04-20T10:36:03.743695Z","caller":"traceutil/trace.go:171","msg":"trace[1545825670] transaction","detail":"{read_only:false; response_revision:9473; number_of_response:1; }","duration":"157.103561ms","start":"2026-04-20T10:36:03.586556Z","end":"2026-04-20T10:36:03.743659Z","steps":["trace[1545825670] 'process raft request' (duration: 156.900156ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:36:03.758374Z","caller":"traceutil/trace.go:171","msg":"trace[1465680825] transaction","detail":"{read_only:false; response_revision:9474; number_of_response:1; }","duration":"170.291409ms","start":"2026-04-20T10:36:03.588061Z","end":"2026-04-20T10:36:03.758353Z","steps":["trace[1465680825] 'process raft request' (duration: 170.178327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:36:04.036908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.489541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:36:04.037049Z","caller":"traceutil/trace.go:171","msg":"trace[902204150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9474; }","duration":"109.701615ms","start":"2026-04-20T10:36:03.927322Z","end":"2026-04-20T10:36:04.037024Z","steps":["trace[902204150] 'range keys from in-memory index tree' (duration: 109.284177ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:36:28.369162Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T10:36:28.377651Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T10:36:28.377814Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T10:38:36.483927Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8119} {"level":"info","ts":"2026-04-20T10:38:36.549001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8119,"took":"62.559801ms","hash":187726016,"current-db-size-bytes":56610816,"current-db-size":"57 MB","current-db-size-in-use-bytes":29016064,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-20T10:38:36.549062Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":187726016,"revision":8119,"compact-revision":6020} {"level":"info","ts":"2026-04-20T10:43:36.490581Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10644} {"level":"info","ts":"2026-04-20T10:43:36.576036Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10644,"took":"82.466462ms","hash":3445365048,"current-db-size-bytes":56610816,"current-db-size":"57 MB","current-db-size-in-use-bytes":21274624,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-20T10:43:36.576094Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3445365048,"revision":10644,"compact-revision":8119}