{"level":"warn","ts":"2026-10-13T12:31:39.154621Z","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-13T12:31:39.154722Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.22: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.22:2380","--initial-cluster=instance=https://162.253.55.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.22: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-13T12:31:39.154926Z","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-13T12:31:39.154948Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.22:2380"]} {"level":"info","ts":"2026-10-13T12:31:39.154973Z","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-13T12:31:39.155853Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"]} {"level":"info","ts":"2026-10-13T12:31:39.155945Z","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.22:2380"],"listen-peer-urls":["https://162.253.55.22:2380"],"advertise-client-urls":["https://162.253.55.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.22: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-13T12:31:39.211299Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"55.094464ms"} {"level":"info","ts":"2026-10-13T12:31:39.22184Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8608f4af3693a53b","cluster-id":"c541111188849f0e"} {"level":"info","ts":"2026-10-13T12:31:39.221914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=()"} {"level":"info","ts":"2026-10-13T12:31:39.221933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became follower at term 0"} {"level":"info","ts":"2026-10-13T12:31:39.221951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8608f4af3693a53b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-13T12:31:39.221961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became follower at term 1"} {"level":"info","ts":"2026-10-13T12:31:39.222019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=(9658238434268128571)"} {"level":"warn","ts":"2026-10-13T12:31:39.232526Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-13T12:31:39.234653Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-13T12:31:39.235991Z","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-13T12:31:39.238136Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8608f4af3693a53b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-13T12:31:39.239126Z","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-13T12:31:39.243143Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8608f4af3693a53b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-13T12:31:39.243214Z","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-13T12:31:39.243355Z","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-13T12:31:39.244158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=(9658238434268128571)"} {"level":"info","ts":"2026-10-13T12:31:39.244278Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c541111188849f0e","local-member-id":"8608f4af3693a53b","added-peer-id":"8608f4af3693a53b","added-peer-peer-urls":["https://162.253.55.22:2380"]} {"level":"info","ts":"2026-10-13T12:31:39.247038Z","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-13T12:31:39.247167Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.22:2380"} {"level":"info","ts":"2026-10-13T12:31:39.247201Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.22:2380"} {"level":"info","ts":"2026-10-13T12:31:39.24723Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8608f4af3693a53b","initial-advertise-peer-urls":["https://162.253.55.22:2380"],"listen-peer-urls":["https://162.253.55.22:2380"],"advertise-client-urls":["https://162.253.55.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-13T12:31:39.247268Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-13T12:31:39.322829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b is starting a new election at term 1"} {"level":"info","ts":"2026-10-13T12:31:39.322908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-13T12:31:39.322956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b received MsgPreVoteResp from 8608f4af3693a53b at term 1"} {"level":"info","ts":"2026-10-13T12:31:39.322992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became candidate at term 2"} {"level":"info","ts":"2026-10-13T12:31:39.323019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b received MsgVoteResp from 8608f4af3693a53b at term 2"} {"level":"info","ts":"2026-10-13T12:31:39.323035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became leader at term 2"} {"level":"info","ts":"2026-10-13T12:31:39.323048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8608f4af3693a53b elected leader 8608f4af3693a53b at term 2"} {"level":"info","ts":"2026-10-13T12:31:39.324057Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8608f4af3693a53b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.22:2379]}","request-path":"/0/members/8608f4af3693a53b/attributes","cluster-id":"c541111188849f0e","publish-timeout":"7s"} {"level":"info","ts":"2026-10-13T12:31:39.324055Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T12:31:39.324395Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T12:31:39.324626Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T12:31:39.326336Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-13T12:31:39.326363Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-13T12:31:39.326621Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.22:2379"} {"level":"info","ts":"2026-10-13T12:31:39.326637Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-13T12:31:39.329523Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c541111188849f0e","local-member-id":"8608f4af3693a53b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T12:31:39.329628Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T12:31:39.329656Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-10-13T12:31:42.858715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.525598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-10-13T12:31:42.858922Z","caller":"traceutil/trace.go:171","msg":"trace[15112820] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"393.88489ms","start":"2026-10-13T12:31:42.46501Z","end":"2026-10-13T12:31:42.858895Z","steps":["trace[15112820] 'process raft request' (duration: 146.337309ms)","trace[15112820] 'compare' (duration: 246.279295ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:31:42.859062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:31:42.464969Z","time spent":"394.055293ms","remote":"127.0.0.1:39912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T12:31:43.101776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.509391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-10-13T12:31:43.101929Z","caller":"traceutil/trace.go:171","msg":"trace[938486160] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"235.948519ms","start":"2026-10-13T12:31:42.865956Z","end":"2026-10-13T12:31:43.101905Z","steps":["trace[938486160] 'process raft request' (duration: 99.221355ms)","trace[938486160] 'compare' (duration: 136.343769ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:32:31.02889Z","caller":"traceutil/trace.go:171","msg":"trace[1736482572] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"239.119949ms","start":"2026-10-13T12:32:30.789728Z","end":"2026-10-13T12:32:31.028848Z","steps":["trace[1736482572] 'process raft request' (duration: 237.929826ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:32:31.038467Z","caller":"traceutil/trace.go:171","msg":"trace[345271603] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:635; }","duration":"147.887219ms","start":"2026-10-13T12:32:30.89054Z","end":"2026-10-13T12:32:31.038427Z","steps":["trace[345271603] 'read index received' (duration: 136.99984ms)","trace[345271603] 'applied index is now lower than readState.Index' (duration: 10.885909ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:32:31.038629Z","caller":"traceutil/trace.go:171","msg":"trace[466867624] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"200.170022ms","start":"2026-10-13T12:32:30.838411Z","end":"2026-10-13T12:32:31.038581Z","steps":["trace[466867624] 'process raft request' (duration: 199.402924ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:32:31.03872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.103242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T12:32:31.038777Z","caller":"traceutil/trace.go:171","msg":"trace[164196253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"148.271003ms","start":"2026-10-13T12:32:30.890486Z","end":"2026-10-13T12:32:31.038757Z","steps":["trace[164196253] 'agreement among raft nodes before linearized reading' (duration: 148.102871ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:32:46.684349Z","caller":"traceutil/trace.go:171","msg":"trace[903394020] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"133.590979ms","start":"2026-10-13T12:32:46.550713Z","end":"2026-10-13T12:32:46.684304Z","steps":["trace[903394020] 'process raft request' (duration: 133.413367ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:34:10.058415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.979152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T12:34:10.058967Z","caller":"traceutil/trace.go:171","msg":"trace[1865739500] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1940; }","duration":"324.792808ms","start":"2026-10-13T12:34:09.734056Z","end":"2026-10-13T12:34:10.058849Z","steps":["trace[1865739500] 'read index received' (duration: 52.002412ms)","trace[1865739500] 'applied index is now lower than readState.Index' (duration: 272.789056ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:34:10.059145Z","caller":"traceutil/trace.go:171","msg":"trace[1436443075] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"364.160779ms","start":"2026-10-13T12:34:09.694969Z","end":"2026-10-13T12:34:10.05913Z","steps":["trace[1436443075] 'process raft request' (duration: 91.161156ms)","trace[1436443075] 'compare' (duration: 271.875719ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:34:10.059264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:34:09.694946Z","time spent":"364.243272ms","remote":"127.0.0.1:39848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T12:34:10.059339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.263852ms","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-13T12:34:10.059429Z","caller":"traceutil/trace.go:171","msg":"trace[1489761047] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1865; }","duration":"325.395956ms","start":"2026-10-13T12:34:09.73401Z","end":"2026-10-13T12:34:10.059406Z","steps":["trace[1489761047] 'agreement among raft nodes before linearized reading' (duration: 325.141308ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:34:10.059476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:34:09.733993Z","time spent":"325.471738ms","remote":"127.0.0.1:39848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-13T12:34:10.063785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.083085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T12:34:10.063874Z","caller":"traceutil/trace.go:171","msg":"trace[711372221] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1866; }","duration":"213.216769ms","start":"2026-10-13T12:34:09.850635Z","end":"2026-10-13T12:34:10.063852Z","steps":["trace[711372221] 'agreement among raft nodes before linearized reading' (duration: 213.075524ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:34:10.063839Z","caller":"traceutil/trace.go:171","msg":"trace[13898366] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"130.9977ms","start":"2026-10-13T12:34:09.932804Z","end":"2026-10-13T12:34:10.063802Z","steps":["trace[13898366] 'process raft request' (duration: 130.721912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:34:10.063898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.745716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T12:34:10.064044Z","caller":"traceutil/trace.go:171","msg":"trace[2079249111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1866; }","duration":"173.903451ms","start":"2026-10-13T12:34:09.89011Z","end":"2026-10-13T12:34:10.064014Z","steps":["trace[2079249111] 'agreement among raft nodes before linearized reading' (duration: 173.737336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:34:10.380252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.262715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-13T12:34:10.380328Z","caller":"traceutil/trace.go:171","msg":"trace[590766304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1867; }","duration":"208.385289ms","start":"2026-10-13T12:34:10.171924Z","end":"2026-10-13T12:34:10.380309Z","steps":["trace[590766304] 'range keys from in-memory index tree' (duration: 208.10931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:34:10.380578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.992731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-13T12:34:10.380663Z","caller":"traceutil/trace.go:171","msg":"trace[46910481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1867; }","duration":"213.576268ms","start":"2026-10-13T12:34:10.167073Z","end":"2026-10-13T12:34:10.380649Z","steps":["trace[46910481] 'range keys from in-memory index tree' (duration: 212.824715ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:34:26.032405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.61259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T12:34:26.032499Z","caller":"traceutil/trace.go:171","msg":"trace[1703063919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1998; }","duration":"141.774794ms","start":"2026-10-13T12:34:25.890705Z","end":"2026-10-13T12:34:26.03248Z","steps":["trace[1703063919] 'range keys from in-memory index tree' (duration: 141.530207ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:34:44.861372Z","caller":"traceutil/trace.go:171","msg":"trace[598289552] linearizableReadLoop","detail":"{readStateIndex:2189; appliedIndex:2188; }","duration":"265.011349ms","start":"2026-10-13T12:34:44.596335Z","end":"2026-10-13T12:34:44.861347Z","steps":["trace[598289552] 'read index received' (duration: 187.561071ms)","trace[598289552] 'applied index is now lower than readState.Index' (duration: 77.449118ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:34:44.861822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.881539ms","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-13T12:34:44.86188Z","caller":"traceutil/trace.go:171","msg":"trace[2137210782] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2105; }","duration":"258.975581ms","start":"2026-10-13T12:34:44.602887Z","end":"2026-10-13T12:34:44.861863Z","steps":["trace[2137210782] 'agreement among raft nodes before linearized reading' (duration: 258.798946ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:34:44.861849Z","caller":"traceutil/trace.go:171","msg":"trace[646126335] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"267.170697ms","start":"2026-10-13T12:34:44.594653Z","end":"2026-10-13T12:34:44.861824Z","steps":["trace[646126335] 'process raft request' (duration: 189.243256ms)","trace[646126335] 'compare' (duration: 77.12087ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:34:44.861914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.561645ms","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-10-13T12:34:44.861976Z","caller":"traceutil/trace.go:171","msg":"trace[1926203305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2105; }","duration":"265.672388ms","start":"2026-10-13T12:34:44.596285Z","end":"2026-10-13T12:34:44.861957Z","steps":["trace[1926203305] 'agreement among raft nodes before linearized reading' (duration: 265.37378ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:34:44.862403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.28267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-13T12:34:44.862567Z","caller":"traceutil/trace.go:171","msg":"trace[465641521] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2105; }","duration":"239.464864ms","start":"2026-10-13T12:34:44.623079Z","end":"2026-10-13T12:34:44.862544Z","steps":["trace[465641521] 'agreement among raft nodes before linearized reading' (duration: 238.734185ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:35:40.372501Z","caller":"traceutil/trace.go:171","msg":"trace[1190312245] transaction","detail":"{read_only:false; response_revision:2510; number_of_response:1; }","duration":"193.199572ms","start":"2026-10-13T12:35:40.179277Z","end":"2026-10-13T12:35:40.372476Z","steps":["trace[1190312245] 'process raft request' (duration: 193.026709ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:36:12.923946Z","caller":"traceutil/trace.go:171","msg":"trace[1244093654] transaction","detail":"{read_only:false; response_revision:2689; number_of_response:1; }","duration":"168.309306ms","start":"2026-10-13T12:36:12.755612Z","end":"2026-10-13T12:36:12.923921Z","steps":["trace[1244093654] 'process raft request' (duration: 168.125632ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:40:52.548223Z","caller":"traceutil/trace.go:171","msg":"trace[74265531] linearizableReadLoop","detail":"{readStateIndex:5111; appliedIndex:5110; }","duration":"129.375436ms","start":"2026-10-13T12:40:52.418783Z","end":"2026-10-13T12:40:52.548158Z","steps":["trace[74265531] 'read index received' (duration: 128.9226ms)","trace[74265531] 'applied index is now lower than readState.Index' (duration: 451.435µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:40:52.548457Z","caller":"traceutil/trace.go:171","msg":"trace[566982921] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"171.497728ms","start":"2026-10-13T12:40:52.376856Z","end":"2026-10-13T12:40:52.548353Z","steps":["trace[566982921] 'process raft request' (duration: 170.926561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:40:52.548995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.177836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-746b3fd7-1b29-4b93-aa80-834e6b0de95f\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-13T12:40:52.549098Z","caller":"traceutil/trace.go:171","msg":"trace[394877666] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-746b3fd7-1b29-4b93-aa80-834e6b0de95f; range_end:; response_count:1; response_revision:4921; }","duration":"130.369709ms","start":"2026-10-13T12:40:52.418701Z","end":"2026-10-13T12:40:52.54907Z","steps":["trace[394877666] 'agreement among raft nodes before linearized reading' (duration: 129.861862ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:40:52.856111Z","caller":"traceutil/trace.go:171","msg":"trace[21875084] linearizableReadLoop","detail":"{readStateIndex:5117; appliedIndex:5116; }","duration":"143.825982ms","start":"2026-10-13T12:40:52.712186Z","end":"2026-10-13T12:40:52.856012Z","steps":["trace[21875084] 'read index received' (duration: 117.352361ms)","trace[21875084] 'applied index is now lower than readState.Index' (duration: 26.472ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:40:52.856316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.105965ms","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-10-13T12:40:52.856459Z","caller":"traceutil/trace.go:171","msg":"trace[992367988] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"150.525178ms","start":"2026-10-13T12:40:52.705911Z","end":"2026-10-13T12:40:52.856436Z","steps":["trace[992367988] 'process raft request' (duration: 123.691933ms)","trace[992367988] 'compare' (duration: 26.263018ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:40:52.856476Z","caller":"traceutil/trace.go:171","msg":"trace[1754892668] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4927; }","duration":"144.342428ms","start":"2026-10-13T12:40:52.712111Z","end":"2026-10-13T12:40:52.856454Z","steps":["trace[1754892668] 'agreement among raft nodes before linearized reading' (duration: 144.020124ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:40:52.929128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.663101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-13T12:40:52.929127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.652087ms","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-10-13T12:40:52.929279Z","caller":"traceutil/trace.go:171","msg":"trace[878236030] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4928; }","duration":"116.918365ms","start":"2026-10-13T12:40:52.81233Z","end":"2026-10-13T12:40:52.929249Z","steps":["trace[878236030] 'agreement among raft nodes before linearized reading' (duration: 116.483049ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:40:52.929291Z","caller":"traceutil/trace.go:171","msg":"trace[710185308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4928; }","duration":"111.887951ms","start":"2026-10-13T12:40:52.817368Z","end":"2026-10-13T12:40:52.929256Z","steps":["trace[710185308] 'agreement among raft nodes before linearized reading' (duration: 111.397594ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:40:53.034121Z","caller":"traceutil/trace.go:171","msg":"trace[1527237422] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"171.264144ms","start":"2026-10-13T12:40:52.862821Z","end":"2026-10-13T12:40:53.034086Z","steps":["trace[1527237422] 'process raft request' (duration: 96.875356ms)","trace[1527237422] 'compare' (duration: 73.708149ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:40:53.034741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.90059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T12:40:53.034921Z","caller":"traceutil/trace.go:171","msg":"trace[954912264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4931; }","duration":"143.103661ms","start":"2026-10-13T12:40:52.891738Z","end":"2026-10-13T12:40:53.034841Z","steps":["trace[954912264] 'agreement among raft nodes before linearized reading' (duration: 142.91035ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:40:53.034387Z","caller":"traceutil/trace.go:171","msg":"trace[1274198330] linearizableReadLoop","detail":"{readStateIndex:5119; appliedIndex:5118; }","duration":"105.639071ms","start":"2026-10-13T12:40:52.928712Z","end":"2026-10-13T12:40:53.034351Z","steps":["trace[1274198330] 'read index received' (duration: 30.99466ms)","trace[1274198330] 'applied index is now lower than readState.Index' (duration: 74.642061ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:40:53.251561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.546327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-13T12:40:53.251676Z","caller":"traceutil/trace.go:171","msg":"trace[1723294219] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4931; }","duration":"115.848111ms","start":"2026-10-13T12:40:53.135802Z","end":"2026-10-13T12:40:53.25165Z","steps":["trace[1723294219] 'range keys from in-memory index tree' (duration: 115.188392ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:41:06.747694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.488603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-10-13T12:41:06.747855Z","caller":"traceutil/trace.go:171","msg":"trace[420376676] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5103; }","duration":"199.698965ms","start":"2026-10-13T12:41:06.548128Z","end":"2026-10-13T12:41:06.747827Z","steps":["trace[420376676] 'range keys from in-memory index tree' (duration: 199.29369ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:41:06.747923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.264982ms","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-10-13T12:41:06.748066Z","caller":"traceutil/trace.go:171","msg":"trace[1240714297] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5103; }","duration":"171.477374ms","start":"2026-10-13T12:41:06.576563Z","end":"2026-10-13T12:41:06.74804Z","steps":["trace[1240714297] 'range keys from in-memory index tree' (duration: 171.015628ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:41:39.675976Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2832} {"level":"info","ts":"2026-10-13T12:41:39.758878Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2832,"took":"81.353227ms","hash":1902558561,"current-db-size-bytes":51535872,"current-db-size":"52 MB","current-db-size-in-use-bytes":27525120,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-13T12:41:39.758943Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1902558561,"revision":2832,"compact-revision":-1} {"level":"warn","ts":"2026-10-13T12:45:16.701743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.711094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T12:45:16.701924Z","caller":"traceutil/trace.go:171","msg":"trace[95963264] transaction","detail":"{read_only:false; response_revision:6917; number_of_response:1; }","duration":"243.538312ms","start":"2026-10-13T12:45:16.45834Z","end":"2026-10-13T12:45:16.701878Z","steps":["trace[95963264] 'process raft request' (duration: 83.167004ms)","trace[95963264] 'compare' (duration: 159.417488ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:45:16.702367Z","caller":"traceutil/trace.go:171","msg":"trace[1834978849] linearizableReadLoop","detail":"{readStateIndex:7174; appliedIndex:7173; }","duration":"206.619943ms","start":"2026-10-13T12:45:16.49573Z","end":"2026-10-13T12:45:16.70235Z","steps":["trace[1834978849] 'read index received' (duration: 45.870898ms)","trace[1834978849] 'applied index is now lower than readState.Index' (duration: 160.747925ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:45:16.702492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.295202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-13T12:45:16.702543Z","caller":"traceutil/trace.go:171","msg":"trace[1720510200] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:6919; }","duration":"224.383394ms","start":"2026-10-13T12:45:16.478147Z","end":"2026-10-13T12:45:16.70253Z","steps":["trace[1720510200] 'agreement among raft nodes before linearized reading' (duration: 224.274692ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:45:16.702734Z","caller":"traceutil/trace.go:171","msg":"trace[500706765] transaction","detail":"{read_only:false; response_revision:6918; number_of_response:1; }","duration":"202.841988ms","start":"2026-10-13T12:45:16.499871Z","end":"2026-10-13T12:45:16.702713Z","steps":["trace[500706765] 'process raft request' (duration: 202.055022ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:46:39.68184Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5318} {"level":"info","ts":"2026-10-13T12:46:39.770266Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5318,"took":"86.992233ms","hash":7358688,"current-db-size-bytes":51535872,"current-db-size":"52 MB","current-db-size-in-use-bytes":18427904,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-10-13T12:46:39.770396Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":7358688,"revision":5318,"compact-revision":2832}