{"level":"warn","ts":"2026-04-12T20:46:05.576818Z","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-12T20:46:05.576909Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.29: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.29:2380","--initial-cluster=instance=https://199.19.213.29:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.29:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.29: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-12T20:46:05.576968Z","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-12T20:46:05.576978Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.29:2380"]} {"level":"info","ts":"2026-04-12T20:46:05.576995Z","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-12T20:46:05.577602Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.29:2379"]} {"level":"info","ts":"2026-04-12T20:46:05.577667Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.29:2380"],"listen-peer-urls":["https://199.19.213.29:2380"],"advertise-client-urls":["https://199.19.213.29:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.29:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.29: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-12T20:46:05.581981Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.135122ms"} {"level":"info","ts":"2026-04-12T20:46:05.590009Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b1ecbac2f2214dbd","cluster-id":"94b127ce9c24156a"} {"level":"info","ts":"2026-04-12T20:46:05.590075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd switched to configuration voters=()"} {"level":"info","ts":"2026-04-12T20:46:05.590094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd became follower at term 0"} {"level":"info","ts":"2026-04-12T20:46:05.590105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b1ecbac2f2214dbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-12T20:46:05.590113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd became follower at term 1"} {"level":"info","ts":"2026-04-12T20:46:05.590146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd switched to configuration voters=(12820827585665650109)"} {"level":"warn","ts":"2026-04-12T20:46:05.596292Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-12T20:46:05.597484Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-12T20:46:05.598574Z","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-12T20:46:05.600569Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b1ecbac2f2214dbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-12T20:46:05.600839Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b1ecbac2f2214dbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-12T20:46:05.600885Z","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-12T20:46:05.601031Z","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-12T20:46:05.601115Z","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-12T20:46:05.601566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd switched to configuration voters=(12820827585665650109)"} {"level":"info","ts":"2026-04-12T20:46:05.602187Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"94b127ce9c24156a","local-member-id":"b1ecbac2f2214dbd","added-peer-id":"b1ecbac2f2214dbd","added-peer-peer-urls":["https://199.19.213.29:2380"]} {"level":"info","ts":"2026-04-12T20:46:05.604952Z","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-12T20:46:05.605129Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.29:2380"} {"level":"info","ts":"2026-04-12T20:46:05.606027Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.29:2380"} {"level":"info","ts":"2026-04-12T20:46:05.606652Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b1ecbac2f2214dbd","initial-advertise-peer-urls":["https://199.19.213.29:2380"],"listen-peer-urls":["https://199.19.213.29:2380"],"advertise-client-urls":["https://199.19.213.29:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.29:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-12T20:46:05.606853Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-12T20:46:05.891125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd is starting a new election at term 1"} {"level":"info","ts":"2026-04-12T20:46:05.891173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd became pre-candidate at term 1"} {"level":"info","ts":"2026-04-12T20:46:05.891216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd received MsgPreVoteResp from b1ecbac2f2214dbd at term 1"} {"level":"info","ts":"2026-04-12T20:46:05.891235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd became candidate at term 2"} {"level":"info","ts":"2026-04-12T20:46:05.891246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd received MsgVoteResp from b1ecbac2f2214dbd at term 2"} {"level":"info","ts":"2026-04-12T20:46:05.891267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd became leader at term 2"} {"level":"info","ts":"2026-04-12T20:46:05.891287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b1ecbac2f2214dbd elected leader b1ecbac2f2214dbd at term 2"} {"level":"info","ts":"2026-04-12T20:46:05.891919Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:46:05.892853Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T20:46:05.893096Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T20:46:05.893122Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-12T20:46:05.893171Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-12T20:46:05.893332Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"94b127ce9c24156a","local-member-id":"b1ecbac2f2214dbd","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:46:05.893442Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:46:05.893481Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:46:05.896479Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-12T20:46:05.897387Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.29:2379"} {"level":"info","ts":"2026-04-12T20:46:05.89282Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b1ecbac2f2214dbd","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.29:2379]}","request-path":"/0/members/b1ecbac2f2214dbd/attributes","cluster-id":"94b127ce9c24156a","publish-timeout":"7s"} {"level":"info","ts":"2026-04-12T20:46:08.760567Z","caller":"traceutil/trace.go:171","msg":"trace[513406629] linearizableReadLoop","detail":"{readStateIndex:123; appliedIndex:122; }","duration":"121.075468ms","start":"2026-04-12T20:46:08.639469Z","end":"2026-04-12T20:46:08.760544Z","steps":["trace[513406629] 'read index received' (duration: 97.760946ms)","trace[513406629] 'applied index is now lower than readState.Index' (duration: 23.313692ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:46:08.760713Z","caller":"traceutil/trace.go:171","msg":"trace[2112942556] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"122.491126ms","start":"2026-04-12T20:46:08.638209Z","end":"2026-04-12T20:46:08.760701Z","steps":["trace[2112942556] 'process raft request' (duration: 99.120841ms)","trace[2112942556] 'compare' (duration: 23.104748ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:46:08.761002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.49243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-12T20:46:08.761097Z","caller":"traceutil/trace.go:171","msg":"trace[1140781497] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:118; }","duration":"121.620723ms","start":"2026-04-12T20:46:08.639454Z","end":"2026-04-12T20:46:08.761075Z","steps":["trace[1140781497] 'agreement among raft nodes before linearized reading' (duration: 121.475199ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:34.871701Z","caller":"traceutil/trace.go:171","msg":"trace[47297745] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"197.059227ms","start":"2026-04-12T20:46:34.674618Z","end":"2026-04-12T20:46:34.871678Z","steps":["trace[47297745] 'process raft request' (duration: 196.890962ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:34.90368Z","caller":"traceutil/trace.go:171","msg":"trace[417680095] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"228.625668ms","start":"2026-04-12T20:46:34.675028Z","end":"2026-04-12T20:46:34.903654Z","steps":["trace[417680095] 'process raft request' (duration: 228.456883ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:34.903994Z","caller":"traceutil/trace.go:171","msg":"trace[1909701861] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"203.036459ms","start":"2026-04-12T20:46:34.700941Z","end":"2026-04-12T20:46:34.903977Z","steps":["trace[1909701861] 'process raft request' (duration: 202.727902ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:34.904129Z","caller":"traceutil/trace.go:171","msg":"trace[1573602506] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"211.16493ms","start":"2026-04-12T20:46:34.692936Z","end":"2026-04-12T20:46:34.904101Z","steps":["trace[1573602506] 'process raft request' (duration: 210.671487ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:34.904158Z","caller":"traceutil/trace.go:171","msg":"trace[55233173] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"203.199564ms","start":"2026-04-12T20:46:34.700949Z","end":"2026-04-12T20:46:34.904149Z","steps":["trace[55233173] 'process raft request' (duration: 202.682361ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:34.904188Z","caller":"traceutil/trace.go:171","msg":"trace[1494495077] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"203.147023ms","start":"2026-04-12T20:46:34.701035Z","end":"2026-04-12T20:46:34.904182Z","steps":["trace[1494495077] 'process raft request' (duration: 202.6612ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:34.904212Z","caller":"traceutil/trace.go:171","msg":"trace[394195294] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"150.771626ms","start":"2026-04-12T20:46:34.753433Z","end":"2026-04-12T20:46:34.904205Z","steps":["trace[394195294] 'process raft request' (duration: 150.415777ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:34.904227Z","caller":"traceutil/trace.go:171","msg":"trace[1066365890] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"176.334314ms","start":"2026-04-12T20:46:34.727889Z","end":"2026-04-12T20:46:34.904224Z","steps":["trace[1066365890] 'process raft request' (duration: 175.916013ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:34.904181Z","caller":"traceutil/trace.go:171","msg":"trace[2063531648] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"191.450362ms","start":"2026-04-12T20:46:34.712692Z","end":"2026-04-12T20:46:34.904142Z","steps":["trace[2063531648] 'process raft request' (duration: 191.059722ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:34.904261Z","caller":"traceutil/trace.go:171","msg":"trace[1621858845] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"202.603128ms","start":"2026-04-12T20:46:34.701648Z","end":"2026-04-12T20:46:34.904251Z","steps":["trace[1621858845] 'process raft request' (duration: 202.072805ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:34.90418Z","caller":"traceutil/trace.go:171","msg":"trace[340731954] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"225.110027ms","start":"2026-04-12T20:46:34.679058Z","end":"2026-04-12T20:46:34.904168Z","steps":["trace[340731954] 'process raft request' (duration: 224.524783ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:41.282261Z","caller":"traceutil/trace.go:171","msg":"trace[1918125737] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"129.372566ms","start":"2026-04-12T20:46:41.152864Z","end":"2026-04-12T20:46:41.282237Z","steps":["trace[1918125737] 'read index received' (duration: 128.625397ms)","trace[1918125737] 'applied index is now lower than readState.Index' (duration: 746.259µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:46:41.282384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.520428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:46:41.282414Z","caller":"traceutil/trace.go:171","msg":"trace[266307694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"129.609161ms","start":"2026-04-12T20:46:41.152794Z","end":"2026-04-12T20:46:41.282404Z","steps":["trace[266307694] 'agreement among raft nodes before linearized reading' (duration: 129.538709ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:41.282657Z","caller":"traceutil/trace.go:171","msg":"trace[1298524987] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"337.74378ms","start":"2026-04-12T20:46:40.944768Z","end":"2026-04-12T20:46:41.282512Z","steps":["trace[1298524987] 'process raft request' (duration: 336.96174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:41.28336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:40.944741Z","time spent":"338.069177ms","remote":"127.0.0.1:41326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:46:41.854549Z","caller":"traceutil/trace.go:171","msg":"trace[756111003] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"284.256379ms","start":"2026-04-12T20:46:41.570272Z","end":"2026-04-12T20:46:41.854529Z","steps":["trace[756111003] 'process raft request' (duration: 284.186808ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:41.854574Z","caller":"traceutil/trace.go:171","msg":"trace[2117811922] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"511.830153ms","start":"2026-04-12T20:46:41.342702Z","end":"2026-04-12T20:46:41.854532Z","steps":["trace[2117811922] 'read index received' (duration: 498.75428ms)","trace[2117811922] 'applied index is now lower than readState.Index' (duration: 13.072363ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:46:41.854775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.051079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-njqjg\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-12T20:46:41.854861Z","caller":"traceutil/trace.go:171","msg":"trace[1619441352] range","detail":"{range_begin:/registry/pods/kube-system/cilium-njqjg; range_end:; response_count:1; response_revision:619; }","duration":"512.176933ms","start":"2026-04-12T20:46:41.342665Z","end":"2026-04-12T20:46:41.854842Z","steps":["trace[1619441352] 'agreement among raft nodes before linearized reading' (duration: 511.943896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:41.854903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:41.342649Z","time spent":"512.244384ms","remote":"127.0.0.1:58932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-njqjg\" "} {"level":"info","ts":"2026-04-12T20:46:41.854565Z","caller":"traceutil/trace.go:171","msg":"trace[1522974124] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"517.023516ms","start":"2026-04-12T20:46:41.337489Z","end":"2026-04-12T20:46:41.854513Z","steps":["trace[1522974124] 'process raft request' (duration: 503.954053ms)","trace[1522974124] 'compare' (duration: 12.809887ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:46:41.85531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:41.337455Z","time spent":"517.808687ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:46:56.694742Z","caller":"traceutil/trace.go:171","msg":"trace[195738830] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"152.038706ms","start":"2026-04-12T20:46:56.542685Z","end":"2026-04-12T20:46:56.694724Z","steps":["trace[195738830] 'process raft request' (duration: 151.915163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:14.683557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.481102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:48:14.683705Z","caller":"traceutil/trace.go:171","msg":"trace[723806877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1834; }","duration":"132.672468ms","start":"2026-04-12T20:48:14.551004Z","end":"2026-04-12T20:48:14.683677Z","steps":["trace[723806877] 'range keys from in-memory index tree' (duration: 132.39733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:14.683729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.908683ms","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-12T20:48:14.683853Z","caller":"traceutil/trace.go:171","msg":"trace[799920622] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1834; }","duration":"143.097967ms","start":"2026-04-12T20:48:14.54073Z","end":"2026-04-12T20:48:14.683828Z","steps":["trace[799920622] 'range keys from in-memory index tree' (duration: 142.681846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:14.684043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.728817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-12T20:48:14.684124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.61026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-12T20:48:14.684154Z","caller":"traceutil/trace.go:171","msg":"trace[1364099696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1834; }","duration":"371.899161ms","start":"2026-04-12T20:48:14.312229Z","end":"2026-04-12T20:48:14.684128Z","steps":["trace[1364099696] 'range keys from in-memory index tree' (duration: 371.480842ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:14.684204Z","caller":"traceutil/trace.go:171","msg":"trace[1384742976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1834; }","duration":"366.708942ms","start":"2026-04-12T20:48:14.317477Z","end":"2026-04-12T20:48:14.684185Z","steps":["trace[1384742976] 'range keys from in-memory index tree' (duration: 366.476046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:14.684209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:14.312211Z","time spent":"371.988033ms","remote":"127.0.0.1:59012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-12T20:48:14.684211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.481178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"warn","ts":"2026-04-12T20:48:14.684253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:14.317466Z","time spent":"366.774033ms","remote":"127.0.0.1:59012","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-12T20:48:14.684272Z","caller":"traceutil/trace.go:171","msg":"trace[1738188839] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1834; }","duration":"340.544189ms","start":"2026-04-12T20:48:14.343711Z","end":"2026-04-12T20:48:14.684256Z","steps":["trace[1738188839] 'range keys from in-memory index tree' (duration: 340.291393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:14.684314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:14.343613Z","time spent":"340.689982ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-12T20:48:14.912586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.533999ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-12T20:48:14.912697Z","caller":"traceutil/trace.go:171","msg":"trace[1107839199] linearizableReadLoop","detail":"{readStateIndex:1898; appliedIndex:1893; }","duration":"153.356524ms","start":"2026-04-12T20:48:14.759321Z","end":"2026-04-12T20:48:14.912678Z","steps":["trace[1107839199] 'read index received' (duration: 18.123192ms)","trace[1107839199] 'applied index is now lower than readState.Index' (duration: 135.231261ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:48:14.912926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.651127ms","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-12T20:48:14.912962Z","caller":"traceutil/trace.go:171","msg":"trace[1764810794] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1839; }","duration":"130.725808ms","start":"2026-04-12T20:48:14.782225Z","end":"2026-04-12T20:48:14.912951Z","steps":["trace[1764810794] 'agreement among raft nodes before linearized reading' (duration: 130.547204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:14.913173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.813065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:48:14.913263Z","caller":"traceutil/trace.go:171","msg":"trace[1023212790] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1839; }","duration":"153.97691ms","start":"2026-04-12T20:48:14.759268Z","end":"2026-04-12T20:48:14.913245Z","steps":["trace[1023212790] 'agreement among raft nodes before linearized reading' (duration: 153.480437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:14.913475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.051799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:9032"} {"level":"info","ts":"2026-04-12T20:48:14.913511Z","caller":"traceutil/trace.go:171","msg":"trace[1567086400] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1839; }","duration":"105.138151ms","start":"2026-04-12T20:48:14.808363Z","end":"2026-04-12T20:48:14.913501Z","steps":["trace[1567086400] 'agreement among raft nodes before linearized reading' (duration: 105.044779ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:57.162123Z","caller":"traceutil/trace.go:171","msg":"trace[152958923] linearizableReadLoop","detail":"{readStateIndex:2250; appliedIndex:2249; }","duration":"169.822469ms","start":"2026-04-12T20:48:56.992259Z","end":"2026-04-12T20:48:57.162082Z","steps":["trace[152958923] 'read index received' (duration: 169.698716ms)","trace[152958923] 'applied index is now lower than readState.Index' (duration: 122.253µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:48:57.162235Z","caller":"traceutil/trace.go:171","msg":"trace[1858387125] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"170.987089ms","start":"2026-04-12T20:48:56.991196Z","end":"2026-04-12T20:48:57.162183Z","steps":["trace[1858387125] 'process raft request' (duration: 170.689882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:57.162517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.22962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-12T20:48:57.162632Z","caller":"traceutil/trace.go:171","msg":"trace[1427532234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2174; }","duration":"170.379153ms","start":"2026-04-12T20:48:56.992237Z","end":"2026-04-12T20:48:57.162616Z","steps":["trace[1427532234] 'agreement among raft nodes before linearized reading' (duration: 170.118056ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:57.366025Z","caller":"traceutil/trace.go:171","msg":"trace[867385822] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"198.70413ms","start":"2026-04-12T20:48:57.167296Z","end":"2026-04-12T20:48:57.366Z","steps":["trace[867385822] 'process raft request' (duration: 198.653909ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:57.366181Z","caller":"traceutil/trace.go:171","msg":"trace[1666107894] linearizableReadLoop","detail":"{readStateIndex:2251; appliedIndex:2250; }","duration":"203.90601ms","start":"2026-04-12T20:48:57.162262Z","end":"2026-04-12T20:48:57.366168Z","steps":["trace[1666107894] 'read index received' (duration: 149.558547ms)","trace[1666107894] 'applied index is now lower than readState.Index' (duration: 54.345663ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:48:57.366091Z","caller":"traceutil/trace.go:171","msg":"trace[1994106325] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"370.234422ms","start":"2026-04-12T20:48:56.995812Z","end":"2026-04-12T20:48:57.366046Z","steps":["trace[1994106325] 'process raft request' (duration: 316.041362ms)","trace[1994106325] 'compare' (duration: 53.934243ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:48:57.366476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.512838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-04-12T20:48:57.366367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.378412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T20:48:57.366536Z","caller":"traceutil/trace.go:171","msg":"trace[1218546869] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2176; }","duration":"368.59416ms","start":"2026-04-12T20:48:56.997924Z","end":"2026-04-12T20:48:57.366518Z","steps":["trace[1218546869] 'agreement among raft nodes before linearized reading' (duration: 368.380525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:57.366575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:56.997917Z","time spent":"368.648841ms","remote":"127.0.0.1:41614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4615,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-12T20:48:57.366605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.332686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:48:57.36662Z","caller":"traceutil/trace.go:171","msg":"trace[1334534484] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:2176; }","duration":"251.612367ms","start":"2026-04-12T20:48:57.11495Z","end":"2026-04-12T20:48:57.366562Z","steps":["trace[1334534484] 'agreement among raft nodes before linearized reading' (duration: 251.3301ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:57.366717Z","caller":"traceutil/trace.go:171","msg":"trace[2010898928] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2176; }","duration":"285.533582ms","start":"2026-04-12T20:48:57.081157Z","end":"2026-04-12T20:48:57.36669Z","steps":["trace[2010898928] 'agreement among raft nodes before linearized reading' (duration: 285.283365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:57.366664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:56.995796Z","time spent":"370.653041ms","remote":"127.0.0.1:59012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:48:57.614017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.075512ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:48:57.614293Z","caller":"traceutil/trace.go:171","msg":"trace[1121899435] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"239.366711ms","start":"2026-04-12T20:48:57.374818Z","end":"2026-04-12T20:48:57.614185Z","steps":["trace[1121899435] 'process raft request' (duration: 138.979572ms)","trace[1121899435] 'compare' (duration: 99.919808ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:49:39.235667Z","caller":"traceutil/trace.go:171","msg":"trace[68624728] transaction","detail":"{read_only:false; response_revision:2564; number_of_response:1; }","duration":"106.938774ms","start":"2026-04-12T20:49:39.128695Z","end":"2026-04-12T20:49:39.235634Z","steps":["trace[68624728] 'process raft request' (duration: 106.238026ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:39.741382Z","caller":"traceutil/trace.go:171","msg":"trace[515653760] transaction","detail":"{read_only:false; response_revision:2567; number_of_response:1; }","duration":"148.726002ms","start":"2026-04-12T20:49:39.592629Z","end":"2026-04-12T20:49:39.741355Z","steps":["trace[515653760] 'process raft request' (duration: 148.519687ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:39.743351Z","caller":"traceutil/trace.go:171","msg":"trace[347844345] transaction","detail":"{read_only:false; response_revision:2568; number_of_response:1; }","duration":"149.42892ms","start":"2026-04-12T20:49:39.593901Z","end":"2026-04-12T20:49:39.74333Z","steps":["trace[347844345] 'process raft request' (duration: 149.206425ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:12.106694Z","caller":"traceutil/trace.go:171","msg":"trace[26269578] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"185.546261ms","start":"2026-04-12T20:50:11.921121Z","end":"2026-04-12T20:50:12.106667Z","steps":["trace[26269578] 'process raft request' (duration: 185.385276ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:12.233827Z","caller":"traceutil/trace.go:171","msg":"trace[1945104471] linearizableReadLoop","detail":"{readStateIndex:2901; appliedIndex:2900; }","duration":"154.530177ms","start":"2026-04-12T20:50:12.079269Z","end":"2026-04-12T20:50:12.2338Z","steps":["trace[1945104471] 'read index received' (duration: 27.448744ms)","trace[1945104471] 'applied index is now lower than readState.Index' (duration: 127.080263ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:50:12.234012Z","caller":"traceutil/trace.go:171","msg":"trace[2135833063] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"312.00716ms","start":"2026-04-12T20:50:11.921989Z","end":"2026-04-12T20:50:12.233996Z","steps":["trace[2135833063] 'process raft request' (duration: 307.101557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:12.234159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:11.921952Z","time spent":"312.098941ms","remote":"127.0.0.1:59012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:50:12.234288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.087832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-12T20:50:12.234618Z","caller":"traceutil/trace.go:171","msg":"trace[1114345091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2791; }","duration":"155.482811ms","start":"2026-04-12T20:50:12.079107Z","end":"2026-04-12T20:50:12.234589Z","steps":["trace[1114345091] 'agreement among raft nodes before linearized reading' (duration: 154.995139ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:53:03.144144Z","caller":"traceutil/trace.go:171","msg":"trace[1722724440] linearizableReadLoop","detail":"{readStateIndex:4828; appliedIndex:4827; }","duration":"167.56555ms","start":"2026-04-12T20:53:02.976543Z","end":"2026-04-12T20:53:03.144109Z","steps":["trace[1722724440] 'read index received' (duration: 134.169676ms)","trace[1722724440] 'applied index is now lower than readState.Index' (duration: 33.393884ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:53:03.144424Z","caller":"traceutil/trace.go:171","msg":"trace[839781021] transaction","detail":"{read_only:false; response_revision:4626; number_of_response:1; }","duration":"168.204232ms","start":"2026-04-12T20:53:02.976206Z","end":"2026-04-12T20:53:03.14441Z","steps":["trace[839781021] 'process raft request' (duration: 134.591224ms)","trace[839781021] 'compare' (duration: 33.087387ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:53:03.144833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.997527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-12T20:53:03.144898Z","caller":"traceutil/trace.go:171","msg":"trace[879589764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4626; }","duration":"106.12989ms","start":"2026-04-12T20:53:03.038754Z","end":"2026-04-12T20:53:03.144884Z","steps":["trace[879589764] 'agreement among raft nodes before linearized reading' (duration: 105.935317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:53:03.144933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.961198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:56674"} {"level":"info","ts":"2026-04-12T20:53:03.145041Z","caller":"traceutil/trace.go:171","msg":"trace[278765634] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4626; }","duration":"175.122442ms","start":"2026-04-12T20:53:02.969893Z","end":"2026-04-12T20:53:03.145016Z","steps":["trace[278765634] 'agreement among raft nodes before linearized reading' (duration: 174.691483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:53:15.589337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.546575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:53:15.589445Z","caller":"traceutil/trace.go:171","msg":"trace[2067120155] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"303.662559ms","start":"2026-04-12T20:53:15.285764Z","end":"2026-04-12T20:53:15.589427Z","steps":["trace[2067120155] 'process raft request' (duration: 177.94111ms)","trace[2067120155] 'compare' (duration: 125.396022ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:53:15.589496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:53:15.28575Z","time spent":"303.72495ms","remote":"127.0.0.1:59012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:53:15.61188Z","caller":"traceutil/trace.go:171","msg":"trace[520247756] linearizableReadLoop","detail":"{readStateIndex:5008; appliedIndex:5005; }","duration":"179.083224ms","start":"2026-04-12T20:53:15.432762Z","end":"2026-04-12T20:53:15.611845Z","steps":["trace[520247756] 'read index received' (duration: 30.977546ms)","trace[520247756] 'applied index is now lower than readState.Index' (duration: 148.104338ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:53:15.612246Z","caller":"traceutil/trace.go:171","msg":"trace[317736635] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"326.312333ms","start":"2026-04-12T20:53:15.285918Z","end":"2026-04-12T20:53:15.61223Z","steps":["trace[317736635] 'process raft request' (duration: 325.713281ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:53:15.612289Z","caller":"traceutil/trace.go:171","msg":"trace[1181657797] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4798; }","duration":"325.689531ms","start":"2026-04-12T20:53:15.286577Z","end":"2026-04-12T20:53:15.612267Z","steps":["trace[1181657797] 'process raft request' (duration: 325.218081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:53:15.612354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:53:15.286566Z","time spent":"325.761091ms","remote":"127.0.0.1:59218","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:53:15.612409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.556125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-12T20:53:15.61251Z","caller":"traceutil/trace.go:171","msg":"trace[1871017456] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4798; }","duration":"104.744219ms","start":"2026-04-12T20:53:15.50774Z","end":"2026-04-12T20:53:15.612484Z","steps":["trace[1871017456] 'agreement among raft nodes before linearized reading' (duration: 104.478414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:53:15.612527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:53:15.285906Z","time spent":"326.382936ms","remote":"127.0.0.1:59012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:53:15.612538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.736657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10515"} {"level":"info","ts":"2026-04-12T20:53:15.612971Z","caller":"traceutil/trace.go:171","msg":"trace[1652277704] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4798; }","duration":"180.242127ms","start":"2026-04-12T20:53:15.432713Z","end":"2026-04-12T20:53:15.612955Z","steps":["trace[1652277704] 'agreement among raft nodes before linearized reading' (duration: 179.470851ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:53:15.89842Z","caller":"traceutil/trace.go:171","msg":"trace[408100233] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"142.373571ms","start":"2026-04-12T20:53:15.756006Z","end":"2026-04-12T20:53:15.89838Z","steps":["trace[408100233] 'process raft request' (duration: 79.230536ms)","trace[408100233] 'compare' (duration: 62.897309ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:53:45.548443Z","caller":"traceutil/trace.go:171","msg":"trace[1262642493] transaction","detail":"{read_only:false; response_revision:5160; number_of_response:1; }","duration":"383.379942ms","start":"2026-04-12T20:53:45.164985Z","end":"2026-04-12T20:53:45.548365Z","steps":["trace[1262642493] 'process raft request' (duration: 383.124767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:53:45.548651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:53:45.16497Z","time spent":"383.587496ms","remote":"127.0.0.1:41326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2585,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:53:45.590274Z","caller":"traceutil/trace.go:171","msg":"trace[1171026981] linearizableReadLoop","detail":"{readStateIndex:5385; appliedIndex:5383; }","duration":"219.957461ms","start":"2026-04-12T20:53:45.370292Z","end":"2026-04-12T20:53:45.59025Z","steps":["trace[1171026981] 'read index received' (duration: 177.798576ms)","trace[1171026981] 'applied index is now lower than readState.Index' (duration: 42.157485ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:53:45.590685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.345969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-12T20:53:45.590897Z","caller":"traceutil/trace.go:171","msg":"trace[1997331136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5161; }","duration":"220.586795ms","start":"2026-04-12T20:53:45.370284Z","end":"2026-04-12T20:53:45.590871Z","steps":["trace[1997331136] 'agreement among raft nodes before linearized reading' (duration: 220.098374ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:53:45.591051Z","caller":"traceutil/trace.go:171","msg":"trace[358985589] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"417.013337ms","start":"2026-04-12T20:53:45.173299Z","end":"2026-04-12T20:53:45.590313Z","steps":["trace[358985589] 'process raft request' (duration: 413.531902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:53:45.591545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:53:45.173262Z","time spent":"417.883125ms","remote":"127.0.0.1:59012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:56:06.453259Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3250} {"level":"info","ts":"2026-04-12T20:56:06.541996Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3250,"took":"86.670689ms","hash":2723740015,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":31236096,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-12T20:56:06.542094Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2723740015,"revision":3250,"compact-revision":-1}