{"level":"warn","ts":"2026-04-12T08:48:24.381766Z","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-12T08:48:24.381846Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62: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://162.253.55.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62: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-12T08:48:24.381943Z","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-12T08:48:24.381956Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-04-12T08:48:24.381972Z","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-12T08:48:24.3826Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-04-12T08:48:24.382668Z","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://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62: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-12T08:48:24.388141Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.125215ms"} {"level":"info","ts":"2026-04-12T08:48:24.396369Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-04-12T08:48:24.396551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-04-12T08:48:24.39664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-04-12T08:48:24.396729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-12T08:48:24.396877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-04-12T08:48:24.396998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-04-12T08:48:24.402657Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-12T08:48:24.404523Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-12T08:48:24.406412Z","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-12T08:48:24.409025Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-12T08:48:24.409172Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-12T08:48:24.411164Z","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-12T08:48:24.411756Z","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-12T08:48:24.411788Z","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-12T08:48:24.414971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-04-12T08:48:24.415052Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-04-12T08:48:24.415953Z","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-12T08:48:24.416092Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-04-12T08:48:24.41614Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-04-12T08:48:24.416307Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-12T08:48:24.416351Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-12T08:48:24.697283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-04-12T08:48:24.697333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-12T08:48:24.697365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-04-12T08:48:24.697379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-04-12T08:48:24.697386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-04-12T08:48:24.697396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-04-12T08:48:24.697405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-04-12T08:48:24.698161Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-04-12T08:48:24.698178Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T08:48:24.698306Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T08:48:24.698401Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-12T08:48:24.698414Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-12T08:48:24.698387Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T08:48:24.698938Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T08:48:24.699011Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T08:48:24.699028Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T08:48:24.700953Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-12T08:48:24.701686Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-04-12T08:48:34.221905Z","caller":"traceutil/trace.go:171","msg":"trace[498000482] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"105.234791ms","start":"2026-04-12T08:48:34.116641Z","end":"2026-04-12T08:48:34.221876Z","steps":["trace[498000482] 'process raft request' (duration: 92.735851ms)","trace[498000482] 'compare' (duration: 12.339477ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T08:48:42.682931Z","caller":"traceutil/trace.go:171","msg":"trace[490832340] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"262.383695ms","start":"2026-04-12T08:48:42.420516Z","end":"2026-04-12T08:48:42.6829Z","steps":["trace[490832340] 'process raft request' (duration: 254.462107ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T08:48:42.742601Z","caller":"traceutil/trace.go:171","msg":"trace[2139090609] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"321.985399ms","start":"2026-04-12T08:48:42.420583Z","end":"2026-04-12T08:48:42.742568Z","steps":["trace[2139090609] 'process raft request' (duration: 321.823766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:48:42.743414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T08:48:42.420564Z","time spent":"322.132252ms","remote":"127.0.0.1:33790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T08:49:00.369133Z","caller":"traceutil/trace.go:171","msg":"trace[1502054496] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"100.317863ms","start":"2026-04-12T08:49:00.268791Z","end":"2026-04-12T08:49:00.369109Z","steps":["trace[1502054496] 'read index received' (duration: 98.691291ms)","trace[1502054496] 'applied index is now lower than readState.Index' (duration: 1.623252ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T08:49:00.369158Z","caller":"traceutil/trace.go:171","msg":"trace[1655508447] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"216.70101ms","start":"2026-04-12T08:49:00.152421Z","end":"2026-04-12T08:49:00.369122Z","steps":["trace[1655508447] 'process raft request' (duration: 215.466857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:49:00.369302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.463785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T08:49:00.369564Z","caller":"traceutil/trace.go:171","msg":"trace[1781784910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"100.808662ms","start":"2026-04-12T08:49:00.268738Z","end":"2026-04-12T08:49:00.369547Z","steps":["trace[1781784910] 'agreement among raft nodes before linearized reading' (duration: 100.472686ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T08:49:00.632499Z","caller":"traceutil/trace.go:171","msg":"trace[652236884] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"118.712503ms","start":"2026-04-12T08:49:00.513771Z","end":"2026-04-12T08:49:00.632484Z","steps":["trace[652236884] 'read index received' (duration: 118.56323ms)","trace[652236884] 'applied index is now lower than readState.Index' (duration: 148.563µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T08:49:00.632633Z","caller":"traceutil/trace.go:171","msg":"trace[1562587441] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"205.472597ms","start":"2026-04-12T08:49:00.427102Z","end":"2026-04-12T08:49:00.632574Z","steps":["trace[1562587441] 'process raft request' (duration: 205.276493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:49:00.632674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.889976ms","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-12T08:49:00.633049Z","caller":"traceutil/trace.go:171","msg":"trace[704947019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"119.269183ms","start":"2026-04-12T08:49:00.513764Z","end":"2026-04-12T08:49:00.633033Z","steps":["trace[704947019] 'agreement among raft nodes before linearized reading' (duration: 118.815805ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T08:49:00.984271Z","caller":"traceutil/trace.go:171","msg":"trace[163865671] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"216.651751ms","start":"2026-04-12T08:49:00.767595Z","end":"2026-04-12T08:49:00.984246Z","steps":["trace[163865671] 'read index received' (duration: 216.435936ms)","trace[163865671] 'applied index is now lower than readState.Index' (duration: 214.645µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T08:49:00.984422Z","caller":"traceutil/trace.go:171","msg":"trace[147110860] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"336.348211ms","start":"2026-04-12T08:49:00.648036Z","end":"2026-04-12T08:49:00.984384Z","steps":["trace[147110860] 'process raft request' (duration: 336.057056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:49:00.984474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.854744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-12T08:49:00.98461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T08:49:00.64801Z","time spent":"336.508505ms","remote":"127.0.0.1:33696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T08:49:00.984622Z","caller":"traceutil/trace.go:171","msg":"trace[983004532] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"217.039618ms","start":"2026-04-12T08:49:00.767566Z","end":"2026-04-12T08:49:00.984606Z","steps":["trace[983004532] 'agreement among raft nodes before linearized reading' (duration: 216.796834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:49:00.984788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.867913ms","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-12T08:49:00.985013Z","caller":"traceutil/trace.go:171","msg":"trace[710251321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"144.081027ms","start":"2026-04-12T08:49:00.840872Z","end":"2026-04-12T08:49:00.984953Z","steps":["trace[710251321] 'agreement among raft nodes before linearized reading' (duration: 143.639569ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T08:49:01.110154Z","caller":"traceutil/trace.go:171","msg":"trace[1557364936] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"120.612781ms","start":"2026-04-12T08:49:00.989488Z","end":"2026-04-12T08:49:01.110101Z","steps":["trace[1557364936] 'process raft request' (duration: 100.954677ms)","trace[1557364936] 'compare' (duration: 19.493221ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T08:49:01.132845Z","caller":"traceutil/trace.go:171","msg":"trace[1292665254] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"140.823747ms","start":"2026-04-12T08:49:00.991986Z","end":"2026-04-12T08:49:01.13281Z","steps":["trace[1292665254] 'process raft request' (duration: 140.48343ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T08:49:01.133133Z","caller":"traceutil/trace.go:171","msg":"trace[250478337] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"100.841584ms","start":"2026-04-12T08:49:01.032278Z","end":"2026-04-12T08:49:01.13312Z","steps":["trace[250478337] 'process raft request' (duration: 100.459887ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T08:49:16.236857Z","caller":"traceutil/trace.go:171","msg":"trace[1560717081] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"137.756828ms","start":"2026-04-12T08:49:16.099071Z","end":"2026-04-12T08:49:16.236828Z","steps":["trace[1560717081] 'process raft request' (duration: 137.586545ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T08:50:28.683819Z","caller":"traceutil/trace.go:171","msg":"trace[405904077] linearizableReadLoop","detail":"{readStateIndex:1861; appliedIndex:1860; }","duration":"415.417998ms","start":"2026-04-12T08:50:28.268375Z","end":"2026-04-12T08:50:28.683793Z","steps":["trace[405904077] 'read index received' (duration: 415.156072ms)","trace[405904077] 'applied index is now lower than readState.Index' (duration: 261.006µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T08:50:28.683838Z","caller":"traceutil/trace.go:171","msg":"trace[1464622417] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"419.0445ms","start":"2026-04-12T08:50:28.264773Z","end":"2026-04-12T08:50:28.683818Z","steps":["trace[1464622417] 'process raft request' (duration: 418.871407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:50:28.68396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.581231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T08:50:28.683998Z","caller":"traceutil/trace.go:171","msg":"trace[484285822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1790; }","duration":"415.665143ms","start":"2026-04-12T08:50:28.268323Z","end":"2026-04-12T08:50:28.683988Z","steps":["trace[484285822] 'agreement among raft nodes before linearized reading' (duration: 415.598712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:50:28.68398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T08:50:28.264743Z","time spent":"419.165223ms","remote":"127.0.0.1:33790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T08:50:28.684061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T08:50:28.268307Z","time spent":"415.713214ms","remote":"127.0.0.1:33488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T08:50:28.684203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.518824ms","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-12T08:50:28.684374Z","caller":"traceutil/trace.go:171","msg":"trace[1429105703] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1790; }","duration":"133.737869ms","start":"2026-04-12T08:50:28.550617Z","end":"2026-04-12T08:50:28.684354Z","steps":["trace[1429105703] 'agreement among raft nodes before linearized reading' (duration: 133.442793ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T08:51:03.050565Z","caller":"traceutil/trace.go:171","msg":"trace[81495265] linearizableReadLoop","detail":"{readStateIndex:2111; appliedIndex:2110; }","duration":"217.420101ms","start":"2026-04-12T08:51:02.83312Z","end":"2026-04-12T08:51:03.05054Z","steps":["trace[81495265] 'read index received' (duration: 217.277238ms)","trace[81495265] 'applied index is now lower than readState.Index' (duration: 141.603µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T08:51:03.050945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.118809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T08:51:03.050937Z","caller":"traceutil/trace.go:171","msg":"trace[1445357975] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"220.047263ms","start":"2026-04-12T08:51:02.830687Z","end":"2026-04-12T08:51:03.050734Z","steps":["trace[1445357975] 'process raft request' (duration: 219.683366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:51:03.050972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.516548ms","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-12T08:51:03.051038Z","caller":"traceutil/trace.go:171","msg":"trace[947567379] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2033; }","duration":"190.211731ms","start":"2026-04-12T08:51:02.860771Z","end":"2026-04-12T08:51:03.050983Z","steps":["trace[947567379] 'agreement among raft nodes before linearized reading' (duration: 190.013557ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T08:51:03.051049Z","caller":"traceutil/trace.go:171","msg":"trace[1202128787] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2033; }","duration":"112.642549ms","start":"2026-04-12T08:51:02.938388Z","end":"2026-04-12T08:51:03.051031Z","steps":["trace[1202128787] 'agreement among raft nodes before linearized reading' (duration: 112.412025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:51:03.051163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.525239ms","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-12T08:51:03.051262Z","caller":"traceutil/trace.go:171","msg":"trace[1513127979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2033; }","duration":"173.683203ms","start":"2026-04-12T08:51:02.877556Z","end":"2026-04-12T08:51:03.051239Z","steps":["trace[1513127979] 'agreement among raft nodes before linearized reading' (duration: 173.357306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:51:03.051451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.322619ms","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-12T08:51:03.051556Z","caller":"traceutil/trace.go:171","msg":"trace[1369465619] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2033; }","duration":"218.429961ms","start":"2026-04-12T08:51:02.833099Z","end":"2026-04-12T08:51:03.051529Z","steps":["trace[1369465619] 'agreement among raft nodes before linearized reading' (duration: 217.662096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:51:03.31149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.332793ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-12T08:51:03.31193Z","caller":"traceutil/trace.go:171","msg":"trace[1181479828] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"186.498227ms","start":"2026-04-12T08:51:03.125412Z","end":"2026-04-12T08:51:03.31191Z","steps":["trace[1181479828] 'process raft request' (duration: 186.188051ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T08:52:07.537427Z","caller":"traceutil/trace.go:171","msg":"trace[1587398836] linearizableReadLoop","detail":"{readStateIndex:2578; appliedIndex:2577; }","duration":"145.097086ms","start":"2026-04-12T08:52:07.392306Z","end":"2026-04-12T08:52:07.537403Z","steps":["trace[1587398836] 'read index received' (duration: 144.915452ms)","trace[1587398836] 'applied index is now lower than readState.Index' (duration: 180.774µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T08:52:07.537502Z","caller":"traceutil/trace.go:171","msg":"trace[1969243634] transaction","detail":"{read_only:false; response_revision:2485; number_of_response:1; }","duration":"147.440512ms","start":"2026-04-12T08:52:07.390013Z","end":"2026-04-12T08:52:07.537454Z","steps":["trace[1969243634] 'process raft request' (duration: 147.223137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:52:07.5377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.373031ms","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-12T08:52:07.537742Z","caller":"traceutil/trace.go:171","msg":"trace[2005986386] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2485; }","duration":"145.456093ms","start":"2026-04-12T08:52:07.392272Z","end":"2026-04-12T08:52:07.537728Z","steps":["trace[2005986386] 'agreement among raft nodes before linearized reading' (duration: 145.219788ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T08:52:07.540211Z","caller":"traceutil/trace.go:171","msg":"trace[1155841397] transaction","detail":"{read_only:false; response_revision:2486; number_of_response:1; }","duration":"146.637386ms","start":"2026-04-12T08:52:07.393563Z","end":"2026-04-12T08:52:07.5402Z","steps":["trace[1155841397] 'process raft request' (duration: 146.583955ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T08:56:25.35947Z","caller":"traceutil/trace.go:171","msg":"trace[1712394704] transaction","detail":"{read_only:false; response_revision:4448; number_of_response:1; }","duration":"168.633674ms","start":"2026-04-12T08:56:25.190794Z","end":"2026-04-12T08:56:25.359428Z","steps":["trace[1712394704] 'process raft request' (duration: 102.807986ms)","trace[1712394704] 'compare' (duration: 65.658604ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T08:56:26.500927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.520749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-04-12T08:56:26.501048Z","caller":"traceutil/trace.go:171","msg":"trace[1548473761] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4513; }","duration":"108.709392ms","start":"2026-04-12T08:56:26.392312Z","end":"2026-04-12T08:56:26.501021Z","steps":["trace[1548473761] 'range keys from in-memory index tree' (duration: 108.312125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:56:28.142159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.697905ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-12T08:56:28.142264Z","caller":"traceutil/trace.go:171","msg":"trace[1792115937] linearizableReadLoop","detail":"{readStateIndex:4712; appliedIndex:4711; }","duration":"142.733843ms","start":"2026-04-12T08:56:27.999513Z","end":"2026-04-12T08:56:28.142247Z","steps":["trace[1792115937] 'read index received' (duration: 11.945435ms)","trace[1792115937] 'applied index is now lower than readState.Index' (duration: 130.786748ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T08:56:28.142338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.825004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T08:56:28.142363Z","caller":"traceutil/trace.go:171","msg":"trace[777485708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4536; }","duration":"142.890226ms","start":"2026-04-12T08:56:27.999463Z","end":"2026-04-12T08:56:28.142354Z","steps":["trace[777485708] 'agreement among raft nodes before linearized reading' (duration: 142.828305ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T08:56:33.107703Z","caller":"traceutil/trace.go:171","msg":"trace[977255952] linearizableReadLoop","detail":"{readStateIndex:4835; appliedIndex:4833; }","duration":"227.551534ms","start":"2026-04-12T08:56:32.880128Z","end":"2026-04-12T08:56:33.107679Z","steps":["trace[977255952] 'read index received' (duration: 33.572361ms)","trace[977255952] 'applied index is now lower than readState.Index' (duration: 193.978363ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T08:56:33.107885Z","caller":"traceutil/trace.go:171","msg":"trace[781730445] transaction","detail":"{read_only:false; response_revision:4658; number_of_response:1; }","duration":"271.391688ms","start":"2026-04-12T08:56:32.836447Z","end":"2026-04-12T08:56:33.107839Z","steps":["trace[781730445] 'process raft request' (duration: 271.05573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:56:33.108121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.976362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-12T08:56:33.108162Z","caller":"traceutil/trace.go:171","msg":"trace[796217321] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4658; }","duration":"228.055484ms","start":"2026-04-12T08:56:32.880094Z","end":"2026-04-12T08:56:33.108149Z","steps":["trace[796217321] 'agreement among raft nodes before linearized reading' (duration: 227.907371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:56:33.187862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.789476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-27eec30f-46c8-4121-a991-0d2f82d12b7b\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-12T08:56:33.187921Z","caller":"traceutil/trace.go:171","msg":"trace[463847521] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-27eec30f-46c8-4121-a991-0d2f82d12b7b; range_end:; response_count:1; response_revision:4662; }","duration":"168.884277ms","start":"2026-04-12T08:56:33.019021Z","end":"2026-04-12T08:56:33.187906Z","steps":["trace[463847521] 'agreement among raft nodes before linearized reading' (duration: 168.753714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:56:33.188008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.215347ms","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":"warn","ts":"2026-04-12T08:56:33.188033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.121271ms","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-12T08:56:33.188063Z","caller":"traceutil/trace.go:171","msg":"trace[1529879343] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4662; }","duration":"128.294698ms","start":"2026-04-12T08:56:33.059753Z","end":"2026-04-12T08:56:33.188048Z","steps":["trace[1529879343] 'agreement among raft nodes before linearized reading' (duration: 128.165795ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T08:56:33.188108Z","caller":"traceutil/trace.go:171","msg":"trace[63888887] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4662; }","duration":"274.180782ms","start":"2026-04-12T08:56:32.913903Z","end":"2026-04-12T08:56:33.188083Z","steps":["trace[63888887] 'agreement among raft nodes before linearized reading' (duration: 274.094111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:56:33.188032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.748303ms","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-12T08:56:33.18839Z","caller":"traceutil/trace.go:171","msg":"trace[1528917430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4662; }","duration":"209.14071ms","start":"2026-04-12T08:56:32.979224Z","end":"2026-04-12T08:56:33.188365Z","steps":["trace[1528917430] 'agreement among raft nodes before linearized reading' (duration: 208.667131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:56:33.27716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.968592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-bf62774c-1e00-4e16-b940-16f6b7d1b8ac\" ","response":"range_response_count:1 size:1435"} {"level":"warn","ts":"2026-04-12T08:56:33.277213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.052714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16231"} {"level":"info","ts":"2026-04-12T08:56:33.277255Z","caller":"traceutil/trace.go:171","msg":"trace[859613249] range","detail":"{range_begin:/registry/persistentvolumes/pvc-bf62774c-1e00-4e16-b940-16f6b7d1b8ac; range_end:; response_count:1; response_revision:4664; }","duration":"158.099125ms","start":"2026-04-12T08:56:33.119125Z","end":"2026-04-12T08:56:33.277224Z","steps":["trace[859613249] 'agreement among raft nodes before linearized reading' (duration: 157.795329ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T08:56:33.27733Z","caller":"traceutil/trace.go:171","msg":"trace[1593409078] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4664; }","duration":"158.214117ms","start":"2026-04-12T08:56:33.119091Z","end":"2026-04-12T08:56:33.277305Z","steps":["trace[1593409078] 'agreement among raft nodes before linearized reading' (duration: 157.836459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:56:33.277809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.827465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T08:56:33.277848Z","caller":"traceutil/trace.go:171","msg":"trace[390619409] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:4667; }","duration":"138.900066ms","start":"2026-04-12T08:56:33.138939Z","end":"2026-04-12T08:56:33.277839Z","steps":["trace[390619409] 'agreement among raft nodes before linearized reading' (duration: 137.969358ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T08:56:44.420411Z","caller":"traceutil/trace.go:171","msg":"trace[1219535779] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"447.039188ms","start":"2026-04-12T08:56:43.973346Z","end":"2026-04-12T08:56:44.420385Z","steps":["trace[1219535779] 'process raft request' (duration: 446.929405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:56:44.420564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T08:56:43.973336Z","time spent":"447.14977ms","remote":"127.0.0.1:34002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8196,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T08:56:44.420755Z","caller":"traceutil/trace.go:171","msg":"trace[1613059459] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"451.165658ms","start":"2026-04-12T08:56:43.969557Z","end":"2026-04-12T08:56:44.420723Z","steps":["trace[1613059459] 'process raft request' (duration: 420.738479ms)","trace[1613059459] 'compare' (duration: 29.465201ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T08:56:44.420914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T08:56:43.969537Z","time spent":"451.295731ms","remote":"127.0.0.1:33672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1328,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T08:56:44.503181Z","caller":"traceutil/trace.go:171","msg":"trace[1144975884] linearizableReadLoop","detail":"{readStateIndex:5004; appliedIndex:5001; }","duration":"464.750677ms","start":"2026-04-12T08:56:44.038414Z","end":"2026-04-12T08:56:44.503165Z","steps":["trace[1144975884] 'read index received' (duration: 351.895624ms)","trace[1144975884] 'applied index is now lower than readState.Index' (duration: 112.854043ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T08:56:44.503258Z","caller":"traceutil/trace.go:171","msg":"trace[1401435641] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"514.259631ms","start":"2026-04-12T08:56:43.988949Z","end":"2026-04-12T08:56:44.503208Z","steps":["trace[1401435641] 'process raft request' (duration: 514.116538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:56:44.503414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.998041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-12T08:56:44.503459Z","caller":"traceutil/trace.go:171","msg":"trace[1884946853] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4821; }","duration":"465.101324ms","start":"2026-04-12T08:56:44.03835Z","end":"2026-04-12T08:56:44.503451Z","steps":["trace[1884946853] 'agreement among raft nodes before linearized reading' (duration: 464.953161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:56:44.503495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T08:56:44.038325Z","time spent":"465.162935ms","remote":"127.0.0.1:33656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-12T08:56:44.503479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T08:56:43.988937Z","time spent":"514.408685ms","remote":"127.0.0.1:33696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10663,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T08:56:44.513646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.124946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T08:56:44.513688Z","caller":"traceutil/trace.go:171","msg":"trace[172151276] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:4822; }","duration":"220.25574ms","start":"2026-04-12T08:56:44.293422Z","end":"2026-04-12T08:56:44.513677Z","steps":["trace[172151276] 'agreement among raft nodes before linearized reading' (duration: 220.034575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:56:44.513645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.597829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T08:56:44.51386Z","caller":"traceutil/trace.go:171","msg":"trace[1824050030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4822; }","duration":"244.854724ms","start":"2026-04-12T08:56:44.268945Z","end":"2026-04-12T08:56:44.513799Z","steps":["trace[1824050030] 'agreement among raft nodes before linearized reading' (duration: 244.508317ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T08:57:23.746493Z","caller":"traceutil/trace.go:171","msg":"trace[370341341] transaction","detail":"{read_only:false; response_revision:5172; number_of_response:1; }","duration":"178.293282ms","start":"2026-04-12T08:57:23.568151Z","end":"2026-04-12T08:57:23.746444Z","steps":["trace[370341341] 'process raft request' (duration: 103.954887ms)","trace[370341341] 'compare' (duration: 73.320905ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T08:57:23.746446Z","caller":"traceutil/trace.go:171","msg":"trace[1083184998] linearizableReadLoop","detail":"{readStateIndex:5365; appliedIndex:5363; }","duration":"177.534207ms","start":"2026-04-12T08:57:23.568859Z","end":"2026-04-12T08:57:23.746394Z","steps":["trace[1083184998] 'read index received' (duration: 93.861059ms)","trace[1083184998] 'applied index is now lower than readState.Index' (duration: 83.671648ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T08:57:23.746597Z","caller":"traceutil/trace.go:171","msg":"trace[1512369857] transaction","detail":"{read_only:false; response_revision:5173; number_of_response:1; }","duration":"147.357263ms","start":"2026-04-12T08:57:23.599215Z","end":"2026-04-12T08:57:23.746572Z","steps":["trace[1512369857] 'process raft request' (duration: 147.082877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:57:23.747181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.243641ms","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-12T08:57:23.747265Z","caller":"traceutil/trace.go:171","msg":"trace[819707767] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5173; }","duration":"178.411264ms","start":"2026-04-12T08:57:23.568837Z","end":"2026-04-12T08:57:23.747248Z","steps":["trace[819707767] 'agreement among raft nodes before linearized reading' (duration: 177.917944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T08:57:23.75821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.84454ms","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-12T08:57:23.758318Z","caller":"traceutil/trace.go:171","msg":"trace[879850492] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5174; }","duration":"187.979392ms","start":"2026-04-12T08:57:23.570313Z","end":"2026-04-12T08:57:23.758293Z","steps":["trace[879850492] 'agreement among raft nodes before linearized reading' (duration: 187.578994ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T08:58:24.794486Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2903} {"level":"info","ts":"2026-04-12T08:58:24.917354Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2903,"took":"120.322529ms","hash":2046298484,"current-db-size-bytes":53018624,"current-db-size":"53 MB","current-db-size-in-use-bytes":29319168,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-12T08:58:24.917454Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2046298484,"revision":2903,"compact-revision":-1} {"level":"warn","ts":"2026-04-12T09:00:48.680056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.221962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/rabbitmqclusters.rabbitmq.com\" ","response":"range_response_count:1 size:111685"} {"level":"info","ts":"2026-04-12T09:00:48.680129Z","caller":"traceutil/trace.go:171","msg":"trace[723579634] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/rabbitmqclusters.rabbitmq.com; range_end:; response_count:1; response_revision:6606; }","duration":"170.352574ms","start":"2026-04-12T09:00:48.509761Z","end":"2026-04-12T09:00:48.680113Z","steps":["trace[723579634] 'range keys from in-memory index tree' (duration: 170.065458ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T09:00:48.78584Z","caller":"traceutil/trace.go:171","msg":"trace[12171068] transaction","detail":"{read_only:false; response_revision:6607; number_of_response:1; }","duration":"207.115548ms","start":"2026-04-12T09:00:48.578705Z","end":"2026-04-12T09:00:48.78582Z","steps":["trace[12171068] 'process raft request' (duration: 206.988145ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T09:00:48.795493Z","caller":"traceutil/trace.go:171","msg":"trace[239244496] transaction","detail":"{read_only:false; response_revision:6608; number_of_response:1; }","duration":"216.159745ms","start":"2026-04-12T09:00:48.579308Z","end":"2026-04-12T09:00:48.795468Z","steps":["trace[239244496] 'process raft request' (duration: 216.018842ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T09:03:24.800056Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5625} {"level":"info","ts":"2026-04-12T09:03:24.876804Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5625,"took":"75.318212ms","hash":3422331844,"current-db-size-bytes":53018624,"current-db-size":"53 MB","current-db-size-in-use-bytes":18206720,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-12T09:03:24.876862Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3422331844,"revision":5625,"compact-revision":2903}