{"level":"warn","ts":"2026-10-06T13:03:32.269268Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-06T13:03:32.269364Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.15: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.15:2380","--initial-cluster=instance=https://162.253.55.15:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.15:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.15: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-10-06T13:03:32.269434Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-06T13:03:32.269449Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.15:2380"]} {"level":"info","ts":"2026-10-06T13:03:32.269478Z","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-10-06T13:03:32.270217Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.15:2379"]} {"level":"info","ts":"2026-10-06T13:03:32.270304Z","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.15:2380"],"listen-peer-urls":["https://162.253.55.15:2380"],"advertise-client-urls":["https://162.253.55.15:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.15:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.15: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-10-06T13:03:32.297386Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"26.780356ms"} {"level":"info","ts":"2026-10-06T13:03:32.306857Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"337baeb286292d09","cluster-id":"3cc18487e1df591c"} {"level":"info","ts":"2026-10-06T13:03:32.306941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 switched to configuration voters=()"} {"level":"info","ts":"2026-10-06T13:03:32.306964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 became follower at term 0"} {"level":"info","ts":"2026-10-06T13:03:32.306995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 337baeb286292d09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-06T13:03:32.307009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 became follower at term 1"} {"level":"info","ts":"2026-10-06T13:03:32.307043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 switched to configuration voters=(3709750799847992585)"} {"level":"warn","ts":"2026-10-06T13:03:32.319917Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-06T13:03:32.321794Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-06T13:03:32.32316Z","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-10-06T13:03:32.326091Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"337baeb286292d09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-06T13:03:32.327384Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"337baeb286292d09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-06T13:03:32.327841Z","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-10-06T13:03:32.327947Z","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-10-06T13:03:32.327959Z","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-10-06T13:03:32.328748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 switched to configuration voters=(3709750799847992585)"} {"level":"info","ts":"2026-10-06T13:03:32.328867Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3cc18487e1df591c","local-member-id":"337baeb286292d09","added-peer-id":"337baeb286292d09","added-peer-peer-urls":["https://162.253.55.15:2380"]} {"level":"info","ts":"2026-10-06T13:03:32.329666Z","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-10-06T13:03:32.329885Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.15:2380"} {"level":"info","ts":"2026-10-06T13:03:32.32994Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"337baeb286292d09","initial-advertise-peer-urls":["https://162.253.55.15:2380"],"listen-peer-urls":["https://162.253.55.15:2380"],"advertise-client-urls":["https://162.253.55.15:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.15:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-06T13:03:32.330006Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-06T13:03:32.329942Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.15:2380"} {"level":"info","ts":"2026-10-06T13:03:33.107547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 is starting a new election at term 1"} {"level":"info","ts":"2026-10-06T13:03:33.107603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-06T13:03:33.107638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 received MsgPreVoteResp from 337baeb286292d09 at term 1"} {"level":"info","ts":"2026-10-06T13:03:33.107656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 became candidate at term 2"} {"level":"info","ts":"2026-10-06T13:03:33.107666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 received MsgVoteResp from 337baeb286292d09 at term 2"} {"level":"info","ts":"2026-10-06T13:03:33.107681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 became leader at term 2"} {"level":"info","ts":"2026-10-06T13:03:33.107694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 337baeb286292d09 elected leader 337baeb286292d09 at term 2"} {"level":"info","ts":"2026-10-06T13:03:33.108793Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"337baeb286292d09","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.15:2379]}","request-path":"/0/members/337baeb286292d09/attributes","cluster-id":"3cc18487e1df591c","publish-timeout":"7s"} {"level":"info","ts":"2026-10-06T13:03:33.108845Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-06T13:03:33.108879Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-06T13:03:33.109044Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T13:03:33.109254Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-06T13:03:33.109282Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-06T13:03:33.110489Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3cc18487e1df591c","local-member-id":"337baeb286292d09","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T13:03:33.110605Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.15:2379"} {"level":"info","ts":"2026-10-06T13:03:33.110627Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T13:03:33.110665Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T13:03:33.111909Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-06T13:04:13.669379Z","caller":"traceutil/trace.go:171","msg":"trace[571864275] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"118.747483ms","start":"2026-10-06T13:04:13.550607Z","end":"2026-10-06T13:04:13.669354Z","steps":["trace[571864275] 'process raft request' (duration: 118.59218ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:04:13.790109Z","caller":"traceutil/trace.go:171","msg":"trace[2098847607] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"209.579063ms","start":"2026-10-06T13:04:13.580482Z","end":"2026-10-06T13:04:13.790061Z","steps":["trace[2098847607] 'process raft request' (duration: 208.798147ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:04:14.020253Z","caller":"traceutil/trace.go:171","msg":"trace[1074323423] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"141.548791ms","start":"2026-10-06T13:04:13.878658Z","end":"2026-10-06T13:04:14.020206Z","steps":["trace[1074323423] 'process raft request' (duration: 141.144331ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:04:14.492427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.080041ms","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-10-06T13:04:14.492545Z","caller":"traceutil/trace.go:171","msg":"trace[1758951564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"318.247374ms","start":"2026-10-06T13:04:14.174277Z","end":"2026-10-06T13:04:14.492524Z","steps":["trace[1758951564] 'range keys from in-memory index tree' (duration: 317.879046ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:04:14.492601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T13:04:14.174257Z","time spent":"318.335286ms","remote":"127.0.0.1:50282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-06T13:04:30.227042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.586641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-06T13:04:30.227108Z","caller":"traceutil/trace.go:171","msg":"trace[65799844] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:788; }","duration":"119.697684ms","start":"2026-10-06T13:04:30.107397Z","end":"2026-10-06T13:04:30.227095Z","steps":["trace[65799844] 'range keys from in-memory index tree' (duration: 119.398878ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:05:25.069294Z","caller":"traceutil/trace.go:171","msg":"trace[1799578484] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"130.617074ms","start":"2026-10-06T13:05:24.938626Z","end":"2026-10-06T13:05:25.069243Z","steps":["trace[1799578484] 'process raft request' (duration: 130.301269ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:05:51.819455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.783422ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-06T13:05:51.819749Z","caller":"traceutil/trace.go:171","msg":"trace[553982579] linearizableReadLoop","detail":"{readStateIndex:1954; appliedIndex:1953; }","duration":"226.49783ms","start":"2026-10-06T13:05:51.593218Z","end":"2026-10-06T13:05:51.819716Z","steps":["trace[553982579] 'read index received' (duration: 73.232121ms)","trace[553982579] 'applied index is now lower than readState.Index' (duration: 153.262719ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T13:05:51.820024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.797594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T13:05:51.820088Z","caller":"traceutil/trace.go:171","msg":"trace[1206312777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1882; }","duration":"226.866515ms","start":"2026-10-06T13:05:51.593202Z","end":"2026-10-06T13:05:51.820069Z","steps":["trace[1206312777] 'agreement among raft nodes before linearized reading' (duration: 226.634482ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:06:04.076974Z","caller":"traceutil/trace.go:171","msg":"trace[832786424] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"129.138439ms","start":"2026-10-06T13:06:03.947811Z","end":"2026-10-06T13:06:04.076949Z","steps":["trace[832786424] 'process raft request' (duration: 128.991617ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:06:27.444078Z","caller":"traceutil/trace.go:171","msg":"trace[1725101710] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2201; }","duration":"205.28ms","start":"2026-10-06T13:06:27.238777Z","end":"2026-10-06T13:06:27.444057Z","steps":["trace[1725101710] 'read index received' (duration: 205.201649ms)","trace[1725101710] 'applied index is now lower than readState.Index' (duration: 77.191µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T13:06:27.444167Z","caller":"traceutil/trace.go:171","msg":"trace[1757426180] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"291.563931ms","start":"2026-10-06T13:06:27.152554Z","end":"2026-10-06T13:06:27.444118Z","steps":["trace[1757426180] 'process raft request' (duration: 291.342688ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:06:27.444379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.570054ms","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-10-06T13:06:27.444455Z","caller":"traceutil/trace.go:171","msg":"trace[1661377617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2122; }","duration":"205.694606ms","start":"2026-10-06T13:06:27.238742Z","end":"2026-10-06T13:06:27.444436Z","steps":["trace[1661377617] 'agreement among raft nodes before linearized reading' (duration: 205.410821ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:06:27.559687Z","caller":"traceutil/trace.go:171","msg":"trace[798941695] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"177.734428ms","start":"2026-10-06T13:06:27.381924Z","end":"2026-10-06T13:06:27.559658Z","steps":["trace[798941695] 'process raft request' (duration: 118.768566ms)","trace[798941695] 'compare' (duration: 58.649507ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T13:06:27.55976Z","caller":"traceutil/trace.go:171","msg":"trace[951682773] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"110.278029ms","start":"2026-10-06T13:06:27.449452Z","end":"2026-10-06T13:06:27.55973Z","steps":["trace[951682773] 'process raft request' (duration: 110.139477ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:06:27.559834Z","caller":"traceutil/trace.go:171","msg":"trace[1266299920] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2202; }","duration":"115.6402ms","start":"2026-10-06T13:06:27.444148Z","end":"2026-10-06T13:06:27.559788Z","steps":["trace[1266299920] 'read index received' (duration: 56.558806ms)","trace[1266299920] 'applied index is now lower than readState.Index' (duration: 59.076064ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T13:06:27.560102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.072082ms","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-10-06T13:06:27.560162Z","caller":"traceutil/trace.go:171","msg":"trace[1926986764] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2124; }","duration":"184.176114ms","start":"2026-10-06T13:06:27.375969Z","end":"2026-10-06T13:06:27.560145Z","steps":["trace[1926986764] 'agreement among raft nodes before linearized reading' (duration: 184.015932ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:07:38.200351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.789333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T13:07:38.200412Z","caller":"traceutil/trace.go:171","msg":"trace[1837545030] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2607; }","duration":"110.900138ms","start":"2026-10-06T13:07:38.0895Z","end":"2026-10-06T13:07:38.2004Z","steps":["trace[1837545030] 'count revisions from in-memory index tree' (duration: 110.710161ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:08:10.244596Z","caller":"traceutil/trace.go:171","msg":"trace[1608217830] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"130.063328ms","start":"2026-10-06T13:08:10.114502Z","end":"2026-10-06T13:08:10.244565Z","steps":["trace[1608217830] 'process raft request' (duration: 129.819232ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:08:10.262318Z","caller":"traceutil/trace.go:171","msg":"trace[302009445] linearizableReadLoop","detail":"{readStateIndex:2886; appliedIndex:2884; }","duration":"143.294352ms","start":"2026-10-06T13:08:10.119009Z","end":"2026-10-06T13:08:10.262303Z","steps":["trace[302009445] 'read index received' (duration: 125.282878ms)","trace[302009445] 'applied index is now lower than readState.Index' (duration: 18.010084ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T13:08:10.262641Z","caller":"traceutil/trace.go:171","msg":"trace[162423600] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"147.17959ms","start":"2026-10-06T13:08:10.115423Z","end":"2026-10-06T13:08:10.262603Z","steps":["trace[162423600] 'process raft request' (duration: 146.801749ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:08:10.263108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.486107ms","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-10-06T13:08:10.263158Z","caller":"traceutil/trace.go:171","msg":"trace[1197140068] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2783; }","duration":"144.242866ms","start":"2026-10-06T13:08:10.118902Z","end":"2026-10-06T13:08:10.263145Z","steps":["trace[1197140068] 'agreement among raft nodes before linearized reading' (duration: 143.458927ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:12:48.169522Z","caller":"traceutil/trace.go:171","msg":"trace[639886469] transaction","detail":"{read_only:false; response_revision:5003; number_of_response:1; }","duration":"133.408456ms","start":"2026-10-06T13:12:48.036088Z","end":"2026-10-06T13:12:48.169496Z","steps":["trace[639886469] 'process raft request' (duration: 133.275904ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:12:48.382341Z","caller":"traceutil/trace.go:171","msg":"trace[1379489739] linearizableReadLoop","detail":"{readStateIndex:5192; appliedIndex:5190; }","duration":"218.808922ms","start":"2026-10-06T13:12:48.1635Z","end":"2026-10-06T13:12:48.382309Z","steps":["trace[1379489739] 'read index received' (duration: 5.851181ms)","trace[1379489739] 'applied index is now lower than readState.Index' (duration: 212.956221ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T13:12:48.38259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.080676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-06T13:12:48.382626Z","caller":"traceutil/trace.go:171","msg":"trace[1922740425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5004; }","duration":"219.167498ms","start":"2026-10-06T13:12:48.163449Z","end":"2026-10-06T13:12:48.382617Z","steps":["trace[1922740425] 'agreement among raft nodes before linearized reading' (duration: 219.001595ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:12:48.38258Z","caller":"traceutil/trace.go:171","msg":"trace[1170692734] transaction","detail":"{read_only:false; response_revision:5004; number_of_response:1; }","duration":"334.800637ms","start":"2026-10-06T13:12:48.047612Z","end":"2026-10-06T13:12:48.382413Z","steps":["trace[1170692734] 'process raft request' (duration: 300.82805ms)","trace[1170692734] 'compare' (duration: 33.495979ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T13:12:48.382847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.993976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T13:12:48.382956Z","caller":"traceutil/trace.go:171","msg":"trace[1699527181] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:5004; }","duration":"215.147819ms","start":"2026-10-06T13:12:48.167785Z","end":"2026-10-06T13:12:48.382933Z","steps":["trace[1699527181] 'agreement among raft nodes before linearized reading' (duration: 214.945026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:12:48.38303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.216364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9690aa51-5551-4337-9525-48498536fb5f\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-06T13:12:48.383072Z","caller":"traceutil/trace.go:171","msg":"trace[1105876275] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9690aa51-5551-4337-9525-48498536fb5f; range_end:; response_count:1; response_revision:5004; }","duration":"129.284464ms","start":"2026-10-06T13:12:48.253775Z","end":"2026-10-06T13:12:48.38306Z","steps":["trace[1105876275] 'agreement among raft nodes before linearized reading' (duration: 129.165782ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:12:48.383571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T13:12:48.047578Z","time spent":"335.180793ms","remote":"127.0.0.1:50218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11832,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T13:13:02.227881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.492626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-06T13:13:02.227997Z","caller":"traceutil/trace.go:171","msg":"trace[314184623] linearizableReadLoop","detail":"{readStateIndex:5374; appliedIndex:5373; }","duration":"330.56435ms","start":"2026-10-06T13:13:01.897411Z","end":"2026-10-06T13:13:02.227975Z","steps":["trace[314184623] 'read index received' (duration: 105.84144ms)","trace[314184623] 'applied index is now lower than readState.Index' (duration: 224.71782ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T13:13:02.228221Z","caller":"traceutil/trace.go:171","msg":"trace[973135173] transaction","detail":"{read_only:false; response_revision:5181; number_of_response:1; }","duration":"338.985264ms","start":"2026-10-06T13:13:01.889184Z","end":"2026-10-06T13:13:02.228169Z","steps":["trace[973135173] 'process raft request' (duration: 114.107541ms)","trace[973135173] 'compare' (duration: 224.219532ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T13:13:02.228333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T13:13:01.889167Z","time spent":"339.111236ms","remote":"127.0.0.1:50282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T13:13:02.228492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.161796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-06T13:13:02.22855Z","caller":"traceutil/trace.go:171","msg":"trace[2086572766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5181; }","duration":"286.252468ms","start":"2026-10-06T13:13:01.942282Z","end":"2026-10-06T13:13:02.228534Z","steps":["trace[2086572766] 'agreement among raft nodes before linearized reading' (duration: 286.073605ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:13:02.228571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.323723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-10-06T13:13:02.228681Z","caller":"traceutil/trace.go:171","msg":"trace[105047547] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5181; }","duration":"281.491605ms","start":"2026-10-06T13:13:01.947169Z","end":"2026-10-06T13:13:02.228661Z","steps":["trace[105047547] 'agreement among raft nodes before linearized reading' (duration: 281.19705ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:13:02.22874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.41536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-06T13:13:02.22878Z","caller":"traceutil/trace.go:171","msg":"trace[844767679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5181; }","duration":"286.485172ms","start":"2026-10-06T13:13:01.942282Z","end":"2026-10-06T13:13:02.228767Z","steps":["trace[844767679] 'agreement among raft nodes before linearized reading' (duration: 286.37672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:13:02.229145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.70271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-06T13:13:02.229472Z","caller":"traceutil/trace.go:171","msg":"trace[552892319] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:5181; }","duration":"332.068356ms","start":"2026-10-06T13:13:01.897385Z","end":"2026-10-06T13:13:02.229453Z","steps":["trace[552892319] 'agreement among raft nodes before linearized reading' (duration: 330.791153ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:13:02.229707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T13:13:01.897373Z","time spent":"332.31072ms","remote":"127.0.0.1:50498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":3,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-10-06T13:13:02.433588Z","caller":"traceutil/trace.go:171","msg":"trace[684702755] transaction","detail":"{read_only:false; response_revision:5182; number_of_response:1; }","duration":"198.722233ms","start":"2026-10-06T13:13:02.234837Z","end":"2026-10-06T13:13:02.43356Z","steps":["trace[684702755] 'process raft request' (duration: 104.668777ms)","trace[684702755] 'compare' (duration: 93.935984ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T13:13:02.484834Z","caller":"traceutil/trace.go:171","msg":"trace[598648842] transaction","detail":"{read_only:false; response_revision:5183; number_of_response:1; }","duration":"249.907082ms","start":"2026-10-06T13:13:02.234903Z","end":"2026-10-06T13:13:02.484811Z","steps":["trace[598648842] 'process raft request' (duration: 249.616268ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:13:02.485043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.217187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1162"} {"level":"info","ts":"2026-10-06T13:13:02.485098Z","caller":"traceutil/trace.go:171","msg":"trace[198620999] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:5184; }","duration":"240.372369ms","start":"2026-10-06T13:13:02.244713Z","end":"2026-10-06T13:13:02.485085Z","steps":["trace[198620999] 'agreement among raft nodes before linearized reading' (duration: 240.165516ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:13:02.484834Z","caller":"traceutil/trace.go:171","msg":"trace[1460350998] linearizableReadLoop","detail":"{readStateIndex:5377; appliedIndex:5374; }","duration":"240.040204ms","start":"2026-10-06T13:13:02.244771Z","end":"2026-10-06T13:13:02.484811Z","steps":["trace[1460350998] 'read index received' (duration: 94.745648ms)","trace[1460350998] 'applied index is now lower than readState.Index' (duration: 145.293556ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T13:13:02.485346Z","caller":"traceutil/trace.go:171","msg":"trace[1419488711] transaction","detail":"{read_only:false; response_revision:5184; number_of_response:1; }","duration":"240.521483ms","start":"2026-10-06T13:13:02.244711Z","end":"2026-10-06T13:13:02.485233Z","steps":["trace[1419488711] 'process raft request' (duration: 239.977773ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:13:02.661885Z","caller":"traceutil/trace.go:171","msg":"trace[1132838239] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"169.335399ms","start":"2026-10-06T13:13:02.492519Z","end":"2026-10-06T13:13:02.661854Z","steps":["trace[1132838239] 'process raft request' (duration: 137.518002ms)","trace[1132838239] 'compare' (duration: 31.553682ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T13:13:02.675654Z","caller":"traceutil/trace.go:171","msg":"trace[987766119] transaction","detail":"{read_only:false; response_revision:5187; number_of_response:1; }","duration":"182.598156ms","start":"2026-10-06T13:13:02.493027Z","end":"2026-10-06T13:13:02.675625Z","steps":["trace[987766119] 'process raft request' (duration: 182.410293ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:13:02.675642Z","caller":"traceutil/trace.go:171","msg":"trace[67744612] transaction","detail":"{read_only:false; response_revision:5186; number_of_response:1; }","duration":"182.651248ms","start":"2026-10-06T13:13:02.49295Z","end":"2026-10-06T13:13:02.675602Z","steps":["trace[67744612] 'process raft request' (duration: 182.231111ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:13:02.675915Z","caller":"traceutil/trace.go:171","msg":"trace[1614865654] linearizableReadLoop","detail":"{readStateIndex:5382; appliedIndex:5377; }","duration":"178.277333ms","start":"2026-10-06T13:13:02.497601Z","end":"2026-10-06T13:13:02.675879Z","steps":["trace[1614865654] 'read index received' (duration: 132.446387ms)","trace[1614865654] 'applied index is now lower than readState.Index' (duration: 45.828946ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T13:13:02.675965Z","caller":"traceutil/trace.go:171","msg":"trace[1664792492] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"181.991356ms","start":"2026-10-06T13:13:02.493952Z","end":"2026-10-06T13:13:02.675943Z","steps":["trace[1664792492] 'process raft request' (duration: 181.576939ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:13:02.676056Z","caller":"traceutil/trace.go:171","msg":"trace[600359990] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"181.909275ms","start":"2026-10-06T13:13:02.494122Z","end":"2026-10-06T13:13:02.676031Z","steps":["trace[600359990] 'process raft request' (duration: 181.60477ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:13:02.67617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.555228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-10-06T13:13:02.676241Z","caller":"traceutil/trace.go:171","msg":"trace[1045994336] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:5189; }","duration":"178.676659ms","start":"2026-10-06T13:13:02.497553Z","end":"2026-10-06T13:13:02.67623Z","steps":["trace[1045994336] 'agreement among raft nodes before linearized reading' (duration: 178.471526ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:13:33.141783Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2904} {"level":"info","ts":"2026-10-06T13:13:33.219468Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2904,"took":"76.381627ms","hash":486720633,"current-db-size-bytes":51744768,"current-db-size":"52 MB","current-db-size-in-use-bytes":27582464,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-06T13:13:33.21953Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":486720633,"revision":2904,"compact-revision":-1} {"level":"info","ts":"2026-10-06T13:18:33.14799Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5393} {"level":"info","ts":"2026-10-06T13:18:33.241112Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5393,"took":"92.129443ms","hash":3141436730,"current-db-size-bytes":51744768,"current-db-size":"52 MB","current-db-size-in-use-bytes":18649088,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-06T13:18:33.241185Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3141436730,"revision":5393,"compact-revision":2904}